At Sun, 23 Nov 2014 22:27:40 +0100, Stefan Seyfried wrote:
Hi all,
I know this is for upstream. But 3.16 ist old, so nobody will care and additionally I know that both sound and USB maintainers are reading this list, this is why I come here :-)
My daughters machine got updated to 13.2. She has a "Midiman M-Audio Keystation USB 49" keyboard. I have the following script to use the box as cheap synthesizer: --- #!/bin/sh trap 'killall -9 timidity; pasuspender /bin/true' 0 killall -9 timidity export TIMIDITY_PCM_NAME=sysdefault ( pasuspender -- timidity -Os -A250a -s 44100 -iA -B1,1 )& sleep 2; aconnect 20:0 128:0 sleep 1 echo "Enter zum beenden..." read x killall timidity ---
Plug in keyboard in "boot" mode:
2014-11-21T17:50:47.095100+01:00 wendy kernel: [29816.287121] usb 4-2: new full-speed USB device number 3 using uhci_hcd 2014-11-21T17:50:47.244111+01:00 wendy kernel: [29816.436861] usb 4-2: New USB device found, idVendor=0763, idProduct=1014 2014-11-21T17:50:47.244165+01:00 wendy kernel: [29816.436871] usb 4-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
...firmware is uploaded...
2014-11-21T17:50:54.058158+01:00 wendy kernel: [29823.250222] usb 4-2: USB disconnect, device number 3 2014-11-21T17:50:55.014085+01:00 wendy kernel: [29824.206153] usb 4-2: new full-speed USB device number 4 using uhci_hcd 2014-11-21T17:50:55.146113+01:00 wendy kernel: [29824.338789] usb 4-2: New USB device found, idVendor=0763, idProduct=1015 2014-11-21T17:50:55.146163+01:00 wendy kernel: [29824.338804] usb 4-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
... device get's new USB is an dis usable.
2014-11-21T17:50:56.288141+01:00 wendy kernel: [29825.480162] usbcore: registered new interface driver snd-usb-audio
This works well. However, She quickly got the following backtraces:
2014-11-21T17:51:08.319339+01:00 wendy kernel: [29837.511023] ------------[ cut here ]------------ 2014-11-21T17:51:08.319386+01:00 wendy kernel: [29837.511061] WARNING: CPU: 0 PID: 0 at ../drivers/usb/core/urb.c:339 usb_submit_urb+0x5f/0x70() 2014-11-21T17:51:08.319396+01:00 wendy kernel: [29837.511069] URB ef705c40 submitted while active 2014-11-21T17:51:08.319410+01:00 wendy kernel: [29837.511074] Modules linked in: snd_seq_midi snd_seq_midi_event snd_hrtimer snd_usb_audio snd_usbmidi_lib snd_rawmidi nfsv3 nfs_acl rpcsec_gss_krb5 auth_rpcgss oid_registry nfsv4 dns_resolver bnep bluetooth 6lowpan_iphc rfkill fuse nfs lockd sunrpc fscache iscsi_ibft iscsi_boot_sysfs af_packet snd_hda_codec_realtek snd_hda_codec_generic snd_hda_intel snd_hda_controller snd_hda_codec snd_hwdep snd_pcm_oss snd_pcm snd_seq snd_seq_device r8169 snd_timer snd_mixer_oss snd mii iTCO_wdt iTCO_vendor_support ppdev parport_pc shpchp parport gpio_ich ite_cir rc_core lpc_ich mfd_core soundcore coretemp i2c_i801 serio_raw pcspkr tpm_infineon tpm_tis tpm dm_mod sr_mod cdrom i915 i2c_algo_bit video drm_kms_helper drm thermal button processor sg 2014-11-21T17:51:08.319420+01:00 wendy kernel: [29837.511209] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 3.16.6-2-desktop #1 2014-11-21T17:51:08.319429+01:00 wendy kernel: [29837.511215] Hardware name: FOXCONN TPS01/TPS01, BIOS 080015 03/23/2010 2014-11-21T17:51:08.319436+01:00 wendy kernel: [29837.511221] c0984bfa f4009ed4 c078deaf f4009ee4 c024c884 c09a135c f4009f00 00000000 2014-11-21T17:51:08.319444+01:00 wendy kernel: [29837.511234] c0984bfa 00000153 c061ac4f c061ac4f 00000009 00000001 ef705c40 e854d1c0 2014-11-21T17:51:08.319451+01:00 wendy kernel: [29837.511247] f4009eec c024c8d3 00000009 f4009ee4 c09a135c f4009f00 f4009f04 c061ac4f 2014-11-21T17:51:08.319459+01:00 wendy kernel: [29837.511259] Call Trace: 2014-11-21T17:51:08.319466+01:00 wendy kernel: [29837.511283] [<c0205df6>] try_stack_unwind+0x156/0x170 2014-11-21T17:51:08.319474+01:00 wendy kernel: [29837.511303] [<c020482a>] dump_trace+0x5a/0x1b0 2014-11-21T17:51:08.319483+01:00 wendy kernel: [29837.511315] [<c0205e56>] show_trace_log_lvl+0x46/0x50 2014-11-21T17:51:08.319490+01:00 wendy kernel: [29837.511327] [<c02049d1>] show_stack_log_lvl+0x51/0xe0 2014-11-21T17:51:08.319499+01:00 wendy kernel: [29837.511338] [<c0205eb7>] show_stack+0x27/0x50 2014-11-21T17:51:08.319507+01:00 wendy kernel: [29837.511350] [<c078deaf>] dump_stack+0x45/0x65 2014-11-21T17:51:08.319514+01:00 wendy kernel: [29837.511369] [<c024c884>] warn_slowpath_common+0x84/0xa0 2014-11-21T17:51:08.319523+01:00 wendy kernel: [29837.511381] [<c024c8d3>] warn_slowpath_fmt+0x33/0x40 2014-11-21T17:51:08.319531+01:00 wendy kernel: [29837.511393] [<c061ac4f>] usb_submit_urb+0x5f/0x70 2014-11-21T17:51:08.319539+01:00 wendy kernel: [29837.511415] [<f7974104>] snd_usbmidi_submit_urb+0x14/0x60 [snd_usbmidi_lib] 2014-11-21T17:51:08.319546+01:00 wendy kernel: [29837.511441] [<f797483a>] snd_usbmidi_error_timer+0x6a/0xa0 [snd_usbmidi_lib] 2014-11-21T17:51:08.319555+01:00 wendy kernel: [29837.511456] [<c02570c0>] call_timer_fn+0x30/0x130 2014-11-21T17:51:08.319563+01:00 wendy kernel: [29837.511468] [<c0257442>] run_timer_softirq+0x1c2/0x260 2014-11-21T17:51:08.319571+01:00 wendy kernel: [29837.511483] [<c0251493>] __do_softirq+0xc3/0x270 2014-11-21T17:51:08.319578+01:00 wendy kernel: [29837.511494] [<c0204732>] do_softirq_own_stack+0x22/0x30 2014-11-21T17:51:08.319585+01:00 wendy kernel: [29837.511505] [<c025186d>] irq_exit+0x8d/0xa0 2014-11-21T17:51:08.319591+01:00 wendy kernel: [29837.511520] [<c0795228>] smp_apic_timer_interrupt+0x38/0x50 2014-11-21T17:51:08.319598+01:00 wendy kernel: [29837.511533] [<c0794a3c>] apic_timer_interrupt+0x34/0x3c 2014-11-21T17:51:08.319605+01:00 wendy kernel: [29837.511550] [<c0673d9e>] cpuidle_enter_state+0x3e/0xd0 2014-11-21T17:51:08.319612+01:00 wendy kernel: [29837.511563] [<c028bb8d>] cpu_idle_loop+0x29d/0x3e0 2014-11-21T17:51:08.319619+01:00 wendy kernel: [29837.511575] [<c028bd23>] cpu_startup_entry+0x53/0x60 2014-11-21T17:51:08.319625+01:00 wendy kernel: [29837.511589] [<c0bfac1e>] start_kernel+0x415/0x41a 2014-11-21T17:51:08.319632+01:00 wendy kernel: [29837.511598] ---[ end trace f8fefbc2e004204d ]--- 2014-11-21T17:51:08.319639+01:00 wendy kernel: [29837.511609] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.319646+01:00 wendy kernel: [29837.511616] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.319653+01:00 wendy kernel: [29837.511622] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.319659+01:00 wendy kernel: [29837.511636] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.319665+01:00 wendy kernel: [29837.511643] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.319673+01:00 wendy kernel: [29837.511650] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.319680+01:00 wendy kernel: [29837.511656] ------------[ cut here ]------------ 2014-11-21T17:51:08.319688+01:00 wendy kernel: [29837.511665] WARNING: CPU: 0 PID: 0 at ../kernel/timer.c:1147 call_timer_fn+0x115/0x130() 2014-11-21T17:51:08.319695+01:00 wendy kernel: [29837.511676] timer: snd_usbmidi_error_timer+0x0/0xa0 [snd_usbmidi_lib] preempt leak: 00000101 -> 00000102 2014-11-21T17:51:08.319704+01:00 wendy kernel: [29837.511680] Modules linked in: snd_seq_midi snd_seq_midi_event snd_hrtimer snd_usb_audio snd_usbmidi_lib snd_rawmidi nfsv3 nfs_acl rpcsec_gss_krb5 auth_rpcgss oid_registry nfsv4 dns_resolver bnep bluetooth 6lowpan_iphc rfkill fuse nfs lockd sunrpc fscache iscsi_ibft iscsi_boot_sysfs af_packet snd_hda_codec_realtek snd_hda_codec_generic snd_hda_intel snd_hda_controller snd_hda_codec snd_hwdep snd_pcm_oss snd_pcm snd_seq snd_seq_device r8169 snd_timer snd_mixer_oss snd mii iTCO_wdt iTCO_vendor_support ppdev parport_pc shpchp parport gpio_ich ite_cir rc_core lpc_ich mfd_core soundcore coretemp i2c_i801 serio_raw pcspkr tpm_infineon tpm_tis tpm dm_mod sr_mod cdrom i915 i2c_algo_bit video drm_kms_helper drm thermal button processor sg 2014-11-21T17:51:08.319713+01:00 wendy kernel: [29837.511782] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G W 3.16.6-2-desktop #1 2014-11-21T17:51:08.319719+01:00 wendy kernel: [29837.511787] Hardware name: FOXCONN TPS01/TPS01, BIOS 080015 03/23/2010 2014-11-21T17:51:08.319727+01:00 wendy kernel: [29837.511792] c0940e36 f4009f28 c078deaf f4009f38 c024c884 c0936590 f4009f54 00000000 2014-11-21T17:51:08.319735+01:00 wendy kernel: [29837.511805] c0940e36 0000047b c02571a5 c02571a5 00000009 f4009f9c e854d1e0 00000101 2014-11-21T17:51:08.319745+01:00 wendy kernel: [29837.511818] f4009f40 c024c8d3 00000009 f4009f38 c0936590 f4009f54 f4009f74 c02571a5 2014-11-21T17:51:08.319754+01:00 wendy kernel: [29837.511830] Call Trace: 2014-11-21T17:51:08.319763+01:00 wendy kernel: [29837.511843] [<c0205df6>] try_stack_unwind+0x156/0x170 2014-11-21T17:51:08.319771+01:00 wendy kernel: [29837.511862] [<c020482a>] dump_trace+0x5a/0x1b0 2014-11-21T17:51:08.319778+01:00 wendy kernel: [29837.511873] [<c0205e56>] show_trace_log_lvl+0x46/0x50 2014-11-21T17:51:08.319786+01:00 wendy kernel: [29837.511885] [<c02049d1>] show_stack_log_lvl+0x51/0xe0 2014-11-21T17:51:08.319795+01:00 wendy kernel: [29837.511896] [<c0205eb7>] show_stack+0x27/0x50 2014-11-21T17:51:08.319804+01:00 wendy kernel: [29837.511907] [<c078deaf>] dump_stack+0x45/0x65 2014-11-21T17:51:08.319813+01:00 wendy kernel: [29837.511918] [<c024c884>] warn_slowpath_common+0x84/0xa0 2014-11-21T17:51:08.319823+01:00 wendy kernel: [29837.511930] [<c024c8d3>] warn_slowpath_fmt+0x33/0x40 2014-11-21T17:51:08.319874+01:00 wendy kernel: [29837.511941] [<c02571a5>] call_timer_fn+0x115/0x130 2014-11-21T17:51:08.319883+01:00 wendy kernel: [29837.511954] [<c0257442>] run_timer_softirq+0x1c2/0x260 2014-11-21T17:51:08.319892+01:00 wendy kernel: [29837.511968] [<c0251493>] __do_softirq+0xc3/0x270 2014-11-21T17:51:08.319900+01:00 wendy kernel: [29837.511979] [<c0204732>] do_softirq_own_stack+0x22/0x30 2014-11-21T17:51:08.319908+01:00 wendy kernel: [29837.511990] [<c025186d>] irq_exit+0x8d/0xa0 2014-11-21T17:51:08.319916+01:00 wendy kernel: [29837.512021] [<c0795228>] smp_apic_timer_interrupt+0x38/0x50 2014-11-21T17:51:08.319924+01:00 wendy kernel: [29837.512034] [<c0794a3c>] apic_timer_interrupt+0x34/0x3c 2014-11-21T17:51:08.319932+01:00 wendy kernel: [29837.512047] [<c0673d9e>] cpuidle_enter_state+0x3e/0xd0 2014-11-21T17:51:08.319940+01:00 wendy kernel: [29837.512059] [<c028bb8d>] cpu_idle_loop+0x29d/0x3e0 2014-11-21T17:51:08.319949+01:00 wendy kernel: [29837.512070] [<c028bd23>] cpu_startup_entry+0x53/0x60 2014-11-21T17:51:08.319957+01:00 wendy kernel: [29837.512082] [<c0bfac1e>] start_kernel+0x415/0x41a 2014-11-21T17:51:08.319965+01:00 wendy kernel: [29837.512091] ---[ end trace f8fefbc2e004204e ]--- 2014-11-21T17:51:08.685138+01:00 wendy kernel: [29837.878046] usb 4-2: usb_submit_urb: -16 2014-11-21T17:51:08.685183+01:00 wendy kernel: [29837.878078] usb 4-2: usb_submit_urb: -16
the usb_submit_urb goes on and on until today, over several suspend to RAM cycles. The keyboard did still work. Until the machine finally panicked today 19:40+01:00.
After reboot, the module was loaded during boot, same procedure:
2014-11-23T19:43:06.088478+01:00 wendy kernel: [ 27.562444] usb 4-2: new full-speed USB device number 3 using uhci_hcd 2014-11-23T19:43:06.088486+01:00 wendy kernel: [ 27.698944] usb 4-2: New USB device found, idVendor=0763, idProduct=1014 2014-11-23T19:43:06.088494+01:00 wendy kernel: [ 27.698954] usb 4-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 2014-11-23T19:43:06.088502+01:00 wendy kernel: [ 34.954224] usb 4-2: USB disconnect, device number 3
something strange happens: 2014-11-23T19:43:06.088510+01:00 wendy kernel: [ 35.914169] usb 4-2: new full-speed USB device number 4 using uhci_hcd 2014-11-23T19:43:06.088518+01:00 wendy kernel: [ 36.042935] usb 4-2: config index 0 descriptor too short (expected 9, got 2) 2014-11-23T19:43:06.088526+01:00 wendy kernel: [ 36.042944] usb 4-2: can't read configurations, error -22
...but the device appears finally: 2014-11-23T19:43:06.088549+01:00 wendy kernel: [ 36.146573] usb 4-2: new full-speed USB device number 5 using uhci_hcd 2014-11-23T19:43:06.088559+01:00 wendy kernel: [ 36.279939] usb 4-2: New USB device found, idVendor=0763, idProduct=1015 2014-11-23T19:43:06.088567+01:00 wendy kernel: [ 36.279948] usb 4-2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 2014-11-23T19:43:06.088593+01:00 wendy kernel: [ 37.406602] usbcore: registered new interface driver snd-usb-audio
then again: 2014-11-23T19:44:17.179668+01:00 wendy kernel: [ 108.594035] ------------[ cut here ]------------ 2014-11-23T19:44:17.179728+01:00 wendy kernel: [ 108.594078] WARNING: CPU: 1 PID: 0 at ../drivers/usb/core/urb.c:339 usb_submit_urb+0x5f/0x70() 2014-11-23T19:44:17.179739+01:00 wendy kernel: [ 108.594088] URB f6bcd700 submitted while active 2014-11-23T19:44:17.179785+01:00 wendy kernel: [ 108.594094] Modules linked in: snd_seq_midi snd_seq_midi_event snd_hrtimer nfsv3 nfs_acl rpcsec_gss_krb5 auth_rpcgss oid_registry nfsv4 dns_resolver bnep bluetooth 6lowpan_iphc rfkill fuse nfs lockd sunrpc fscache iscsi_ibft iscsi_boot_sysfs af_packet snd_usb_audio snd_usbmidi_lib snd_rawmidi snd_hda_codec_realtek snd_hda_codec_generic gpio_ich iTCO_wdt iTCO_vendor_support snd_hda_intel snd_hda_controller snd_hda_codec ppdev snd_hwdep snd_pcm_oss snd_pcm snd_seq snd_seq_device snd_timer snd_mixer_oss coretemp serio_raw pcspkr i2c_i801 snd lpc_ich mfd_core r8169 mii shpchp soundcore parport_pc parport ite_cir rc_core tpm_infineon tpm_tis tpm dm_mod sr_mod cdrom i915 i2c_algo_bit video drm_kms_helper drm thermal button processor sg 2014-11-23T19:44:17.179796+01:00 wendy kernel: [ 108.594249] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 3.16.6-2-desktop #1 2014-11-23T19:44:17.179805+01:00 wendy kernel: [ 108.594266] Hardware name: FOXCONN TPS01/TPS01, BIOS 080015 03/23/2010 2014-11-23T19:44:17.179814+01:00 wendy kernel: [ 108.594275] c0984bfa f416ded4 c078deaf f416dee4 c024c884 c09a135c f416df00 00000000 2014-11-23T19:44:17.179822+01:00 wendy kernel: [ 108.594293] c0984bfa 00000153 c061ac4f c061ac4f 00000009 00000002 f6bcd700 f6bcd040 2014-11-23T19:44:17.179831+01:00 wendy kernel: [ 108.594311] f416deec c024c8d3 00000009 f416dee4 c09a135c f416df00 f416df04 c061ac4f 2014-11-23T19:44:17.179839+01:00 wendy kernel: [ 108.594329] Call Trace: 2014-11-23T19:44:17.179847+01:00 wendy kernel: [ 108.594361] [<c0205df6>] try_stack_unwind+0x156/0x170 2014-11-23T19:44:17.179855+01:00 wendy kernel: [ 108.594391] [<c020482a>] dump_trace+0x5a/0x1b0 2014-11-23T19:44:17.179863+01:00 wendy kernel: [ 108.594412] [<c0205e56>] show_trace_log_lvl+0x46/0x50 2014-11-23T19:44:17.179871+01:00 wendy kernel: [ 108.594430] [<c02049d1>] show_stack_log_lvl+0x51/0xe0 2014-11-23T19:44:17.179879+01:00 wendy kernel: [ 108.594448] [<c0205eb7>] show_stack+0x27/0x50 2014-11-23T19:44:17.179888+01:00 wendy kernel: [ 108.594466] [<c078deaf>] dump_stack+0x45/0x65 2014-11-23T19:44:17.179896+01:00 wendy kernel: [ 108.594486] [<c024c884>] warn_slowpath_common+0x84/0xa0 2014-11-23T19:44:17.179904+01:00 wendy kernel: [ 108.594503] [<c024c8d3>] warn_slowpath_fmt+0x33/0x40 2014-11-23T19:44:17.179912+01:00 wendy kernel: [ 108.594520] [<c061ac4f>] usb_submit_urb+0x5f/0x70 2014-11-23T19:44:17.179921+01:00 wendy kernel: [ 108.594554] [<f76eb104>] snd_usbmidi_submit_urb+0x14/0x60 [snd_usbmidi_lib] 2014-11-23T19:44:17.179928+01:00 wendy kernel: [ 108.594587] [<f76eb83a>] snd_usbmidi_error_timer+0x6a/0xa0 [snd_usbmidi_lib] 2014-11-23T19:44:17.179937+01:00 wendy kernel: [ 108.594611] [<c02570c0>] call_timer_fn+0x30/0x130 2014-11-23T19:44:17.179944+01:00 wendy kernel: [ 108.594631] [<c0257442>] run_timer_softirq+0x1c2/0x260 2014-11-23T19:44:17.179953+01:00 wendy kernel: [ 108.594655] [<c0251493>] __do_softirq+0xc3/0x270 2014-11-23T19:44:17.179960+01:00 wendy kernel: [ 108.594673] [<c0204732>] do_softirq_own_stack+0x22/0x30 2014-11-23T19:44:17.179968+01:00 wendy kernel: [ 108.594690] [<c025186d>] irq_exit+0x8d/0xa0 2014-11-23T19:44:17.179976+01:00 wendy kernel: [ 108.594713] [<c0795228>] smp_apic_timer_interrupt+0x38/0x50 2014-11-23T19:44:17.179984+01:00 wendy kernel: [ 108.594732] [<c0794a3c>] apic_timer_interrupt+0x34/0x3c 2014-11-23T19:44:17.180003+01:00 wendy kernel: [ 108.594751] [<c0673d9e>] cpuidle_enter_state+0x3e/0xd0 2014-11-23T19:44:17.180011+01:00 wendy kernel: [ 108.594770] [<c028bb8d>] cpu_idle_loop+0x29d/0x3e0 2014-11-23T19:44:17.180020+01:00 wendy kernel: [ 108.594787] [<c028bd23>] cpu_startup_entry+0x53/0x60 2014-11-23T19:44:17.180028+01:00 wendy kernel: [ 108.594818] [<c0230bdf>] start_secondary+0x20f/0x2e0 2014-11-23T19:44:17.180036+01:00 wendy kernel: [ 108.594833] ---[ end trace 952a2543044934e8 ]--- 2014-11-23T19:44:17.180044+01:00 wendy kernel: [ 108.594848] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.180052+01:00 wendy kernel: [ 108.594867] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.180060+01:00 wendy kernel: [ 108.594877] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.180067+01:00 wendy kernel: [ 108.594888] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.180075+01:00 wendy kernel: [ 108.594898] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.180083+01:00 wendy kernel: [ 108.594908] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.180091+01:00 wendy kernel: [ 108.594917] ------------[ cut here ]------------ 2014-11-23T19:44:17.180099+01:00 wendy kernel: [ 108.594930] WARNING: CPU: 1 PID: 0 at ../kernel/timer.c:1147 call_timer_fn+0x115/0x130() 2014-11-23T19:44:17.180107+01:00 wendy kernel: [ 108.594947] timer: snd_usbmidi_error_timer+0x0/0xa0 [snd_usbmidi_lib] preempt leak: 00000101 -> 00000102 2014-11-23T19:44:17.180117+01:00 wendy kernel: [ 108.594954] Modules linked in: snd_seq_midi snd_seq_midi_event snd_hrtimer nfsv3 nfs_acl rpcsec_gss_krb5 auth_rpcgss oid_registry nfsv4 dns_resolver bnep bluetooth 6lowpan_iphc rfkill fuse nfs lockd sunrpc fscache iscsi_ibft iscsi_boot_sysfs af_packet snd_usb_audio snd_usbmidi_lib snd_rawmidi snd_hda_codec_realtek snd_hda_codec_generic gpio_ich iTCO_wdt iTCO_vendor_support snd_hda_intel snd_hda_controller snd_hda_codec ppdev snd_hwdep snd_pcm_oss snd_pcm snd_seq snd_seq_device snd_timer snd_mixer_oss coretemp serio_raw pcspkr i2c_i801 snd lpc_ich mfd_core r8169 mii shpchp soundcore parport_pc parport ite_cir rc_core tpm_infineon tpm_tis tpm dm_mod sr_mod cdrom i915 i2c_algo_bit video drm_kms_helper drm thermal button processor sg 2014-11-23T19:44:17.180126+01:00 wendy kernel: [ 108.595119] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 3.16.6-2-desktop #1 2014-11-23T19:44:17.180135+01:00 wendy kernel: [ 108.595129] Hardware name: FOXCONN TPS01/TPS01, BIOS 080015 03/23/2010 2014-11-23T19:44:17.180142+01:00 wendy kernel: [ 108.595137] c0940e36 f416df28 c078deaf f416df38 c024c884 c0936590 f416df54 00000000 2014-11-23T19:44:17.180151+01:00 wendy kernel: [ 108.595156] c0940e36 0000047b c02571a5 c02571a5 00000009 f416df9c f6bcd060 00000101 2014-11-23T19:44:17.180158+01:00 wendy kernel: [ 108.595175] f416df40 c024c8d3 00000009 f416df38 c0936590 f416df54 f416df74 c02571a5 2014-11-23T19:44:17.180166+01:00 wendy kernel: [ 108.595195] Call Trace: 2014-11-23T19:44:17.180174+01:00 wendy kernel: [ 108.595221] [<c0205df6>] try_stack_unwind+0x156/0x170 2014-11-23T19:44:17.180181+01:00 wendy kernel: [ 108.595250] [<c020482a>] dump_trace+0x5a/0x1b0 2014-11-23T19:44:17.180189+01:00 wendy kernel: [ 108.595279] [<c0205e56>] show_trace_log_lvl+0x46/0x50 2014-11-23T19:44:17.180196+01:00 wendy kernel: [ 108.595298] [<c02049d1>] show_stack_log_lvl+0x51/0xe0 2014-11-23T19:44:17.180205+01:00 wendy kernel: [ 108.595315] [<c0205eb7>] show_stack+0x27/0x50 2014-11-23T19:44:17.180212+01:00 wendy kernel: [ 108.595333] [<c078deaf>] dump_stack+0x45/0x65 2014-11-23T19:44:17.180219+01:00 wendy kernel: [ 108.595354] [<c024c884>] warn_slowpath_common+0x84/0xa0 2014-11-23T19:44:17.180227+01:00 wendy kernel: [ 108.595373] [<c024c8d3>] warn_slowpath_fmt+0x33/0x40 2014-11-23T19:44:17.180235+01:00 wendy kernel: [ 108.595391] [<c02571a5>] call_timer_fn+0x115/0x130 2014-11-23T19:44:17.180243+01:00 wendy kernel: [ 108.595411] [<c0257442>] run_timer_softirq+0x1c2/0x260 2014-11-23T19:44:17.180250+01:00 wendy kernel: [ 108.595434] [<c0251493>] __do_softirq+0xc3/0x270 2014-11-23T19:44:17.180258+01:00 wendy kernel: [ 108.595452] [<c0204732>] do_softirq_own_stack+0x22/0x30 2014-11-23T19:44:17.180266+01:00 wendy kernel: [ 108.595469] [<c025186d>] irq_exit+0x8d/0xa0 2014-11-23T19:44:17.180275+01:00 wendy kernel: [ 108.595491] [<c0795228>] smp_apic_timer_interrupt+0x38/0x50 2014-11-23T19:44:17.180282+01:00 wendy kernel: [ 108.595510] [<c0794a3c>] apic_timer_interrupt+0x34/0x3c 2014-11-23T19:44:17.180291+01:00 wendy kernel: [ 108.595532] [<c0673d9e>] cpuidle_enter_state+0x3e/0xd0 2014-11-23T19:44:17.180298+01:00 wendy kernel: [ 108.595551] [<c028bb8d>] cpu_idle_loop+0x29d/0x3e0 2014-11-23T19:44:17.180307+01:00 wendy kernel: [ 108.595570] [<c028bd23>] cpu_startup_entry+0x53/0x60 2014-11-23T19:44:17.180314+01:00 wendy kernel: [ 108.595588] [<c0230bdf>] start_secondary+0x20f/0x2e0 2014-11-23T19:44:17.180323+01:00 wendy kernel: [ 108.595604] ---[ end trace 952a2543044934e9 ]--- 2014-11-23T19:44:17.767840+01:00 wendy kernel: [ 109.183061] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.767889+01:00 wendy kernel: [ 109.183070] usb 4-2: usb_submit_urb: -16 2014-11-23T19:44:17.767899+01:00 wendy kernel: [ 109.183076] usb 4-2: usb_submit_urb: -16
.... on and on, until panic at 20:04 local time.
My daughter took a picture of the second panic (but I don't know how good the quality is and if we can see anything on it)
What did I already try? * update to 3.17.x from Kernel:stable * downgrade to 3.10.x from 13.1
Both show the same "usb_submit_urb: -16" flood. I did not try long enough to panic the box. My daughter suggested that the panic does happen when she starts playing with the special MIDI functions of the keyboard. I don't know if that's true or if the two panics today are just bad luck.
Grepping through the archived syslog seems to suggest that it happened first on March 23 2014, with 13.1 kernel 3.11.10
With 3.11.10, there is only one call trace from [ 135.889057] WARNING: CPU: 3 PID: 1756 at /home/abuild/rpmbuild/BUILD/kernel-desktop-3.11.10/linux-3.11/drivers/usb/core/urb.c:327 usb_submit_urb+0x3a4/0x3c0() [ 135.889061] URB f3f710c0 submitted while active
And the message is slightly different: [ 135.889439] ALSA midi.c:194 usb_submit_urb: -16
Going further back through the logs would suggest this started with 13.1, 12.3 (kernel 3.7) seems to have worked fine. OTOH the MIDI keyboard is only used once in a while, so this might not be true.
Anything more you need from me? (and *please* don't say "git bisect" :-))
This is a code path for the error recovery, so something went already wrong in the USB side, supposedly. In anyway, could you try the patch below? The problem after reboot isn't cured by that, though. It's the hardware issue, somehow it's screwed up. You'd need to do some hard reset. Takashi --- diff --git a/sound/usb/midi.c b/sound/usb/midi.c index d3d49525a16b..b604760cb7f0 100644 --- a/sound/usb/midi.c +++ b/sound/usb/midi.c @@ -353,11 +353,12 @@ static void snd_usbmidi_out_tasklet(unsigned long data) static void snd_usbmidi_error_timer(unsigned long data) { struct snd_usb_midi *umidi = (struct snd_usb_midi *)data; + unsigned long flags; unsigned int i, j; - spin_lock(&umidi->disc_lock); + spin_lock_irqsave(&umidi->disc_lock, flags); if (umidi->disconnected) { - spin_unlock(&umidi->disc_lock); + spin_unlock_irqrestore(&umidi->disc_lock, flags); return; } for (i = 0; i < MIDI_MAX_ENDPOINTS; ++i) { @@ -365,6 +366,8 @@ static void snd_usbmidi_error_timer(unsigned long data) if (in && in->error_resubmit) { in->error_resubmit = 0; for (j = 0; j < INPUT_URBS; ++j) { + if (atomic_read(&in->urbs[j]->use_count)) + continue; in->urbs[j]->dev = umidi->dev; snd_usbmidi_submit_urb(in->urbs[j], GFP_ATOMIC); } @@ -372,7 +375,7 @@ static void snd_usbmidi_error_timer(unsigned long data) if (umidi->endpoints[i].out) snd_usbmidi_do_output(umidi->endpoints[i].out); } - spin_unlock(&umidi->disc_lock); + spin_unlock_irqrestore(&umidi->disc_lock, flags); } /* helper function to send static data that may not DMA-able */ -- To unsubscribe, e-mail: opensuse-kernel+unsubscribe@opensuse.org To contact the owner, e-mail: opensuse-kernel+owner@opensuse.org