[dm-devel] Next-20151009 - crash during boot

Valdis Kletnieks Valdis.Kletnieks at vt.edu
Mon Oct 12 21:50:08 UTC 2015


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 at 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 ]---

-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 848 bytes
Desc: not available
URL: <http://listman.redhat.com/archives/dm-devel/attachments/20151012/ab7d55f1/attachment.sig>


More information about the dm-devel mailing list