New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

"kernel: [ 252.424285] BUG: unable to handle kernel NULL pointer dereference at 0000000000000098" #284

Open
timabell opened this Issue Mar 25, 2018 · 1 comment

Comments

Projects
None yet
2 participants
@timabell

timabell commented Mar 25, 2018

This image seems to reliably crash my kernel, sometimes taking the whole machine down with it.

As per https://docs.microsoft.com/en-us/sql/linux/quickstart-install-connect-docker I ran it as follows:

docker pull microsoft/mssql-server-linux:2017-latest
docker run -e 'ACCEPT_EULA=Y' -e 'MSSQL_SA_PASSWORD=redacted' -p 1433:1433 --name sql2    -d microsoft/mssql-server-linux:2017-latest

syslog extract

Mar 26 00:24:03 max kernel: [  252.424285] BUG: unable to handle kernel NULL pointer dereference at 0000000000000098
Mar 26 00:24:03 max kernel: [  252.424338] IP: au_write_pre+0x8b/0x110 [aufs]
Mar 26 00:24:03 max kernel: [  252.424360] PGD 3e8c57067 
Mar 26 00:24:03 max kernel: [  252.424361] PUD 0 
Mar 26 00:24:03 max kernel: [  252.424374] 
Mar 26 00:24:03 max kernel: [  252.424394] Oops: 0000 [#1] SMP
Mar 26 00:24:03 max kernel: [  252.424410] Modules linked in: veth rfcomm nf_conntrack_netbios_ns nf_conntrack_broadcast xt_nat ipt_MASQUERADE nf_nat_masquerade_ipv4 xfrm_user xfrm_algo xt_addrtype br_netfilter bridge stp llc aufs pci_stub vboxpci(OE) vboxnetadp(OE) vboxnetflt(OE) vboxdrv(OE) xt_tcpudp ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 ipt_REJECT nf_reject_ipv4 xt_conntrack ip6table_mangle ip6table_nat nf_conntrack_ipv6 nf_defrag_ipv6 nf_nat_ipv6 ip6table_raw ip6table_security ip6table_filter ip6_tables iptable_mangle iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack libcrc32c iptable_raw iptable_security iptable_filter ip_tables x_tables bnep cdc_ether usbnet binfmt_misc nls_iso8859_1 r8152 mii i2c_designware_platform i2c_designware_core snd_hda_codec_hdmi hid_multitouch dell_led uvcvideo videobuf2_vmalloc
Mar 26 00:24:03 max kernel: [  252.424712]  videobuf2_memops videobuf2_v4l2 videobuf2_core videodev snd_usb_audio media snd_usbmidi_lib btusb btrtl dell_wmi snd_hda_codec_realtek snd_soc_skl snd_hda_codec_generic snd_soc_skl_ipc snd_soc_sst_ipc snd_soc_sst_dsp snd_hda_ext_core snd_soc_sst_match snd_soc_core snd_compress ac97_bus snd_pcm_dmaengine dell_laptop dell_smbios snd_hda_intel snd_hda_codec dcdbas snd_hda_core snd_hwdep arc4 snd_pcm intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm iwlmvm irqbypass intel_cstate mac80211 intel_rapl_perf snd_seq_midi snd_seq_midi_event joydev input_leds snd_rawmidi serio_raw snd_seq snd_seq_device iwlwifi cfg80211 snd_timer snd soundcore idma64 shpchp virt_dma mei_me mei intel_lpss_pci hci_uart processor_thermal_device intel_soc_dts_iosf intel_pch_thermal btbcm btqca
Mar 26 00:24:03 max kernel: [  252.425012]  btintel bluetooth int3403_thermal int340x_thermal_zone intel_lpss_acpi intel_lpss mac_hid intel_hid sparse_keymap int3400_thermal acpi_thermal_rel acpi_pad acpi_als kfifo_buf industrialio parport_pc ppdev lp parport autofs4 btrfs xor raid6_pq algif_skcipher af_alg dm_crypt hid_logitech_hidpp hid_logitech_dj dm_mirror dm_region_hash dm_log hid_generic usbhid crct10dif_pclmul crc32_pclmul ghash_clmulni_intel pcbc i915 aesni_intel aes_x86_64 crypto_simd glue_helper cryptd i2c_algo_bit drm_kms_helper psmouse syscopyarea sysfillrect sysimgblt nvme fb_sys_fops nvme_core drm ahci libahci wmi i2c_hid hid pinctrl_sunrisepoint video pinctrl_intel fjes
Mar 26 00:24:03 max kernel: [  252.425262] CPU: 2 PID: 4528 Comm: sqlservr Tainted: G           OE   4.10.0-38-generic #42~16.04.1-Ubuntu
Mar 26 00:24:03 max kernel: [  252.425303] Hardware name: Dell Inc. XPS 13 9350/0VM5NC, BIOS 1.6.1 12/14/2017
Mar 26 00:24:03 max kernel: [  252.425333] task: ffff9b0127218000 task.stack: ffffbeed848ec000
Mar 26 00:24:03 max kernel: [  252.425367] RIP: 0010:au_write_pre+0x8b/0x110 [aufs]
Mar 26 00:24:03 max kernel: [  252.425389] RSP: 0018:ffffbeed848efc78 EFLAGS: 00010286
Mar 26 00:24:03 max kernel: [  252.425412] RAX: 0000000000000000 RBX: ffff9b0127aaba00 RCX: 0000000000000000
Mar 26 00:24:03 max kernel: [  252.425442] RDX: 0000000100000000 RSI: 0000000000000000 RDI: ffff9b013a3bbe08
Mar 26 00:24:03 max kernel: [  252.425472] RBP: ffffbeed848efcf0 R08: ffff9b012875aa00 R09: ffff9b013a3c0058
Mar 26 00:24:03 max kernel: [  252.425502] R10: 0000000505c58000 R11: 00007ffffffff000 R12: ffffbeed848efd00
Mar 26 00:24:03 max kernel: [  252.425530] R13: 0000000000000001 R14: ffff9b01309f5000 R15: ffff9b0127aab800
Mar 26 00:24:03 max kernel: [  252.425558] FS:  00007fb7bced6700(0000) GS:ffff9b01bed00000(0000) knlGS:0000000509040000
Mar 26 00:24:03 max kernel: [  252.425589] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 26 00:24:03 max kernel: [  252.425614] CR2: 0000000000000098 CR3: 0000000465c27000 CR4: 00000000003406e0
Mar 26 00:24:03 max kernel: [  252.425644] Call Trace:
Mar 26 00:24:03 max kernel: [  252.425665]  aufs_write_iter+0x4c/0x170 [aufs]
Mar 26 00:24:03 max kernel: [  252.425688]  ? security_file_permission+0x3b/0xc0
Mar 26 00:24:03 max kernel: [  252.425710]  aio_write+0xf4/0x150
Mar 26 00:24:03 max kernel: [  252.425728]  ? futex_wake+0x90/0x170
Mar 26 00:24:03 max kernel: [  252.425745]  ? __hrtimer_init+0x90/0x90
Mar 26 00:24:03 max kernel: [  252.425765]  ? __check_object_size+0x108/0x1e3
Mar 26 00:24:03 max kernel: [  252.425786]  ? kmem_cache_alloc+0xd7/0x1b0
Mar 26 00:24:03 max kernel: [  252.425814]  ? do_io_submit+0x192/0x600
Mar 26 00:24:03 max kernel: [  252.425832]  do_io_submit+0x2b4/0x600
Mar 26 00:24:03 max kernel: [  252.425850]  SyS_io_submit+0x10/0x20
Mar 26 00:24:03 max kernel: [  252.425868]  do_syscall_64+0x5b/0xc0
Mar 26 00:24:03 max kernel: [  252.425891]  entry_SYSCALL64_slow_path+0x25/0x25
Mar 26 00:24:03 max kernel: [  252.425912] RIP: 0033:0x7fb7c472d4d9
Mar 26 00:24:03 max kernel: [  252.425929] RSP: 002b:00007fb7bced23d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000d1
Mar 26 00:24:03 max kernel: [  252.425961] RAX: ffffffffffffffda RBX: 00007fb7bced2620 RCX: 00007fb7c472d4d9
Mar 26 00:24:03 max kernel: [  252.425991] RDX: 00007fb7bced2520 RSI: 0000000000000001 RDI: 00007fb7c348c000
Mar 26 00:24:03 max kernel: [  252.426022] RBP: 00007fb7bced2550 R08: 0000000000000001 R09: 000000052480de60
Mar 26 00:24:03 max kernel: [  252.426052] R10: 0000000505c56000 R11: 0000000000000246 R12: 00007fb7bd07a918
Mar 26 00:24:03 max kernel: [  252.426080] R13: 0000000183ac51f9 R14: 00007fb7c348c000 R15: 00007fb7bced2620
Mar 26 00:24:03 max kernel: [  252.426111] Code: 74 10 49 8b 87 c8 00 00 00 0f b6 40 30 41 88 44 24 08 49 8b 87 c8 00 00 00 48 8b 58 38 f0 48 ff 43 38 4d 85 e4 74 0f 48 8b 43 20 <48> 8b 80 98 00 00 00 49 89 04 24 45 85 ed 75 63 31 f6 4c 89 f7 
Mar 26 00:24:03 max kernel: [  252.426219] RIP: au_write_pre+0x8b/0x110 [aufs] RSP: ffffbeed848efc78
Mar 26 00:24:03 max kernel: [  252.426246] CR2: 0000000000000098
Mar 26 00:24:03 max kernel: [  252.447523] ---[ end trace 0fd33906c5bf62ef ]---
Mar 26 00:24:03 max kernel: [  252.447526] ------------[ cut here ]------------
Mar 26 00:24:03 max kernel: [  252.447531] WARNING: CPU: 2 PID: 4528 at /build/linux-hwe-lyR8gz/linux-hwe-4.10.0/kernel/exit.c:746 do_exit+0x62/0xb40
Mar 26 00:24:03 max kernel: [  252.447532] Modules linked in: veth rfcomm nf_conntrack_netbios_ns nf_conntrack_broadcast xt_nat ipt_MASQUERADE nf_nat_masquerade_ipv4 xfrm_user xfrm_algo xt_addrtype br_netfilter bridge stp llc aufs pci_stub vboxpci(OE) vboxnetadp(OE) vboxnetflt(OE) vboxdrv(OE) xt_tcpudp ip6t_rpfilter ip6t_REJECT nf_reject_ipv6 ipt_REJECT nf_reject_ipv4 xt_conntrack ip6table_mangle ip6table_nat nf_conntrack_ipv6 nf_defrag_ipv6 nf_nat_ipv6 ip6table_raw ip6table_security ip6table_filter ip6_tables iptable_mangle iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack libcrc32c iptable_raw iptable_security iptable_filter ip_tables x_tables bnep cdc_ether usbnet binfmt_misc nls_iso8859_1 r8152 mii i2c_designware_platform i2c_designware_core snd_hda_codec_hdmi hid_multitouch dell_led uvcvideo videobuf2_vmalloc
Mar 26 00:24:03 max kernel: [  252.447558]  videobuf2_memops videobuf2_v4l2 videobuf2_core videodev snd_usb_audio media snd_usbmidi_lib btusb btrtl dell_wmi snd_hda_codec_realtek snd_soc_skl snd_hda_codec_generic snd_soc_skl_ipc snd_soc_sst_ipc snd_soc_sst_dsp snd_hda_ext_core snd_soc_sst_match snd_soc_core snd_compress ac97_bus snd_pcm_dmaengine dell_laptop dell_smbios snd_hda_intel snd_hda_codec dcdbas snd_hda_core snd_hwdep arc4 snd_pcm intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm iwlmvm irqbypass intel_cstate mac80211 intel_rapl_perf snd_seq_midi snd_seq_midi_event joydev input_leds snd_rawmidi serio_raw snd_seq snd_seq_device iwlwifi cfg80211 snd_timer snd soundcore idma64 shpchp virt_dma mei_me mei intel_lpss_pci hci_uart processor_thermal_device intel_soc_dts_iosf intel_pch_thermal btbcm btqca
Mar 26 00:24:03 max kernel: [  252.447584]  btintel bluetooth int3403_thermal int340x_thermal_zone intel_lpss_acpi intel_lpss mac_hid intel_hid sparse_keymap int3400_thermal acpi_thermal_rel acpi_pad acpi_als kfifo_buf industrialio parport_pc ppdev lp parport autofs4 btrfs xor raid6_pq algif_skcipher af_alg dm_crypt hid_logitech_hidpp hid_logitech_dj dm_mirror dm_region_hash dm_log hid_generic usbhid crct10dif_pclmul crc32_pclmul ghash_clmulni_intel pcbc i915 aesni_intel aes_x86_64 crypto_simd glue_helper cryptd i2c_algo_bit drm_kms_helper psmouse syscopyarea sysfillrect sysimgblt nvme fb_sys_fops nvme_core drm ahci libahci wmi i2c_hid hid pinctrl_sunrisepoint video pinctrl_intel fjes
Mar 26 00:24:03 max kernel: [  252.447611] CPU: 2 PID: 4528 Comm: sqlservr Tainted: G      D    OE   4.10.0-38-generic #42~16.04.1-Ubuntu
Mar 26 00:24:03 max kernel: [  252.447612] Hardware name: Dell Inc. XPS 13 9350/0VM5NC, BIOS 1.6.1 12/14/2017
Mar 26 00:24:03 max kernel: [  252.447614] Call Trace:
Mar 26 00:24:03 max kernel: [  252.447623]  dump_stack+0x63/0x90
Mar 26 00:24:03 max kernel: [  252.447625]  __warn+0xcb/0xf0
Mar 26 00:24:03 max kernel: [  252.447626]  warn_slowpath_null+0x1d/0x20
Mar 26 00:24:03 max kernel: [  252.447627]  do_exit+0x62/0xb40
Mar 26 00:24:03 max kernel: [  252.447630]  rewind_stack_do_exit+0x17/0x20
Mar 26 00:24:03 max kernel: [  252.447631] RIP: 0033:0x7fb7c472d4d9
Mar 26 00:24:03 max kernel: [  252.447632] RSP: 002b:00007fb7bced23d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000d1
Mar 26 00:24:03 max kernel: [  252.447634] RAX: ffffffffffffffda RBX: 00007fb7bced2620 RCX: 00007fb7c472d4d9
Mar 26 00:24:03 max kernel: [  252.447635] RDX: 00007fb7bced2520 RSI: 0000000000000001 RDI: 00007fb7c348c000
Mar 26 00:24:03 max kernel: [  252.447636] RBP: 00007fb7bced2550 R08: 0000000000000001 R09: 000000052480de60
Mar 26 00:24:03 max kernel: [  252.447637] R10: 0000000505c56000 R11: 0000000000000246 R12: 00007fb7bd07a918
Mar 26 00:24:03 max kernel: [  252.447637] R13: 0000000183ac51f9 R14: 00007fb7c348c000 R15: 00007fb7bced2620
Mar 26 00:24:03 max kernel: [  252.447639] ---[ end trace 0fd33906c5bf62f0 ]---

docker logs

docker logs sql2
2018-03-25 23:24:02.62 Server      Setup step is copying system data file 'C:\templatedata\master.mdf' to '/var/opt/mssql/data/master.mdf'.
2018-03-25 23:24:02.67 Server      Did not find an existing master data file /var/opt/mssql/data/master.mdf, copying the missing default master and other system database files. If you have moved the database location, but not moved the database files, startup may fail. To repair: shutdown SQL Server, move the master database to configured location, and restart.
2018-03-25 23:24:02.67 Server      Setup step is copying system data file 'C:\templatedata\mastlog.ldf' to '/var/opt/mssql/data/mastlog.ldf'.
2018-03-25 23:24:02.68 Server      Setup step is copying system data file 'C:\templatedata\model.mdf' to '/var/opt/mssql/data/model.mdf'.
2018-03-25 23:24:02.69 Server      Setup step is copying system data file 'C:\templatedata\modellog.ldf' to '/var/opt/mssql/data/modellog.ldf'.
2018-03-25 23:24:02.70 Server      Setup step is copying system data file 'C:\templatedata\msdbdata.mdf' to '/var/opt/mssql/data/msdbdata.mdf'.
2018-03-25 23:24:02.71 Server      Setup step is copying system data file 'C:\templatedata\msdblog.ldf' to '/var/opt/mssql/data/msdblog.ldf'.
2018-03-25 23:24:02.79 Server      Microsoft SQL Server 2017 (RTM-CU5) (KB4092643) - 14.0.3023.8 (X64) 
        Mar  2 2018 18:24:44 
        Copyright (C) 2017 Microsoft Corporation
        Developer Edition (64-bit) on Linux (Ubuntu 16.04.4 LTS)
2018-03-25 23:24:02.79 Server      UTC adjustment: 0:00
2018-03-25 23:24:02.79 Server      (c) Microsoft Corporation.
2018-03-25 23:24:02.79 Server      All rights reserved.
2018-03-25 23:24:02.79 Server      Server process ID is 4124.
2018-03-25 23:24:02.79 Server      Logging SQL Server messages in file '/var/opt/mssql/log/errorlog'.
2018-03-25 23:24:02.79 Server      Registry startup parameters: 
         -d /var/opt/mssql/data/master.mdf
         -l /var/opt/mssql/data/mastlog.ldf
         -e /var/opt/mssql/log/errorlog
2018-03-25 23:24:02.80 Server      SQL Server detected 1 sockets with 2 cores per socket and 4 logical processors per socket, 4 total logical processors; using 4 logical processors based on SQL Server licensing. This is an informational message; no user action is required.
2018-03-25 23:24:02.80 Server      SQL Server is starting at normal priority base (=7). This is an informational message only. No user action is required.
2018-03-25 23:24:02.80 Server      Detected 12729 MB of RAM. This is an informational message; no user action is required.
2018-03-25 23:24:02.80 Server      Using conventional memory in the memory manager.
2018-03-25 23:24:02.80 Server      Large Page Allocated: 32MB 
2018-03-25 23:24:03.01 Server      Buffer pool extension is already disabled. No action is necessary. 
2018-03-25 23:24:03.10 Server      InitializeExternalUserGroupSid failed. Implied authentication will be disabled.
2018-03-25 23:24:03.11 Server      Implied authentication manager initialization failed. Implied authentication will be disabled.
2018-03-25 23:24:03.11 Server      Successfully initialized the TLS configuration. Allowed TLS protocol versions are ['1.0 1.1 1.2']. Allowed TLS ciphers are ['ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:!DHE-RSA-AES256-GCM-SHA384:!DHE-RSA-AES128-GCM-SHA256:!DHE-RSA-AES256-SHA:!DHE-RSA-AES128-SHA'].
2018-03-25 23:24:03.13 Server      The maximum number of dedicated administrator connections for this instance is '1'
2018-03-25 23:24:03.13 Server      Node configuration: node 0: CPU mask: 0x000000000000000f:0 Active CPU mask: 0x000000000000000f:0. This message provides a description of the NUMA configuration for this computer. This is an informational message only. No user action is required.
2018-03-25 23:24:03.14 Server      Using dynamic lock allocation.  Initial allocation of 2500 Lock blocks and 5000 Lock Owner blocks per node.  This is an informational message only.  No user action is required.
2018-03-25 23:24:03.15 Server      In-Memory OLTP initialized on lowend machine.
2018-03-25 23:24:03.19 Server      Database Instant File Initialization: enabled. For security and performance considerations see the topic 'Database Instant File Initialization' in SQL Server Books Online. This is an informational message only. No user action is required.
2018-03-25 23:24:03.20 Server      Query Store settings initialized with enabled = 1, 
2018-03-25 23:24:03.20 spid4s      Starting up database 'master'.
2018-03-25 23:24:03.21 Server      Software Usage Metrics is disabled.
2018-03-25 23:24:03.39 spid4s      The tail of the log for database master is being rewritten to match the new sector size of 4096 bytes.  3072 bytes at offset 418816 in file /var/opt/mssql/data/mastlog.ldf will be written.
2018-03-25 23:24:03.50 spid4s      Converting database 'master' from version 862 to the current version 869.
2018-03-25 23:24:03.50 spid4s      Database 'master' running the upgrade step from version 862 to version 863.
2018-03-25 23:24:03.50 spid4s      Database 'master' running the upgrade step from version 863 to version 864.
2018-03-25 23:24:03.52 spid4s      Database 'master' running the upgrade step from version 864 to version 865.
2018-03-25 23:24:03.52 spid4s      Database 'master' running the upgrade step from version 865 to version 866.
2018-03-25 23:24:03.52 spid4s      Database 'master' running the upgrade step from version 866 to version 867.
2018-03-25 23:24:03.52 spid4s      Database 'master' running the upgrade step from version 867 to version 868.
2018-03-25 23:24:03.53 spid4s      Database 'master' running the upgrade step from version 868 to version 869.
2018-03-25 23:24:03.76 spid4s      Resource governor reconfiguration succeeded.
2018-03-25 23:24:03.76 spid4s      SQL Server Audit is starting the audits. This is an informational message. No user action is required.
2018-03-25 23:24:03.76 spid4s      SQL Server Audit has started the audits. This is an informational message. No user action is required.
2018-03-25 23:24:03.80 spid4s      SQL Trace ID 1 was started by login "sa".
2018-03-25 23:24:03.81 spid4s      Server name is '7bd2ed596fa9'. This is an informational message only. No user action is required.
2018-03-25 23:24:03.85 spid4s      Starting up database 'msdb'.
tim@max:~

Running on top of:

$ cat /etc/issue
Linux Mint 18.3 Sylvia \n \l

tim@max:~
$ uname -a
Linux max 4.10.0-38-generic #42~16.04.1-Ubuntu SMP Tue Oct 10 16:32:20 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux
@airways

This comment has been minimized.

airways commented Apr 2, 2018

I am also having this problem. Last night the image was working fine but today both CU5 and CU4 will not start correctly.

Started with:

docker run -e 'ACCEPT_EULA=Y' -e 'SA_PASSWORD=Passw0rd' -p 1433:1433 -d microsoft/mssql-server-linux:2017-CU4

Container log output:

2018-04-02 22:29:10.07 Server      Setup step is copying system data file 'C:\templatedata\master.mdf' to '/var/opt/mssql/data/master.mdf'.
2018-04-02 22:29:10.13 Server      Did not find an existing master data file /var/opt/mssql/data/master.mdf, copying the missing default master and other system database files. If you have moved the database location, but not moved the database files, startup may fail. To repair: shutdown SQL Server, move the master database to configured location, and restart.
2018-04-02 22:29:10.14 Server      Setup step is copying system data file 'C:\templatedata\mastlog.ldf' to '/var/opt/mssql/data/mastlog.ldf'.
2018-04-02 22:29:10.14 Server      Setup step is copying system data file 'C:\templatedata\model.mdf' to '/var/opt/mssql/data/model.mdf'.
2018-04-02 22:29:10.15 Server      Setup step is copying system data file 'C:\templatedata\modellog.ldf' to '/var/opt/mssql/data/modellog.ldf'.
2018-04-02 22:29:10.16 Server      Setup step is copying system data file 'C:\templatedata\msdbdata.mdf' to '/var/opt/mssql/data/msdbdata.mdf'.
2018-04-02 22:29:10.17 Server      Setup step is copying system data file 'C:\templatedata\msdblog.ldf' to '/var/opt/mssql/data/msdblog.ldf'.
2018-04-02 22:29:10.27 Server      Microsoft SQL Server 2017 (RTM-CU4) (KB4056498) - 14.0.3022.28 (X64) 
	Feb  9 2018 19:39:09 
	Copyright (C) 2017 Microsoft Corporation
	Developer Edition (64-bit) on Linux (Ubuntu 16.04.3 LTS)
2018-04-02 22:29:10.28 Server      UTC adjustment: 0:00
2018-04-02 22:29:10.28 Server      (c) Microsoft Corporation.
2018-04-02 22:29:10.28 Server      All rights reserved.
2018-04-02 22:29:10.28 Server      Server process ID is 4124.
2018-04-02 22:29:10.28 Server      Logging SQL Server messages in file '/var/opt/mssql/log/errorlog'.
2018-04-02 22:29:10.28 Server      Registry startup parameters: 
	 -d /var/opt/mssql/data/master.mdf
	 -l /var/opt/mssql/data/mastlog.ldf
	 -e /var/opt/mssql/log/errorlog
2018-04-02 22:29:10.28 Server      SQL Server detected 1 sockets with 2 cores per socket and 4 logical processors per socket, 4 total logical processors; using 4 logical processors based on SQL Server licensing. This is an informational message; no user action is required.
2018-04-02 22:29:10.28 Server      SQL Server is starting at normal priority base (=7). This is an informational message only. No user action is required.
2018-04-02 22:29:10.28 Server      Detected 12763 MB of RAM. This is an informational message; no user action is required.
2018-04-02 22:29:10.28 Server      Using conventional memory in the memory manager.
2018-04-02 22:29:10.29 Server      Large Page Allocated: 32MB 
2018-04-02 22:29:10.55 Server      Buffer pool extension is already disabled. No action is necessary. 
2018-04-02 22:29:10.67 Server      InitializeExternalUserGroupSid failed. Implied authentication will be disabled.
2018-04-02 22:29:10.67 Server      Implied authentication manager initialization failed. Implied authentication will be disabled.
2018-04-02 22:29:10.67 Server      Successfully initialized the TLS configuration. Allowed TLS protocol versions are ['1.0 1.1 1.2']. Allowed TLS ciphers are ['ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:!DHE-RSA-AES256-GCM-SHA384:!DHE-RSA-AES128-GCM-SHA256:!DHE-RSA-AES256-SHA:!DHE-RSA-AES128-SHA'].
2018-04-02 22:29:10.70 Server      The maximum number of dedicated administrator connections for this instance is '1'
2018-04-02 22:29:10.70 Server      Node configuration: node 0: CPU mask: 0x000000000000000f:0 Active CPU mask: 0x000000000000000f:0. This message provides a description of the NUMA configuration for this computer. This is an informational message only. No user action is required.
2018-04-02 22:29:10.71 Server      Using dynamic lock allocation.  Initial allocation of 2500 Lock blocks and 5000 Lock Owner blocks per node.  This is an informational message only.  No user action is required.
2018-04-02 22:29:10.72 Server      In-Memory OLTP initialized on lowend machine.
2018-04-02 22:29:10.77 Server      Database Instant File Initialization: enabled. For security and performance considerations see the topic 'Database Instant File Initialization' in SQL Server Books Online. This is an informational message only. No user action is required.
2018-04-02 22:29:10.77 Server      Query Store settings initialized with enabled = 1, 
2018-04-02 22:29:10.78 spid5s      Starting up database 'master'.
2018-04-02 22:29:10.78 Server      Software Usage Metrics is disabled.
2018-04-02 22:29:11.03 spid5s      The tail of the log for database master is being rewritten to match the new sector size of 4096 bytes.  3072 bytes at offset 418816 in file /var/opt/mssql/data/mastlog.ldf will be written.
2018-04-02 22:29:11.16 spid5s      Converting database 'master' from version 862 to the current version 869.
2018-04-02 22:29:11.16 spid5s      Database 'master' running the upgrade step from version 862 to version 863.
2018-04-02 22:29:11.18 spid5s      Database 'master' running the upgrade step from version 863 to version 864.
2018-04-02 22:29:11.20 spid5s      Database 'master' running the upgrade step from version 864 to version 865.
2018-04-02 22:29:11.20 spid5s      Database 'master' running the upgrade step from version 865 to version 866.
2018-04-02 22:29:11.20 spid5s      Database 'master' running the upgrade step from version 866 to version 867.
2018-04-02 22:29:11.21 spid5s      Database 'master' running the upgrade step from version 867 to version 868.
2018-04-02 22:29:11.21 spid5s      Database 'master' running the upgrade step from version 868 to version 869.

dmesg output from the container:

[ 2494.532525] device vethb7447d5 entered promiscuous mode
[ 2494.532766] IPv6: ADDRCONF(NETDEV_UP): vethb7447d5: link is not ready
[ 2494.718137] eth0: renamed from veth416a47f
[ 2494.741862] IPv6: ADDRCONF(NETDEV_CHANGE): vethb7447d5: link becomes ready
[ 2494.741930] docker0: port 1(vethb7447d5) entered forwarding state
[ 2494.741959] docker0: port 1(vethb7447d5) entered forwarding state
[ 2494.742017] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[ 2498.748046] BUG: unable to handle kernel NULL pointer dereference at 0000000000000098
[ 2498.748093] IP: [<ffffffffc0946a7b>] au_write_pre+0x8b/0x110 [aufs]
[ 2498.748137] PGD 800000038839f067 PUD 0 
[ 2498.748162] Oops: 0000 [#1] SMP 
[ 2498.748181] Modules linked in: xt_nat xt_tcpudp veth ctr ccm nvram msr ftdi_sio ipt_MASQUERADE nf_nat_masquerade_ipv4 nf_conntrack_netlink nfnetlink xfrm_user xfrm_algo iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 xt_addrtype iptable_filter ip_tables xt_conntrack x_tables nf_nat nf_conntrack br_netfilter bridge stp llc aufs pci_stub vboxpci(OE) vboxnetadp(OE) vboxnetflt(OE) hid_multitouch vboxdrv(OE) cdc_mbim cdc_wdm cdc_ncm uvcvideo usbnet videobuf2_vmalloc mii videobuf2_memops qcserial usb_wwan videobuf2_v4l2 videobuf2_core usbserial v4l2_common videodev media binfmt_misc pn544_mei mei_phy pn544 hci nfc dell_wmi sparse_keymap arc4 dell_laptop dcdbas dell_smm_hwmon intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel ath9k kvm ath9k_common ath9k_hw irqbypass ath mac80211 snd_hda_codec_hdmi input_leds joydev snd_soc_rt5640 serio_raw snd_hda_codec_realtek snd_hda_codec_generic cfg80211 snd_soc_rl6231 snd_hda_intel lpc_ich snd_hda_codec snd_soc_core snd_hda_core mei_me mei snd_hwdep shpchp snd_compress ac97_bus snd_pcm_dmaengine snd_pcm snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq snd_seq_device 8250_fintek snd_timer snd elan_i2c soundcore dw_dmac dw_dmac_core snd_soc_sst_acpi dell_rbtn mac_hid 8250_dw spi_pxa2xx_platform i2c_designware_platform i2c_designware_core parport_pc ppdev lp parport autofs4 drbg ansi_cprng algif_skcipher af_alg dm_crypt hid_logitech_hidpp hid_logitech_dj hid_generic usbhid crct10dif_pclmul crc32_pclmul ghash_clmulni_intel i915 aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd i2c_algo_bit drm_kms_helper psmouse syscopyarea sysfillrect ahci sysimgblt libahci fb_sys_fops drm e1000e sdhci_pci ptp pps_core wmi video sdhci_acpi i2c_hid sdhci hid fjes
[ 2498.748890] CPU: 3 PID: 7169 Comm: sqlservr Tainted: G           OE   4.4.0-116-generic #140-Ubuntu
[ 2498.748918] Hardware name: Dell Inc. Latitude E7240/0414F8, BIOS A09 05/01/2014
[ 2498.748940] task: ffff8800a8c00e00 ti: ffff88034cb14000 task.ti: ffff88034cb14000
[ 2498.748962] RIP: 0010:[<ffffffffc0946a7b>]  [<ffffffffc0946a7b>] au_write_pre+0x8b/0x110 [aufs]
[ 2498.748994] RSP: 0018:ffff88034cb17c78  EFLAGS: 00010286
[ 2498.749010] RAX: 0000000000000000 RBX: ffff88040ac8fe00 RCX: 0000000000000000
[ 2498.749031] RDX: 0000000100000000 RSI: 0000000000000000 RDI: ffff8803d4c2ef88
[ 2498.749053] RBP: ffff88034cb17cf0 R08: ffff8803acaead00 R09: ffff8803a89e8958
[ 2498.749073] R10: 0000000894068000 R11: 00007ffffffff000 R12: ffff88034cb17d00
[ 2498.749094] R13: 0000000000000001 R14: ffff88038be3e540 R15: ffff88040ac8ef00
[ 2498.749115] FS:  00007f8e05a10700(0000) GS:ffff88041eb80000(0000) knlGS:00000005080e0000
[ 2498.749139] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 2498.749156] CR2: 0000000000000098 CR3: 00000003d56de000 CR4: 0000000000160670
[ 2498.749177] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 2498.749198] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 2498.749218] Stack:
[ 2498.749225]  ffff88038be3e540 0000070300000004 ffff8803a1a6a600 ffff88034d10c000
[ 2498.749250]  0000000000000000 ffff88038be3e6c0 ffff8803a8906c00 ffff8803acaead00
[ 2498.749276]  ffff8800a8c00e00 1a584ff18d549caa ffff8803a89e8958 ffff88040ac8ef00
[ 2498.749301] Call Trace:
[ 2498.749316]  [<ffffffffc09472ec>] aufs_write_iter+0x4c/0x100 [aufs]
[ 2498.749347]  [<ffffffffc09472a0>] ? aufs_splice_write+0x110/0x110 [aufs]
[ 2498.749371]  [<ffffffff81264cba>] aio_run_iocb+0x27a/0x2e0
[ 2498.749390]  [<ffffffff811b8aed>] ? kzfree+0x2d/0x40
[ 2498.749407]  [<ffffffff811f2c7d>] ? kfree+0x13d/0x150
[ 2498.749423]  [<ffffffff81265adb>] ? do_io_submit+0x19b/0x510
[ 2498.749441]  [<ffffffff81265b9f>] do_io_submit+0x25f/0x510
[ 2498.749459]  [<ffffffff81265e60>] SyS_io_submit+0x10/0x20
[ 2498.749478]  [<ffffffff8184efc8>] entry_SYSCALL_64_fastpath+0x1c/0xbb
[ 2498.749497] Code: 74 10 49 8b 87 d0 00 00 00 0f b6 40 30 41 88 44 24 08 49 8b 87 d0 00 00 00 48 8b 58 38 f0 48 ff 43 38 4d 85 e4 74 0f 48 8b 43 20 <48> 8b 80 98 00 00 00 49 89 04 24 45 85 ed 75 63 31 f6 4c 89 f7 
[ 2498.749616] RIP  [<ffffffffc0946a7b>] au_write_pre+0x8b/0x110 [aufs]
[ 2498.749639]  RSP <ffff88034cb17c78>
[ 2498.749650] CR2: 0000000000000098
[ 2498.757320] ---[ end trace e4d588b98c360d55 ]---
[ 2509.776727] docker0: port 1(vethb7447d5) entered forwarding state
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment