Alan Stern wrote on 11/07/17 23:30:
For your future tests, it would help to enable CONFIG_FRAME_POINTER.
If it is worthwhile doing a git-bisect on usb-storage I could do that also.
I agree with Matt; it's quite possible that the real bug is somewhere
else and it just shows up in usb-storage. So if you decide to do a
bisection, don't confine your attention just to that one module.
Especially since usb-storage has had almost no changes in the last six
months.
Alan Stern
Thanks for the advice, I've enabled CONFIG_FRAME_POINTER and managed to
catch 1 more log triggered by issuing a
blkid
command after inserting a USB stick.
The problem is that I can't be certain of triggering the problem when
testing kernels using git-bisect, so my git-bisect efforts are not
guaranteed to get a result.
Arthur.
Jul 12 18:51:00 localhost kernel: [ 1206.802306] NMI watchdog: Watchdog detected hard LOCKUP on cpu 1
Jul 12 18:51:00 localhost kernel: [ 1206.802308] Modules linked in: cmac arc4 ecb md4 nls_utf8 cifs ccm dns_resolver fscache bnep bluetooth hmac drbg ansi_cprng ecdh_generic nfc rfkill cpufreq_userspace cpufreq_conservative snd_hrtimer cpufreq_powersave binfmt_misc fuse snd_emu10k1_synth snd_emux_synth snd_seq_midi_emul snd_seq_virmidi snd_seq_midi_event snd_seq max6650 ib_iser rdma_cm iw_cm ib_cm ib_core configfs iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi parport_pc ppdev lp parport ir_lirc_codec lirc_dev rtl2832_sdr videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev media fc0012 rtl2832 i2c_mux dvb_usb_rtl28xxu dvb_usb_v2 amdkfd dvb_core rc_core edac_mce_amd radeon kvm_amd kvm irqbypass wmi_bmof snd_hda_codec_hdmi snd_emu10k1 snd_hda_intel ttm sg drm_kms_helper snd_hda_codec snd_util_mem snd_hda_core
Jul 12 18:51:00 localhost kernel: [ 1206.802336] snd_ac97_codec snd_rawmidi snd_seq_device snd_hwdep snd_pcm_oss drm snd_mixer_oss ac97_bus snd_pcm pcspkr evdev k10temp i2c_algo_bit serio_raw snd_timer emu10k1_gp snd gameport sp5100_tco soundcore button acpi_cpufreq asus_atk0110 wmi ext4 mbcache crc16 jbd2 crc32c_generic btrfs raid6_pq xor uas usb_storage sr_mod cdrom sd_mod hid_generic usbhid hid ata_generic ohci_pci ahci firewire_ohci libahci pata_atiixp firewire_core crc_itu_t i2c_piix4 ehci_pci ohci_hcd libata ehci_hcd usbcore scsi_mod r8169 mii
Jul 12 18:51:00 localhost kernel: [ 1206.802359] CPU: 1 PID: 142 Comm: usb-storage Not tainted 4.12.0+ #2751
Jul 12 18:51:00 localhost kernel: [ 1206.802359] Hardware name: System manufacturer System Product Name/M3A78 PRO, BIOS 1701 01/27/2011
Jul 12 18:51:00 localhost kernel: [ 1206.802361] task: ffff927c610eef80 task.stack: ffffba9501b78000
Jul 12 18:51:00 localhost kernel: [ 1206.802366] RIP: 0010:native_queued_spin_lock_slowpath+0x159/0x1c0
Jul 12 18:51:00 localhost kernel: [ 1206.802366] RSP: 0018:ffffba9501b7bdc8 EFLAGS: 00000002
Jul 12 18:51:00 localhost kernel: [ 1206.802368] RAX: 0000000000000101 RBX: ffff927c662f3030 RCX: 0000000000000001
Jul 12 18:51:00 localhost kernel: [ 1206.802368] RDX: 0000000000000101 RSI: 0000000000000001 RDI: ffff927c662f3030
Jul 12 18:51:00 localhost kernel: [ 1206.802369] RBP: ffffba9501b7bde0 R08: 0000000000000101 R09: 0000000000000100
Jul 12 18:51:00 localhost kernel: [ 1206.802370] R10: 0000000000000000 R11: 7fffffffffffffff R12: 0000000000000082
Jul 12 18:51:00 localhost kernel: [ 1206.802370] R13: 0000000000002003 R14: 0000000000001d4c R15: ffff927c610eef80
Jul 12 18:51:00 localhost kernel: [ 1206.802371] FS: 0000000000000000(0000) GS:ffff927c6fc40000(0000) knlGS:0000000000000000
Jul 12 18:51:00 localhost kernel: [ 1206.802372] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Jul 12 18:51:00 localhost kernel: [ 1206.802373] CR2: 00000030007995d8 CR3: 000000017b144000 CR4: 00000000000006e0
Jul 12 18:51:00 localhost kernel: [ 1206.802374] Call Trace:
Jul 12 18:51:00 localhost kernel: [ 1206.802378] ? _raw_spin_lock_irqsave+0x59/0x68
Jul 12 18:51:00 localhost kernel: [ 1206.802389] scsi_eh_scmd_add+0x3f/0x150 [scsi_mod]
Jul 12 18:51:00 localhost kernel: [ 1206.802393] scsi_softirq_done+0xb5/0x150 [scsi_mod]
Jul 12 18:51:00 localhost kernel: [ 1206.802396] __blk_mq_complete_request+0xd4/0x160
Jul 12 18:51:00 localhost kernel: [ 1206.802397] blk_mq_complete_request+0x18/0x20
Jul 12 18:51:00 localhost kernel: [ 1206.802402] scsi_mq_done+0x21/0x80 [scsi_mod]
Jul 12 18:51:00 localhost kernel: [ 1206.802405] usb_stor_control_thread+0xf4/0x250 [usb_storage]
Jul 12 18:51:00 localhost kernel: [ 1206.802407] kthread+0x125/0x140
Jul 12 18:51:00 localhost kernel: [ 1206.802409] ? fill_inquiry_response+0x20/0x20 [usb_storage]
Jul 12 18:51:00 localhost kernel: [ 1206.802410] ? kthread_create_on_node+0x70/0x70
Jul 12 18:51:00 localhost kernel: [ 1206.802412] ret_from_fork+0x25/0x30
Jul 12 18:51:00 localhost kernel: [ 1206.802413] Code: c2 74 a2 89 c2 89 d0 66 31 c0 39 c6 74 ea 4d 85 c9 c6 07 01 74 21 41 c7 41 08 01 00 00 00 eb 85 41 ff c9 75 04 f3 c3 f3 90 8b 07 <84> c0 75 f8 66 c7 07 01 00 c3 f3 90 4c 8b 09 4d 85 c9 74 f6 eb
