search

RishiGupta12 / SerialPundit

Serial port communication in Java - FTDI D2XX, HID API, X/Y modem
GNU Affero General Public License v3.0
127 stars 56 forks source link

Linux kernel driver problem #35

Open slavkoja opened 6 years ago

slavkoja commented 6 years ago

When i try to remove all devices (by command from README), my kernel stuck, in logs i found:

Mar 18 22:55:59 [44248.147927] BUG: unable to handle kernel paging request at 000000ff0000ffa8
Mar 18 22:55:59 [44248.147942] IP: _raw_spin_lock_irqsave+0x1e/0x40
Mar 18 22:55:59 [44248.147946] PGD 0 P4D 0 
Mar 18 22:55:59 [44248.147952] Oops: 0002 [#1] SMP NOPTI
Mar 18 22:55:59 [44248.147956] Modules linked in: tty2com(O) binfmt_misc reiserfs dm_mod br_netfilter bridge stp llc fuse tty0tty(O) tun loop pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) it87 hwmon_vid joydev usblp cp210x usbserial hid_generic usbhid hid snd_hda_codec_hdmi btrfs zstd_decompress zstd_compress xxhash xor raid6_pq pktcdvd nvidia_drm(PO) nvidia_modeset(PO) evdev edac_mce_amd kvm_amd nvidia(PO) snd_hda_codec_realtek kvm irqbypass crct10dif_pclmul snd_hda_codec_generic crc32_pclmul crc32c_intel ohci_pci ghash_clmulni_intel pcbc snd_hda_intel drm_kms_helper aesni_intel efi_pstore aes_x86_64 snd_hda_codec crypto_simd glue_helper cryptd snd_hda_core drm snd_hwdep ipmi_devintf snd_pcm xhci_pci snd_timer ehci_pci pcspkr ohci_hcd ipmi_msghandler efivars xhci_hcd r8169 snd ehci_hcd fam15h_power k10temp
Mar 18 22:55:59 [44248.148034]  soundcore mii usbcore sp5100_tco usb_common i2c_piix4 shpchp button acpi_cpufreq efivarfs initio BusLogic arcmsr aic7xxx aic79xx scsi_transport_spi sr_mod cdrom sg sd_mod pdc_adma sata_mv ata_piix ahci libahci sata_qstor sata_vsc sata_uli sata_sis pata_sis sata_sx4 sata_nv sata_via sata_svw sata_sil24 sata_sil sata_promise libata scsi_mod ext4 fscrypto jbd2 mbcache crc16
Mar 18 22:55:59 [44248.148080] CPU: 2 PID: 3173 Comm: bash Tainted: P           O    4.14.12-2 #1
Mar 18 22:55:59 [44248.148083] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:55:59 [44248.148087] task: ffff94244ac2a080 task.stack: ffffac4f84c5c000
Mar 18 22:55:59 [44248.148092] RIP: 0010:_raw_spin_lock_irqsave+0x1e/0x40
Mar 18 22:55:59 [44248.148095] RSP: 0018:ffffac4f84c5fdf8 EFLAGS: 00010046
Mar 18 22:55:59 [44248.148099] RAX: 0000000000000000 RBX: 0000000000000206 RCX: 0000000000000000
Mar 18 22:55:59 [44248.148102] RDX: 0000000000000001 RSI: ffff94249d8a5848 RDI: 000000ff0000ffa8
Mar 18 22:55:59 [44248.148105] RBP: 000000ff0000ffa8 R08: 0000000000000000 R09: 000000000000003e
Mar 18 22:55:59 [44248.148108] R10: ffffac4f84c5fcd0 R11: 000000000000003f R12: 0000000000000002
Mar 18 22:55:59 [44248.148111] R13: ffff9423b855f700 R14: ffffffffc16356e0 R15: 000000000000003e
Mar 18 22:55:59 [44248.148115] FS:  00007fbfe889f740(0000) GS:ffff9424aec80000(0000) knlGS:0000000000000000
Mar 18 22:55:59 [44248.148119] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:55:59 [44248.148122] CR2: 000000ff0000ffa8 CR3: 000000010ce4c000 CR4: 00000000000406e0
Mar 18 22:55:59 [44248.148125] Call Trace:
Mar 18 22:55:59 [44248.148140]  tty_port_tty_get+0x19/0x40
Mar 18 22:55:59 [44248.148150]  0xffffffffc1633c91
Mar 18 22:55:59 [44248.148159]  proc_reg_write+0x3d/0x60
Mar 18 22:55:59 [44248.148166]  vfs_write+0xad/0x1a0
Mar 18 22:55:59 [44248.148173]  SyS_write+0x52/0xc0
Mar 18 22:55:59 [44248.148181]  system_call_fast_compare_end+0xc/0x6f
Mar 18 22:55:59 [44248.148185] RIP: 0033:0x7fbfe8177eb8
Mar 18 22:55:59 [44248.148190] RSP: 002b:00007ffd10039f90 EFLAGS: 00000246
Mar 18 22:55:59 [44248.148193] Code: ff 66 90 c3 66 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 53 9c 58 0f 1f 44 00 00 48 89 c3 fa 66 0f 1f 44 00 00 31 c0 ba 01 00 00 00 <f0> 0f b1 17 85 c0 75 05 48 89 d8 5b c3 89 c6 e8 be 03 9a ff 66 
Mar 18 22:55:59 [44248.148282] RIP: _raw_spin_lock_irqsave+0x1e/0x40 RSP: ffffac4f84c5fdf8
Mar 18 22:55:59 [44248.148286] CR2: 000000ff0000ffa8
Mar 18 22:55:59 [44248.148295] ---[ end trace 883a70a9d4231776 ]---
Mar 18 22:55:59 [44269.152493] INFO: rcu_sched detected stalls on CPUs/tasks:
Mar 18 22:55:59 [44269.152510]  2-...: (0 ticks this GP) idle=77a/140000000000000/0 softirq=2551346/2551346 fqs=0 
Mar 18 22:55:59 [44269.152514]  (detected by 0, t=5252 jiffies, g=1896230, c=1896229, q=4195)
Mar 18 22:55:59 [44269.152523] Sending NMI from CPU 0 to CPUs 2:
Mar 18 22:55:59 [44269.152535] NMI backtrace for cpu 2
Mar 18 22:55:59 [44269.152541] CPU: 2 PID: 3173 Comm: bash Tainted: P      D    O    4.14.12-2 #1
Mar 18 22:55:59 [44269.152542] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:55:59 [44269.152544] task: ffff94244ac2a080 task.stack: ffffac4f84c5c000
Mar 18 22:55:59 [44269.152549] RIP: 0010:0xfffffffefa758469
Mar 18 22:55:59 [44269.152550] RSP: 0018:ffffac4f84c5f280 EFLAGS: 00000046
Mar 18 22:55:59 [44269.152553] RAX: fffffffefec10000 RBX: fffffffeffc5b318 RCX: 0000000000ffc5cc
Mar 18 22:55:59 [44269.152555] RDX: fffffffefec10000 RSI: fffffffefa6de2b5 RDI: fffffffeffc5b300
Mar 18 22:55:59 [44269.152556] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000004
Mar 18 22:55:59 [44269.152558] R10: 0000000000000004 R11: ffffac4f84c5f2f0 R12: 0000000000000000
Mar 18 22:55:59 [44269.152559] R13: 0000000000000307 R14: 0000000000000007 R15: 0000000000000000
Mar 18 22:55:59 [44269.152562] FS:  00007fbfe889f740(0000) GS:ffff9424aec80000(0000) knlGS:0000000000000000
Mar 18 22:55:59 [44269.152563] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:55:59 [44269.152565] CR2: 000000ff0000ffa8 CR3: 000000022615e000 CR4: 00000000000406e0
Mar 18 22:55:59 [44269.152566] Call Trace:
Mar 18 22:55:59 [44269.152588]  ? efi_call+0x58/0x90
Mar 18 22:55:59 [44269.152595]  ? virt_efi_set_variable.part.7+0x5e/0x110
Mar 18 22:55:59 [44269.152599]  ? efi_query_variable_store+0x123/0x180
Mar 18 22:55:59 [44269.152603]  ? efivar_entry_set_safe+0x161/0x1d0
Mar 18 22:55:59 [44269.152606]  ? efivar_entry_set_safe+0x161/0x1d0
Mar 18 22:55:59 [44269.152612]  ? efi_pstore_write+0x10a/0x150 [efi_pstore]
Mar 18 22:55:59 [44269.152615]  ? efi_pstore_write+0x10a/0x150 [efi_pstore]
Mar 18 22:55:59 [44269.152622]  ? pstore_dump+0x169/0x330
Mar 18 22:55:59 [44269.152626]  ? vprintk_emit+0x337/0x3a0
Mar 18 22:55:59 [44269.152630]  ? kmsg_dump+0xb3/0xd0
Mar 18 22:55:59 [44269.152635]  ? oops_end+0x6e/0xc0
Mar 18 22:55:59 [44269.152638]  ? no_context+0x180/0x400
Mar 18 22:55:59 [44269.152642]  ? __do_page_fault+0x37b/0x4e0
Mar 18 22:55:59 [44269.152648]  ? page_fault+0x2c/0x60
Mar 18 22:55:59 [44269.152652]  ? _raw_spin_lock_irqsave+0x1e/0x40
Mar 18 22:55:59 [44269.152656]  ? tty_port_tty_get+0x19/0x40
Mar 18 22:55:59 [44269.152659]  ? 0xffffffffc1633c91
Mar 18 22:55:59 [44269.152664]  ? proc_reg_write+0x3d/0x60
Mar 18 22:55:59 [44269.152668]  ? vfs_write+0xad/0x1a0
Mar 18 22:55:59 [44269.152671]  ? SyS_write+0x52/0xc0
Mar 18 22:55:59 [44269.152674]  ? system_call_fast_compare_end+0xc/0x6f
Mar 18 22:55:59 [44269.152676] Code: 73 1a 48 8b d6 4d 8b c1 8a 0a 48 8b 05 39 2e 00 00 48 03 d5 4c 2b c5 88 48 0c 75 ec e8 fd fa ff ff 48 8b 15 22 2e 00 00 8a 4a 02 <48> 8b 05 18 2e 00 00 41 0f b7 d4 0b cd 88 48 02 48 8b 05 08 2e 
Mar 18 22:55:59 [44269.153525] rcu_sched kthread starved for 5252 jiffies! g1896230 c1896229 f0x0 RCU_GP_WAIT_FQS(3) ->state=0x402 ->cpu=2
Mar 18 22:55:59 [44269.153569] rcu_sched       I    0     8      2 0x80000000
Mar 18 22:55:59 [44269.153575] Call Trace:
Mar 18 22:55:59 [44269.153588]  ? __schedule+0x28e/0x880
Mar 18 22:55:59 [44269.153594]  schedule+0x28/0x80
Mar 18 22:55:59 [44269.153600]  schedule_timeout+0x184/0x360
Mar 18 22:55:59 [44269.153607]  ? call_timer_fn+0x120/0x120
Mar 18 22:55:59 [44269.153612]  ? rcu_gp_kthread+0x4f0/0x8d0
Mar 18 22:55:59 [44269.153618]  ? prepare_to_swait_event+0x22/0x40
Mar 18 22:55:59 [44269.153621]  rcu_gp_kthread+0x4f0/0x8d0
Mar 18 22:55:59 [44269.153626]  ? rcu_note_context_switch+0x120/0x120
Mar 18 22:55:59 [44269.153630]  kthread+0x118/0x130
Mar 18 22:55:59 [44269.153635]  ? kthread_create_on_node+0x70/0x70
Mar 18 22:55:59 [44269.153640]  ret_from_fork+0x1f/0x30
Mar 18 22:56:26 [44295.444135] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [panel-11-cpufre:6140]
Mar 18 22:56:26 [44295.444142] Modules linked in: tty2com(O) binfmt_misc reiserfs dm_mod br_netfilter bridge stp llc fuse tty0tty(O) tun loop pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) it87 hwmon_vid joydev usblp cp210x usbserial hid_generic usbhid hid snd_hda_codec_hdmi btrfs zstd_decompress zstd_compress xxhash xor raid6_pq pktcdvd nvidia_drm(PO) nvidia_modeset(PO) evdev edac_mce_amd kvm_amd nvidia(PO) snd_hda_codec_realtek kvm irqbypass crct10dif_pclmul snd_hda_codec_generic crc32_pclmul crc32c_intel ohci_pci ghash_clmulni_intel pcbc snd_hda_intel drm_kms_helper aesni_intel efi_pstore aes_x86_64 snd_hda_codec crypto_simd glue_helper cryptd snd_hda_core drm snd_hwdep ipmi_devintf snd_pcm xhci_pci snd_timer ehci_pci pcspkr ohci_hcd ipmi_msghandler efivars xhci_hcd r8169 snd ehci_hcd fam15h_power k10temp
Mar 18 22:56:26 [44295.444229]  soundcore mii usbcore sp5100_tco usb_common i2c_piix4 shpchp button acpi_cpufreq efivarfs initio BusLogic arcmsr aic7xxx aic79xx scsi_transport_spi sr_mod cdrom sg sd_mod pdc_adma sata_mv ata_piix ahci libahci sata_qstor sata_vsc sata_uli sata_sis pata_sis sata_sx4 sata_nv sata_via sata_svw sata_sil24 sata_sil sata_promise libata scsi_mod ext4 fscrypto jbd2 mbcache crc16
Mar 18 22:56:26 [44295.444276] CPU: 0 PID: 6140 Comm: panel-11-cpufre Tainted: P      D    O    4.14.12-2 #1
Mar 18 22:56:26 [44295.444279] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:56:26 [44295.444283] task: ffff9424a427d080 task.stack: ffffac4f81d54000
Mar 18 22:56:26 [44295.444293] RIP: 0010:smp_call_function_single+0xda/0x100
Mar 18 22:56:26 [44295.444297] RSP: 0018:ffffac4f81d57d80 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
Mar 18 22:56:26 [44295.444301] RAX: 0000000000000000 RBX: ffff94249d393000 RCX: 0000000000000000
Mar 18 22:56:26 [44295.444304] RDX: 0000000000000001 RSI: 00000000000000fb RDI: 0000000000000282
Mar 18 22:56:26 [44295.444307] RBP: ffffac4f81d57dd0 R08: ffff94249d393160 R09: ffff9424544cad80
Mar 18 22:56:26 [44295.444310] R10: ffffac4f81d57e00 R11: ffff9424a427d080 R12: 0000000000000002
Mar 18 22:56:26 [44295.444313] R13: ffff9423c0e88000 R14: 0000000000000001 R15: ffff94245442b880
Mar 18 22:56:26 [44295.444317] FS:  00007f35d8723000(0000) GS:ffff9424aec00000(0000) knlGS:0000000000000000
Mar 18 22:56:26 [44295.444321] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:56:26 [44295.444324] CR2: 00000000018cf078 CR3: 0000000206634000 CR4: 00000000000406f0
Mar 18 22:56:26 [44295.444327] Call Trace:
Mar 18 22:56:26 [44295.444336]  ? x86_match_cpu+0xa0/0xa0
Mar 18 22:56:26 [44295.444342]  ? arch_freq_get_on_cpu+0x38/0x80
Mar 18 22:56:26 [44295.444346]  arch_freq_get_on_cpu+0x38/0x80
Mar 18 22:56:26 [44295.444354]  show_scaling_cur_freq+0x18/0x80
Mar 18 22:56:26 [44295.444359]  show+0x2d/0x50
Mar 18 22:56:26 [44295.444366]  sysfs_kf_seq_show+0xb0/0x110
Mar 18 22:56:26 [44295.444372]  seq_read+0xd2/0x440
Mar 18 22:56:26 [44295.444378]  vfs_read+0x89/0x130
Mar 18 22:56:26 [44295.444383]  SyS_read+0x52/0xc0
Mar 18 22:56:26 [44295.444389]  system_call_fast_compare_end+0xc/0x6f
Mar 18 22:56:26 [44295.444393] RIP: 0033:0x7f35d64da69a
Mar 18 22:56:26 [44295.444396] RSP: 002b:00007fffc37cb040 EFLAGS: 00000246
Mar 18 22:56:26 [44295.444399] Code: 3c 25 28 00 00 00 75 40 48 83 c4 48 41 5a 5d 49 8d 62 f8 c3 48 89 d1 48 89 f2 48 8d 75 b0 e8 7e fe ff ff 8b 55 c8 83 e2 01 74 0a <f3> 90 8b 55 c8 83 e2 01 75 f6 eb c2 8b 05 4c 90 4f 01 85 c0 75 
Mar 18 22:56:53 [44318.339427] NMI watchdog: Watchdog detected hard LOCKUP on cpu 2
Mar 18 22:56:53 [44318.339430] Modules linked in: tty2com(O) binfmt_misc reiserfs dm_mod br_netfilter bridge stp llc fuse tty0tty(O) tun loop pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) it87 hwmon_vid joydev usblp cp210x usbserial hid_generic usbhid hid snd_hda_codec_hdmi btrfs zstd_decompress zstd_compress xxhash xor raid6_pq pktcdvd nvidia_drm(PO) nvidia_modeset(PO) evdev edac_mce_amd kvm_amd nvidia(PO) snd_hda_codec_realtek kvm irqbypass crct10dif_pclmul snd_hda_codec_generic crc32_pclmul crc32c_intel ohci_pci ghash_clmulni_intel pcbc snd_hda_intel drm_kms_helper aesni_intel efi_pstore aes_x86_64 snd_hda_codec crypto_simd glue_helper cryptd snd_hda_core drm snd_hwdep ipmi_devintf snd_pcm xhci_pci snd_timer ehci_pci pcspkr ohci_hcd ipmi_msghandler efivars xhci_hcd r8169 snd ehci_hcd fam15h_power k10temp
Mar 18 22:56:53 [44318.339492]  soundcore mii usbcore sp5100_tco usb_common i2c_piix4 shpchp button acpi_cpufreq efivarfs initio BusLogic arcmsr aic7xxx aic79xx scsi_transport_spi sr_mod cdrom sg sd_mod pdc_adma sata_mv ata_piix ahci libahci sata_qstor sata_vsc sata_uli sata_sis pata_sis sata_sx4 sata_nv sata_via sata_svw sata_sil24 sata_sil sata_promise libata scsi_mod ext4 fscrypto jbd2 mbcache crc16
Mar 18 22:56:53 [44318.339528] CPU: 2 PID: 3173 Comm: bash Tainted: P      D    O L  4.14.12-2 #1
Mar 18 22:56:53 [44318.339530] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:56:53 [44318.339532] task: ffff94244ac2a080 task.stack: ffffac4f84c5c000
Mar 18 22:56:53 [44318.339536] RIP: 0010:0xfffffffefa757f7f
Mar 18 22:56:53 [44318.339537] RSP: 0018:ffffac4f84c5f218 EFLAGS: 00000002
Mar 18 22:56:53 [44318.339539] RAX: fffffffefec10000 RBX: fffffffeffc5e900 RCX: 0000000000ffc550
Mar 18 22:56:53 [44318.339541] RDX: fffffffefec10000 RSI: ffffac4f84c5f3e6 RDI: fffffffeffc5e900
Mar 18 22:56:53 [44318.339542] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000002
Mar 18 22:56:53 [44318.339544] R10: 0000000000000004 R11: ffffac4f84c5f2f0 R12: 0000000000000000
Mar 18 22:56:53 [44318.339545] R13: 0000000000000403 R14: ffffac4f84c5f580 R15: 0000000000000000
Mar 18 22:56:53 [44318.339548] FS:  00007fbfe889f740(0000) GS:ffff9424aec80000(0000) knlGS:0000000000000000
Mar 18 22:56:53 [44318.339549] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:56:53 [44318.339551] CR2: 000000ff0000ffa8 CR3: 000000022615e000 CR4: 00000000000406e0
Mar 18 22:56:53 [44318.339552] Call Trace:
Mar 18 22:56:53 [44318.339574]  ? efi_call+0x58/0x90
Mar 18 22:56:53 [44318.339581]  ? virt_efi_set_variable.part.7+0x5e/0x110
Mar 18 22:56:53 [44318.339585]  ? efi_query_variable_store+0x123/0x180
Mar 18 22:56:53 [44318.339589]  ? efivar_entry_set_safe+0x161/0x1d0
Mar 18 22:56:53 [44318.339591]  ? efivar_entry_set_safe+0x161/0x1d0
Mar 18 22:56:53 [44318.339597]  ? efi_pstore_write+0x10a/0x150 [efi_pstore]
Mar 18 22:56:53 [44318.339600]  ? efi_pstore_write+0x10a/0x150 [efi_pstore]
Mar 18 22:56:53 [44318.339606]  ? pstore_dump+0x169/0x330
Mar 18 22:56:53 [44318.339610]  ? vprintk_emit+0x337/0x3a0
Mar 18 22:56:53 [44318.339614]  ? kmsg_dump+0xb3/0xd0
Mar 18 22:56:53 [44318.339618]  ? oops_end+0x6e/0xc0
Mar 18 22:56:53 [44318.339622]  ? no_context+0x180/0x400
Mar 18 22:56:53 [44318.339626]  ? __do_page_fault+0x37b/0x4e0
Mar 18 22:56:53 [44318.339631]  ? page_fault+0x2c/0x60
Mar 18 22:56:53 [44318.339635]  ? _raw_spin_lock_irqsave+0x1e/0x40
Mar 18 22:56:53 [44318.339640]  ? tty_port_tty_get+0x19/0x40
Mar 18 22:56:53 [44318.339642]  ? 0xffffffffc1633c91
Mar 18 22:56:53 [44318.339647]  ? proc_reg_write+0x3d/0x60
Mar 18 22:56:53 [44318.339651]  ? vfs_write+0xad/0x1a0
Mar 18 22:56:53 [44318.339654]  ? SyS_write+0x52/0xc0
Mar 18 22:56:53 [44318.339657]  ? system_call_fast_compare_end+0xc/0x6f
Mar 18 22:56:53 [44318.339659] Code: 83 c4 20 5f c3 cc cc cc 66 90 48 8b 05 23 33 00 00 8a 48 02 48 8b 05 19 33 00 00 83 c9 10 88 48 02 48 8b 05 0c 33 00 00 8a 48 0d <f6> c1 07 75 da f3 c3 cc cc 8a 0d e0 32 00 00 48 8b 05 f3 32 00 
Mar 18 22:56:54 [44323.443749] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [panel-11-cpufre:6140]
Mar 18 22:56:54 [44323.443756] Modules linked in: tty2com(O) binfmt_misc reiserfs dm_mod br_netfilter bridge stp llc fuse tty0tty(O) tun loop pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) it87 hwmon_vid joydev usblp cp210x usbserial hid_generic usbhid hid snd_hda_codec_hdmi btrfs zstd_decompress zstd_compress xxhash xor raid6_pq pktcdvd nvidia_drm(PO) nvidia_modeset(PO) evdev edac_mce_amd kvm_amd nvidia(PO) snd_hda_codec_realtek kvm irqbypass crct10dif_pclmul snd_hda_codec_generic crc32_pclmul crc32c_intel ohci_pci ghash_clmulni_intel pcbc snd_hda_intel drm_kms_helper aesni_intel efi_pstore aes_x86_64 snd_hda_codec crypto_simd glue_helper cryptd snd_hda_core drm snd_hwdep ipmi_devintf snd_pcm xhci_pci snd_timer ehci_pci pcspkr ohci_hcd ipmi_msghandler efivars xhci_hcd r8169 snd ehci_hcd fam15h_power k10temp
Mar 18 22:56:54 [44323.443842]  soundcore mii usbcore sp5100_tco usb_common i2c_piix4 shpchp button acpi_cpufreq efivarfs initio BusLogic arcmsr aic7xxx aic79xx scsi_transport_spi sr_mod cdrom sg sd_mod pdc_adma sata_mv ata_piix ahci libahci sata_qstor sata_vsc sata_uli sata_sis pata_sis sata_sx4 sata_nv sata_via sata_svw sata_sil24 sata_sil sata_promise libata scsi_mod ext4 fscrypto jbd2 mbcache crc16
Mar 18 22:56:54 [44323.443888] CPU: 0 PID: 6140 Comm: panel-11-cpufre Tainted: P      D    O L  4.14.12-2 #1
Mar 18 22:56:54 [44323.443891] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:56:54 [44323.443896] task: ffff9424a427d080 task.stack: ffffac4f81d54000
Mar 18 22:56:54 [44323.443906] RIP: 0010:smp_call_function_single+0xdc/0x100
Mar 18 22:56:54 [44323.443910] RSP: 0018:ffffac4f81d57d80 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
Mar 18 22:56:54 [44323.443914] RAX: 0000000000000000 RBX: ffff94249d393000 RCX: 0000000000000000
Mar 18 22:56:54 [44323.443917] RDX: 0000000000000001 RSI: 00000000000000fb RDI: 0000000000000282
Mar 18 22:56:54 [44323.443920] RBP: ffffac4f81d57dd0 R08: ffff94249d393160 R09: ffff9424544cad80
Mar 18 22:56:54 [44323.443923] R10: ffffac4f81d57e00 R11: ffff9424a427d080 R12: 0000000000000002
Mar 18 22:56:54 [44323.443926] R13: ffff9423c0e88000 R14: 0000000000000001 R15: ffff94245442b880
Mar 18 22:56:54 [44323.443930] FS:  00007f35d8723000(0000) GS:ffff9424aec00000(0000) knlGS:0000000000000000
Mar 18 22:56:54 [44323.443934] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:56:54 [44323.443937] CR2: 00000000018cf078 CR3: 0000000206634000 CR4: 00000000000406f0
Mar 18 22:56:54 [44323.443940] Call Trace:
Mar 18 22:56:54 [44323.443950]  ? x86_match_cpu+0xa0/0xa0
Mar 18 22:56:54 [44323.443955]  ? arch_freq_get_on_cpu+0x38/0x80
Mar 18 22:56:54 [44323.443959]  arch_freq_get_on_cpu+0x38/0x80
Mar 18 22:56:54 [44323.443967]  show_scaling_cur_freq+0x18/0x80
Mar 18 22:56:54 [44323.443972]  show+0x2d/0x50
Mar 18 22:56:54 [44323.443979]  sysfs_kf_seq_show+0xb0/0x110
Mar 18 22:56:54 [44323.443985]  seq_read+0xd2/0x440
Mar 18 22:56:54 [44323.443991]  vfs_read+0x89/0x130
Mar 18 22:56:54 [44323.443996]  SyS_read+0x52/0xc0
Mar 18 22:56:54 [44323.444002]  system_call_fast_compare_end+0xc/0x6f
Mar 18 22:56:54 [44323.444006] RIP: 0033:0x7f35d64da69a
Mar 18 22:56:54 [44323.444009] RSP: 002b:00007fffc37cb040 EFLAGS: 00000246
Mar 18 22:56:54 [44323.444012] Code: 28 00 00 00 75 40 48 83 c4 48 41 5a 5d 49 8d 62 f8 c3 48 89 d1 48 89 f2 48 8d 75 b0 e8 7e fe ff ff 8b 55 c8 83 e2 01 74 0a f3 90 <8b> 55 c8 83 e2 01 75 f6 eb c2 8b 05 4c 90 4f 01 85 c0 75 81 0f 
Mar 18 22:57:02 [44332.171626] INFO: rcu_sched detected stalls on CPUs/tasks:
Mar 18 22:57:02 [44332.171644]  2-...: (0 ticks this GP) idle=77a/140000000000000/0 softirq=2551346/2551346 fqs=7754 
Mar 18 22:57:02 [44332.171647]  (detected by 3, t=21007 jiffies, g=1896230, c=1896229, q=14528)
Mar 18 22:57:02 [44332.171655] Sending NMI from CPU 3 to CPUs 2:
Mar 18 22:57:02 [44332.171667] NMI backtrace for cpu 2
Mar 18 22:57:02 [44332.171672] CPU: 2 PID: 3173 Comm: bash Tainted: P      D    O L  4.14.12-2 #1
Mar 18 22:57:02 [44332.171674] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:57:02 [44332.171676] task: ffff94244ac2a080 task.stack: ffffac4f84c5c000
Mar 18 22:57:02 [44332.171680] RIP: 0010:0xfffffffefa757f7f
Mar 18 22:57:02 [44332.171682] RSP: 0018:ffffac4f84c5f278 EFLAGS: 00000002
Mar 18 22:57:02 [44332.171685] RAX: fffffffefec10000 RBX: fffffffeffc6873c RCX: 0000000000ffc650
Mar 18 22:57:02 [44332.171687] RDX: fffffffefa6de2b3 RSI: fffffffefa6de2af RDI: fffffffeffc68700
Mar 18 22:57:02 [44332.171689] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000004
Mar 18 22:57:02 [44332.171690] R10: 0000000000000004 R11: ffffac4f84c5f2f0 R12: 0000000000000000
Mar 18 22:57:02 [44332.171692] R13: 000000000000040a R14: 0000000000000007 R15: 0000000000000000
Mar 18 22:57:02 [44332.171695] FS:  00007fbfe889f740(0000) GS:ffff9424aec80000(0000) knlGS:0000000000000000
Mar 18 22:57:02 [44332.171697] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:57:02 [44332.171699] CR2: 000000ff0000ffa8 CR3: 000000022615e000 CR4: 00000000000406e0
Mar 18 22:57:02 [44332.171701] Call Trace:
Mar 18 22:57:02 [44332.171724]  ? efi_call+0x58/0x90
Mar 18 22:57:02 [44332.171731]  ? virt_efi_set_variable.part.7+0x5e/0x110
Mar 18 22:57:02 [44332.171736]  ? efi_query_variable_store+0x123/0x180
Mar 18 22:57:02 [44332.171740]  ? efivar_entry_set_safe+0x161/0x1d0
Mar 18 22:57:02 [44332.171743]  ? efivar_entry_set_safe+0x161/0x1d0
Mar 18 22:57:02 [44332.171750]  ? efi_pstore_write+0x10a/0x150 [efi_pstore]
Mar 18 22:57:02 [44332.171754]  ? efi_pstore_write+0x10a/0x150 [efi_pstore]
Mar 18 22:57:02 [44332.171761]  ? pstore_dump+0x169/0x330
Mar 18 22:57:02 [44332.171765]  ? vprintk_emit+0x337/0x3a0
Mar 18 22:57:02 [44332.171770]  ? kmsg_dump+0xb3/0xd0
Mar 18 22:57:02 [44332.171775]  ? oops_end+0x6e/0xc0
Mar 18 22:57:02 [44332.171779]  ? no_context+0x180/0x400
Mar 18 22:57:02 [44332.171783]  ? __do_page_fault+0x37b/0x4e0
Mar 18 22:57:02 [44332.171790]  ? page_fault+0x2c/0x60
Mar 18 22:57:02 [44332.171794]  ? _raw_spin_lock_irqsave+0x1e/0x40
Mar 18 22:57:02 [44332.171799]  ? tty_port_tty_get+0x19/0x40
Mar 18 22:57:02 [44332.171802]  ? 0xffffffffc1633c91
Mar 18 22:57:02 [44332.171808]  ? proc_reg_write+0x3d/0x60
Mar 18 22:57:02 [44332.171812]  ? vfs_write+0xad/0x1a0
Mar 18 22:57:02 [44332.171815]  ? SyS_write+0x52/0xc0
Mar 18 22:57:02 [44332.171818]  ? system_call_fast_compare_end+0xc/0x6f
Mar 18 22:57:02 [44332.171820] Code: 83 c4 20 5f c3 cc cc cc 66 90 48 8b 05 23 33 00 00 8a 48 02 48 8b 05 19 33 00 00 83 c9 10 88 48 02 48 8b 05 0c 33 00 00 8a 48 0d <f6> c1 07 75 da f3 c3 cc cc 8a 0d e0 32 00 00 48 8b 05 f3 32 00 
Mar 18 22:57:04 [44333.375138] perf: interrupt took too long (3903 > 2500), lowering kernel.perf_event_max_sample_rate to 51250
Mar 18 22:57:04 [44333.376093] BUG: unable to handle kernel paging request at fffffffeffc6e0f6
Mar 18 22:57:04 [44333.376106] IP: 0xfffffffefa753ef1
Mar 18 22:57:04 [44333.376110] PGD 57c0e067 P4D 57c0e067 PUD 0 
Mar 18 22:57:04 [44333.376118] Oops: 0000 [#2] SMP NOPTI
Mar 18 22:57:04 [44333.376122] Modules linked in: tty2com(O) binfmt_misc reiserfs dm_mod br_netfilter bridge stp llc fuse tty0tty(O) tun loop pci_stub vboxpci(O) vboxnetadp(O) vboxnetflt(O) vboxdrv(O) it87 hwmon_vid joydev usblp cp210x usbserial hid_generic usbhid hid snd_hda_codec_hdmi btrfs zstd_decompress zstd_compress xxhash xor raid6_pq pktcdvd nvidia_drm(PO) nvidia_modeset(PO) evdev edac_mce_amd kvm_amd nvidia(PO) snd_hda_codec_realtek kvm irqbypass crct10dif_pclmul snd_hda_codec_generic crc32_pclmul crc32c_intel ohci_pci ghash_clmulni_intel pcbc snd_hda_intel drm_kms_helper aesni_intel efi_pstore aes_x86_64 snd_hda_codec crypto_simd glue_helper cryptd snd_hda_core drm snd_hwdep ipmi_devintf snd_pcm xhci_pci snd_timer ehci_pci pcspkr ohci_hcd ipmi_msghandler efivars xhci_hcd r8169 snd ehci_hcd fam15h_power k10temp
Mar 18 22:57:04 [44333.376208]  soundcore mii usbcore sp5100_tco usb_common i2c_piix4 shpchp button acpi_cpufreq efivarfs initio BusLogic arcmsr aic7xxx aic79xx scsi_transport_spi sr_mod cdrom sg sd_mod pdc_adma sata_mv ata_piix ahci libahci sata_qstor sata_vsc sata_uli sata_sis pata_sis sata_sx4 sata_nv sata_via sata_svw sata_sil24 sata_sil sata_promise libata scsi_mod ext4 fscrypto jbd2 mbcache crc16
Mar 18 22:57:04 [44333.376260] CPU: 2 PID: 8045 Comm: kworker/2:2 Tainted: P      D    O L  4.14.12-2 #1
Mar 18 22:57:04 [44333.376263] Hardware name: Gigabyte Technology Co., Ltd. To be filled by O.E.M./970A-DS3P, BIOS FB 10/31/2014
Mar 18 22:57:04 [44333.376271] Workqueue: events efivar_update_sysfs_entries [efivars]
Mar 18 22:57:04 [44333.376275] task: ffff9424546df0c0 task.stack: ffffac4f8a294000
Mar 18 22:57:04 [44333.376279] RIP: 0010:0xfffffffefa753ef1
Mar 18 22:57:04 [44333.376281] RSP: 0018:ffffac4f8a297b20 EFLAGS: 00010213
Mar 18 22:57:04 [44333.376285] RAX: 00000000ffffffff RBX: ffffac4f8a297ba0 RCX: fffffffeffc6e0f2
Mar 18 22:57:04 [44333.376288] RDX: ffffac4f8a297ba0 RSI: ffffac4f8a297df8 RDI: 0000000000000000
Mar 18 22:57:04 [44333.376291] RBP: 0000000000000040 R08: fffffffeffc6e0f2 R09: 0000000000000000
Mar 18 22:57:04 [44333.376294] R10: ffffac4f8a297ba0 R11: fffffffeffc50000 R12: ffffac4f8a297df8
Mar 18 22:57:04 [44333.376297] R13: 8000000000000015 R14: 0000000000000286 R15: ffffffffb24ec7c8
Mar 18 22:57:04 [44333.376301] FS:  0000000000000000(0000) GS:ffff9424aec80000(0000) knlGS:0000000000000000
Mar 18 22:57:04 [44333.376304] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 18 22:57:04 [44333.376306] CR2: fffffffefa753ef1 CR3: 0000000057c0a000 CR4: 00000000000406e0
Mar 18 22:57:04 [44333.376309] Call Trace:
Mar 18 22:57:04 [44333.376323]  ? efi_call+0x58/0x90
Mar 18 22:57:04 [44333.376332]  ? virt_efi_get_next_variable+0x6e/0x120
Mar 18 22:57:04 [44333.376336]  ? efivar_init+0x11c/0x3a0
Mar 18 22:57:04 [44333.376342]  ? sched_clock+0x5/0x10
Mar 18 22:57:04 [44333.376347]  ? efivar_release+0x20/0x20 [efivars]
Mar 18 22:57:04 [44333.376351]  ? put_prev_entity+0x1e/0xe0
Mar 18 22:57:04 [44333.376357]  ? efivar_update_sysfs_entries+0x24/0x60 [efivars]
Mar 18 22:57:04 [44333.376361]  ? efivar_update_sysfs_entries+0x24/0x60 [efivars]
Mar 18 22:57:04 [44333.376366]  ? process_one_work+0x185/0x380
Mar 18 22:57:04 [44333.376371]  ? worker_thread+0x2e/0x390
Mar 18 22:57:04 [44333.376375]  ? process_one_work+0x380/0x380
Mar 18 22:57:04 [44333.376379]  ? kthread+0x118/0x130
Mar 18 22:57:04 [44333.376382]  ? kthread_create_on_node+0x70/0x70
Mar 18 22:57:04 [44333.376387]  ? exit_to_usermode_loop+0x6e/0xc0
Mar 18 22:57:04 [44333.376392]  ? ret_from_fork+0x1f/0x30
Mar 18 22:57:04 [44333.376396] Code:  Bad RIP value.
Mar 18 22:57:04 [44333.376408] RIP: 0xfffffffefa753ef1 RSP: ffffac4f8a297b20
Mar 18 22:57:04 [44333.376411] CR2: fffffffeffc6e0f6
Mar 18 22:57:04 [44333.376415] ---[ end trace 883a70a9d4231777 ]---

Kernel used:

uname -rsvo
Linux 4.14.12-2 #1 SMP Sun Jan 7 15:31:02 CET 2018 GNU/Linux
RishiGupta12 commented 6 years ago

I will check and resolve. I am super loaded with other work at this time.

RishiGupta12 commented 6 years ago

Hi Slavkoja,

I tried a lot but not able to reproduce. Can you give details about how you were using this driver. And can you build your kernel with following debug option enabled so that we can debug issue CONFIG_DEBUG_SPINLOCK, CONFIG_DEBUG_MUTEXES.