Next-20151009 - crash during boot

From: Valdis Kletnieks
Date: Mon Oct 12 2015 - 17:50:23 EST


Next-20151009 crashes early in boot (right after I feed cryptsetup
the passphrase for my root filesystem).

(Yes, the kernel is 10 commits past linux-next - they're all cleanup patches for
drivers/staging that shouldn't enter into it)

It appears to possibly be the same one reported by the "Linux Test Robot",
which called this out:

commit e817ef9b89e5bbfdb2a67ca9896909f414b2af91 ("dm ioctl: use kvfree() in free_params()")

[ 3.091763] systemd[1]: Started Setup Virtual Console.
[ 3.093577] systemd[1]: Started Journal Service.
[ 3.065868] input: HDA NVidia HDMI as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input16
[ 3.092036] audit: type=1130 audit(1444682380.795:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.093873] audit: type=1130 audit(1444682380.797:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.109485] usb 3-1: New USB device found, idVendor=8087, idProduct=0024
[ 3.109489] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 3.109911] hub 3-1:1.0: USB hub found
[ 3.109972] hub 3-1:1.0: 6 ports detected
[ 3.134620] usb 4-1: New USB device found, idVendor=8087, idProduct=0024
[ 3.134625] usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 3.135035] hub 4-1:1.0: USB hub found
[ 3.135094] hub 4-1:1.0: 8 ports detected
[ 3.147376] audit: type=1130 audit(1444682380.851:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.189141] audit: type=1130 audit(1444682380.892:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.202732] audit: type=1130 audit(1444682380.906:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.211218] usb 1-1: New USB device found, idVendor=045e, idProduct=0023
[ 3.211224] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.211228] usb 1-1: Product: Microsoft Trackball Optical®
[ 3.211231] usb 1-1: Manufacturer: Microsoft
[ 3.211660] usb 1-1: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
[ 3.219238] input: Microsoft Microsoft Trackball Optical® as /devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1:1.0/0003:045E:0023.0001/input/input17
[ 3.221834] hid-generic 0003:045E:0023.0001: input,hidraw0: USB HID v1.00 Mouse [Microsoft Microsoft Trackball Optical®] on usb-0000:00:14.0-1/input0
[ 3.295262] tsc: Refined TSC clocksource calibration: 2691.266 MHz
[ 3.295267] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26cb03e1271, max_idle_ns: 440795257954 ns
[ 3.325988] input: PS/2 Generic Mouse as /devices/platform/i8042/serio1/input/input7
[ 3.379168] usb 1-4: new high-speed USB device number 3 using xhci_hcd
[ 3.411161] usb 4-1.7: new high-speed USB device number 3 using ehci-pci
[ 3.496624] usb 4-1.7: New USB device found, idVendor=413c, idProduct=2513
[ 3.496630] usb 4-1.7: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 3.503287] e1000e 0000:00:19.0 eno1: renamed from eth0
[ 3.505607] hub 4-1.7:1.0: USB hub found
[ 3.505748] hub 4-1.7:1.0: 3 ports detected
[ 3.512446] audit: type=1130 audit(1444682381.216:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.514678] udevadm (328) used greatest stack depth: 12584 bytes left
[ 3.544576] usb 1-4: New USB device found, idVendor=413c, idProduct=2134
[ 3.544581] usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.544585] usb 1-4: Product: USB2134
[ 3.544588] usb 1-4: Manufacturer: SMSC
[ 3.565003] hub 1-4:1.0: USB hub found
[ 3.565062] hub 1-4:1.0: 3 ports detected
[ 3.606174] usb 4-1.8: new full-speed USB device number 4 using ehci-pci
[ 3.715922] usb 4-1.8: New USB device found, idVendor=0a5c, idProduct=5801
[ 3.715926] usb 4-1.8: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 3.715928] usb 4-1.8: Product: 5880
[ 3.715930] usb 4-1.8: Manufacturer: Broadcom Corp
[ 3.715931] usb 4-1.8: SerialNumber: 0123456789ABCD
[ 3.716285] usb 4-1.8: config 0 descriptor??
[ 3.817615] systemd-udevd (335) used greatest stack depth: 12568 bytes left
[ 3.849204] usb 1-4.2: new low-speed USB device number 4 using xhci_hcd
[ 3.851516] audit: type=1130 audit(1444682381.555:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=plymouth-start comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 3.940154] usb 1-4.2: New USB device found, idVendor=0e8f, idProduct=0020
[ 3.940201] usb 1-4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.940205] usb 1-4.2: Product: PS2toUSB Adapter
[ 3.940208] usb 1-4.2: Manufacturer: GASIA
[ 3.944917] input: GASIA PS2toUSB Adapter as /devices/pci0000:00/0000:00:14.0/usb1/1-4/1-4.2/1-4.2:1.0/0003:0E8F:0020.0002/input/input18
[ 3.997233] hid-generic 0003:0E8F:0020.0002: input,hidraw1: USB HID v1.10 Keyboard [GASIA PS2toUSB Adapter] on usb-0000:00:14.0-4.2/input0
[ 4.003490] input: GASIA PS2toUSB Adapter as /devices/pci0000:00/0000:00:14.0/usb1/1-4/1-4.2/1-4.2:1.1/0003:0E8F:0020.0003/input/input19
[ 4.055761] hid-generic 0003:0E8F:0020.0003: input,hidraw2: USB HID v1.10 Mouse [GASIA PS2toUSB Adapter] on usb-0000:00:14.0-4.2/input1
[ 4.296246] clocksource: Switched to clocksource tsc
[ 7.812523] random: nonblocking pool is initialized
[ 16.510093] BUG: sleeping function called from invalid context at arch/x86/mm/fault.c:1191
[ 16.510144] in_atomic(): 0, irqs_disabled(): 1, pid: 389, name: systemd-cryptse
[ 16.510183] 1 lock held by systemd-cryptse/389:
[ 16.510185] #0: (&mm->mmap_sem){++++++}, at: [<ffffffff97048d01>] __do_page_fault+0x371/0x740
[ 16.510198] irq event stamp: 27424
[ 16.510200] hardirqs last enabled at (27423): [<ffffffff979828b5>] __mutex_unlock_slowpath+0xf5/0x1f0
[ 16.510206] hardirqs last disabled at (27424): [<ffffffff971d5bc2>] kfree+0x152/0x2d0
[ 16.510212] softirqs last enabled at (27418): [<ffffffff97063f3e>] __do_softirq+0x45e/0x5a0
[ 16.510217] softirqs last disabled at (27411): [<ffffffff97064254>] irq_exit+0xd4/0xe0
[ 16.510223] CPU: 3 PID: 389 Comm: systemd-cryptse Tainted: G W 4.3.0-rc4-next-20151009-00010-g6b3cb144089f #109
[ 16.510226] Hardware name: Dell Inc. Latitude E6530/07Y85M, BIOS A17 08/19/2015
[ 16.510229] 0000000000000000 000000006ccfbbff ffff880221033af0 ffffffff973d30f0
[ 16.510235] ffff880221e1c880 ffffffff97ce70de ffff880221033b18 ffffffff9708be85
[ 16.510241] ffff880221e1c880 ffffffff97ce70de 00000000000004a7 ffff880221033b58
[ 16.510247] Call Trace:
[ 16.510252] [<ffffffff973d30f0>] dump_stack+0x50/0xb0
[ 16.510256] [<ffffffff9708be85>] ___might_sleep+0x135/0x1e0
[ 16.510260] [<ffffffff9708bff9>] __might_sleep+0xc9/0x120
[ 16.510264] [<ffffffff97048ff8>] __do_page_fault+0x668/0x740
[ 16.510267] [<ffffffff970490dc>] do_page_fault+0xc/0x10
[ 16.510271] [<ffffffff97988002>] page_fault+0x22/0x30
[ 16.510275] [<ffffffff971d5c25>] ? kfree+0x1b5/0x2d0
[ 16.510278] [<ffffffff971d5c13>] ? kfree+0x1a3/0x2d0
[ 16.510283] [<ffffffff9719eaea>] kvfree+0x2a/0x40
[ 16.510287] [<ffffffff9762306e>] ctl_ioctl+0x42e/0x5e0
[ 16.510293] [<ffffffff97623233>] dm_ctl_ioctl+0x13/0x20
[ 16.510296] [<ffffffff9720574a>] do_vfs_ioctl+0x2da/0x5c0
[ 16.510302] [<ffffffff971245fb>] ? __audit_syscall_entry+0xfb/0x160
[ 16.510305] [<ffffffff97205aa9>] SyS_ioctl+0x79/0x90
[ 16.510309] [<ffffffff97986357>] entry_SYSCALL_64_fastpath+0x12/0x6f
[ 16.510315] BUG: unable to handle kernel NULL pointer dereference at 0000000000000074
[ 16.510362] IP: [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 16.510394] PGD 0
[ 16.510410] Oops: 0000 [#1] PREEMPT SMP
[ 16.510442] Modules linked in:
[ 16.510465] CPU: 3 PID: 389 Comm: systemd-cryptse Tainted: G W 4.3.0-rc4-next-20151009-00010-g6b3cb144089f #109
[ 16.510522] Hardware name: Dell Inc. Latitude E6530/07Y85M, BIOS A17 08/19/2015
[ 16.510560] task: ffff880221e1c880 ti: ffff880221030000 task.ti: ffff880221030000
[ 16.510599] RIP: 0010:[<ffffffff971d5c25>] [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 16.510646] RSP: 0018:ffff880221033c98 EFLAGS: 00010046
[ 16.510677] RAX: ffffea0007738b28 RBX: ffffea0007738b28 RCX: 0000000000000000
[ 16.510716] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff880221033d10
[ 16.510768] RBP: ffff880221033cc0 R08: 0000000000000000 R09: 0000000000000000
[ 16.510830] R10: 0000000000000001 R11: 0000000000000000 R12: ffff880221033d10
[ 16.510893] R13: 0000000000000286 R14: 0000000000000000 R15: 0000000000000000
[ 16.510956] FS: 00007f30b94de880(0000) GS:ffff88022dd80000(0000) knlGS:0000000000000000
[ 16.511040] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 16.511092] CR2: 0000000000000074 CR3: 0000000221e60000 CR4: 00000000001406e0
[ 16.511155] Stack:
[ 16.511178] 0000000000000000 000055ec10061800 0000000000000138 0000000000000131
[ 16.511266] ffff880221033d10 ffff880221033cd0 ffffffff9719eaea ffff880221033e78
[ 16.511353] ffffffff9762306e 0000000000000000 0000000000000001 0000000000000000
[ 16.511442] Call Trace:
[ 16.511474] [<ffffffff9719eaea>] kvfree+0x2a/0x40
[ 16.511521] [<ffffffff9762306e>] ctl_ioctl+0x42e/0x5e0
[ 16.511575] [<ffffffff97623233>] dm_ctl_ioctl+0x13/0x20
[ 16.511625] [<ffffffff9720574a>] do_vfs_ioctl+0x2da/0x5c0
[ 16.511679] [<ffffffff971245fb>] ? __audit_syscall_entry+0xfb/0x160
[ 16.511737] [<ffffffff97205aa9>] SyS_ioctl+0x79/0x90
[ 16.511786] [<ffffffff97986357>] entry_SYSCALL_64_fastpath+0x12/0x6f
[ 16.511844] Code: 20 4d 89 f7 41 83 e7 01 31 d2 44 89 fe 48 c7 c7 58 0e f3 97 e8 4d 9d f7 ff 49 8d 46 ff 4d 85 ff 4c 89 e7 48 0f 44 c3 4c 8b 70 30 <49> 63 76 74 e8 22 71 ee ff 49 8b 1e 65 48 03 1d bf 44 e3 68 8b
[ 16.512356] RIP [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 16.512411] RSP <ffff880221033c98>
[ 16.512444] CR2: 0000000000000074
[ 16.512480] ---[ end trace 883ed7b099d3373a ]---
[ 19.677505] systemd-cryptse (389) used greatest stack depth: 12376 bytes left
[ 19.681406] audit_printk_skb: 3 callbacks suppressed
[ 19.681452] audit: type=1130 audit(1444682397.380:12): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-cryptsetup@luks\x2d665bb147\x2d9e39\x2d4003\x2db3ae\x2d7be925f51a97 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
[ 21.136483] BUG: sleeping function called from invalid context at arch/x86/mm/fault.c:1191
[ 21.136517] in_atomic(): 0, irqs_disabled(): 1, pid: 456, name: systemd-cryptse
[ 21.136541] INFO: lockdep is turned off.
[ 21.137927] irq event stamp: 0
[ 21.139296] hardirqs last enabled at (0): [< (null)>] (null)
[ 21.140683] hardirqs last disabled at (0): [<ffffffff9705bc4f>] copy_process+0x67f/0x20d0
[ 21.142088] softirqs last enabled at (0): [<ffffffff9705bc4f>] copy_process+0x67f/0x20d0
[ 21.143472] softirqs last disabled at (0): [< (null)>] (null)
[ 21.144870] CPU: 0 PID: 456 Comm: systemd-cryptse Tainted: G D W 4.3.0-rc4-next-20151009-00010-g6b3cb144089f #109
[ 21.146291] Hardware name: Dell Inc. Latitude E6530/07Y85M, BIOS A17 08/19/2015
[ 21.147721] 0000000000000000 0000000019806602 ffff88022297baf0 ffffffff973d30f0
[ 21.149163] ffff8800bbcb0c00 ffffffff97ce70de ffff88022297bb18 ffffffff9708be85
[ 21.150610] ffff8800bbcb0c00 ffffffff97ce70de 00000000000004a7 ffff88022297bb58
[ 21.152048] Call Trace:
[ 21.153476] [<ffffffff973d30f0>] dump_stack+0x50/0xb0
[ 21.154912] [<ffffffff9708be85>] ___might_sleep+0x135/0x1e0
[ 21.156357] [<ffffffff9708bff9>] __might_sleep+0xc9/0x120
[ 21.157794] [<ffffffff97048ff8>] __do_page_fault+0x668/0x740
[ 21.159241] [<ffffffff970b99bf>] ? trace_hardirqs_off_caller+0x1f/0xb0
[ 21.160835] [<ffffffff970490dc>] do_page_fault+0xc/0x10
[ 21.162217] [<ffffffff97988002>] page_fault+0x22/0x30
[ 21.163623] [<ffffffff971d5c25>] ? kfree+0x1b5/0x2d0
[ 21.164990] [<ffffffff971d5c13>] ? kfree+0x1a3/0x2d0
[ 21.166354] [<ffffffff9719eaea>] kvfree+0x2a/0x40
[ 21.167697] [<ffffffff9762306e>] ctl_ioctl+0x42e/0x5e0
[ 21.169015] [<ffffffff97623233>] dm_ctl_ioctl+0x13/0x20
[ 21.170330] [<ffffffff9720574a>] do_vfs_ioctl+0x2da/0x5c0
[ 21.171639] [<ffffffff971245fb>] ? __audit_syscall_entry+0xfb/0x160
[ 21.172931] [<ffffffff97205aa9>] SyS_ioctl+0x79/0x90
[ 21.174221] [<ffffffff97986357>] entry_SYSCALL_64_fastpath+0x12/0x6f
[ 21.175543] BUG: unable to handle kernel NULL pointer dereference at 0000000000000074
[ 21.176903] IP: [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 21.178200] PGD 0
[ 21.179496] Oops: 0000 [#2] PREEMPT SMP
[ 21.180773] Modules linked in:
[ 21.182034] CPU: 0 PID: 456 Comm: systemd-cryptse Tainted: G D W 4.3.0-rc4-next-20151009-00010-g6b3cb144089f #109
[ 21.183332] Hardware name: Dell Inc. Latitude E6530/07Y85M, BIOS A17 08/19/2015
[ 21.184633] task: ffff8800bbcb0c00 ti: ffff880222978000 task.ti: ffff880222978000
[ 21.185927] RIP: 0010:[<ffffffff971d5c25>] [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 21.187230] RSP: 0018:ffff88022297bc98 EFLAGS: 00010046
[ 21.188551] RAX: ffffea00077912e8 RBX: ffffea00077912e8 RCX: 0000000000000000
[ 21.189860] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff88022297bd10
[ 21.191168] RBP: ffff88022297bcc0 R08: 0000000000000000 R09: 0000000000000000
[ 21.192490] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88022297bd10
[ 21.193787] R13: 0000000000000286 R14: 0000000000000000 R15: 0000000000000000
[ 21.195077] FS: 00007fa313d43880(0000) GS:ffff88022dc00000(0000) knlGS:0000000000000000
[ 21.196388] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 21.197674] CR2: 0000000000000074 CR3: 00000000bca5c000 CR4: 00000000001406f0
[ 21.198984] Stack:
[ 21.200259] 0000000000000000 0000560844c482a0 0000000000000138 0000000000000131
[ 21.201587] ffff88022297bd10 ffff88022297bcd0 ffffffff9719eaea ffff88022297be78
[ 21.202903] ffffffff9762306e 0000000000000000 0000000000000001 0000000000000000
[ 21.204225] Call Trace:
[ 21.205560] [<ffffffff9719eaea>] kvfree+0x2a/0x40
[ 21.206880] [<ffffffff9762306e>] ctl_ioctl+0x42e/0x5e0
[ 21.208198] [<ffffffff97623233>] dm_ctl_ioctl+0x13/0x20
[ 21.209531] [<ffffffff9720574a>] do_vfs_ioctl+0x2da/0x5c0
[ 21.210798] [<ffffffff971245fb>] ? __audit_syscall_entry+0xfb/0x160
[ 21.212022] [<ffffffff97205aa9>] SyS_ioctl+0x79/0x90
[ 21.213243] [<ffffffff97986357>] entry_SYSCALL_64_fastpath+0x12/0x6f
[ 21.214493] Code: 20 4d 89 f7 41 83 e7 01 31 d2 44 89 fe 48 c7 c7 58 0e f3 97 e8 4d 9d f7 ff 49 8d 46 ff 4d 85 ff 4c 89 e7 48 0f 44 c3 4c 8b 70 30 <49> 63 76 74 e8 22 71 ee ff 49 8b 1e 65 48 03 1d bf 44 e3 68 8b
[ 21.215937] RIP [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 21.217248] RSP <ffff88022297bc98>
[ 21.218566] CR2: 0000000000000074
[ 21.219822] ---[ end trace 883ed7b099d3373b ]---
[ 22.877079] RSP: 0018:ffff880221d47c98 EFLAGS: 00010046
[ 22.878388] RAX: ffffea0007766788 RBX: ffffea0007766788 RCX: 0000000000000000
[ 22.879722] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff880221d47d10
[ 22.881032] RBP: ffff880221d47cc0 R08: 0000000000000000 R09: 0000000000000000
[ 22.882335] R10: 0000000000000000 R11: 0000000000000000 R12: ffff880221d47d10
[ 22.883667] R13: 0000000000000286 R14: 0000000000000000 R15: 0000000000000000
[ 22.884971] FS: 00007fb1402d1880(0000) GS:ffff88022dd80000(0000) knlGS:0000000000000000
[ 22.886285] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 22.887672] CR2: 0000000000000074 CR3: 00000000bc7fe000 CR4: 00000000001406e0
[ 22.888982] Stack:
[ 22.890280] 0000000000000000 000055d9bbacd260 0000000000000138 0000000000000131
[ 22.891623] ffff880221d47d10 ffff880221d47cd0 ffffffff9719eaea ffff880221d47e78
[ 22.892942] ffffffff9762306e 0000000000000000 0000000000000001 0000000000000000
[ 22.894262] Call Trace:
[ 22.895583] [<ffffffff9719eaea>] kvfree+0x2a/0x40
[ 22.896883] [<ffffffff9762306e>] ctl_ioctl+0x42e/0x5e0
[ 22.898184] [<ffffffff97623233>] dm_ctl_ioctl+0x13/0x20
[ 22.899503] [<ffffffff9720574a>] do_vfs_ioctl+0x2da/0x5c0
[ 22.900803] [<ffffffff971245fb>] ? __audit_syscall_entry+0xfb/0x160
[ 22.902108] [<ffffffff97205aa9>] SyS_ioctl+0x79/0x90
[ 22.903409] [<ffffffff97986357>] entry_SYSCALL_64_fastpath+0x12/0x6f
[ 22.904738] Code: 20 4d 89 f7 41 83 e7 01 31 d2 44 89 fe 48 c7 c7 58 0e f3 97 e8 4d 9d f7 ff 49 8d 46 ff 4d 85 ff 4c 89 e7 48 0f 44 c3 4c 8b 70 30 <49> 63 76 74 e8 22 71 ee ff 49 8b 1e 65 48 03 1d bf 44 e3 68 8b
[ 22.906263] RIP [<ffffffff971d5c25>] kfree+0x1b5/0x2d0
[ 22.907636] RSP <ffff880221d47c98>
[ 22.908935] CR2: 0000000000000074
[ 22.910235] ---[ end trace 883ed7b099d3373c ]---

Attachment: pgp2edbAadY7J.pgp
Description: PGP signature