Bug 68001 - mwifiex_usb on MS Surface Pro 1 crashes when it's under heavy traffic
Summary: mwifiex_usb on MS Surface Pro 1 crashes when it's under heavy traffic
Status: RESOLVED UNREPRODUCIBLE
Alias: None
Product: Drivers
Classification: Unclassified
Component: network-wireless (show other bugs)
Hardware: x86-64 Linux
: P1 normal
Assignee: networking_wireless@kernel-bugs.osdl.org
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2014-01-01 14:44 UTC by Jakub Zeman
Modified: 2014-01-29 17:50 UTC (History)
2 users (show)

See Also:
Kernel Version: 3.12.6
Subsystem:
Regression: No
Bisected commit-id:


Attachments

Description Jakub Zeman 2014-01-01 14:44:50 UTC
I'm using MS Surface Pro 1 hardware with Debian testing OS (the bug was reproduced on 3.11-2-amd64 #1 SMP Debian 3.11.10-1 (2013-12-04) x86_64 GNU/Linux).

The module mwifiex_usb for Marvel 88W8797 USB wireless/bluetooth device crashes under heavy traffic or randomly after some time. I'm using latest mrvl/usb8797_uapsta.bin firmware for it.

I can see following dmesg after the driver crashes (after the crash it stops working until next reboot - when I try modprobe -r mwifiex_usb it gets stuck and the reboot never finishes):

[ 4942.159179] usb 1-3: mwifiex_cmd_timeout_func: Timeout cmd id (1387973970.867379) = 0xa4, act = 0x0
[ 4942.159213] usb 1-3: num_data_h2c_failure = 0
[ 4942.159215] usb 1-3: num_cmd_h2c_failure = 0
[ 4942.159216] usb 1-3: num_cmd_timeout = 1
[ 4942.159217] usb 1-3: num_tx_timeout = 0
[ 4942.159218] usb 1-3: last_cmd_index = 1
[ 4942.159220] usb 1-3: last_cmd_id: 16 00 a4 00 16 00 a4 00 7f 00
[ 4942.159221] usb 1-3: last_cmd_act: 00 00 00 00 00 00 00 00 00 00
[ 4942.159222] usb 1-3: last_cmd_resp_index = 0
[ 4942.159223] usb 1-3: last_cmd_resp_id: 16 80 7f 80 16 80 a4 80 7f 80
[ 4942.159224] usb 1-3: last_event_index = 3
[ 4942.159226] usb 1-3: last_event: 2b 00 2b 00 2b 00 2b 00 2b 00
[ 4942.159227] usb 1-3: data_sent=0 cmd_sent=0
[ 4942.159228] usb 1-3: ps_mode=1 ps_state=0
[ 4942.159233] usb 1-3: cmd timeout
[ 4942.159263] usb 1-3: failed to get signal information
[ 4950.412467] usb 1-3: data: -ENOSR is returned
[ 4950.412514] usb 1-3: data: -ENOSR is returned
[ 4951.411260] usb 1-3: data: -ENOSR is returned
[ 4951.419802] usb 1-3: data: -ENOSR is returned
[ 4952.179442] usb 1-3: mwifiex_cmd_timeout_func: Timeout cmd id (1387973980.883337) = 0xa4, act = 0x0
[ 4952.179454] usb 1-3: num_data_h2c_failure = 0
[ 4952.179459] usb 1-3: num_cmd_h2c_failure = 0
[ 4952.179463] usb 1-3: num_cmd_timeout = 2
[ 4952.179466] usb 1-3: num_tx_timeout = 0
[ 4952.179480] usb 1-3: last_cmd_index = 2
[ 4952.179482] usb 1-3: last_cmd_id: 16 00 a4 00 a4 00 a4 00 7f 00
[ 4952.179483] usb 1-3: last_cmd_act: 00 00 00 00 00 00 00 00 00 00
[ 4952.179485] usb 1-3: last_cmd_resp_index = 0
[ 4952.179486] usb 1-3: last_cmd_resp_id: 16 80 7f 80 16 80 a4 80 7f 80
[ 4952.179487] usb 1-3: last_event_index = 3
[ 4952.179489] usb 1-3: last_event: 2b 00 2b 00 2b 00 2b 00 2b 00
[ 4952.179490] usb 1-3: data_sent=1 cmd_sent=1
[ 4952.179492] usb 1-3: ps_mode=1 ps_state=0
[ 4952.179495] usb 1-3: cmd timeout
[ 4952.179506] usb 1-3: failed to get signal information
[ 4952.179731] ------------[ cut here ]------------
[ 4952.179756] WARNING: CPU: 1 PID: 34 at /build/linux-DpqWmm/linux-3.11.10/drivers/usb/core/urb.c:327 usb_submit_urb+0x33d/0x350 [usbcore]()
[ 4952.179757] URB ffff88011979b480 submitted while active
[ 4952.179758] Modules linked in: btusb mwifiex_usb mwifiex cfg80211 nf_conntrack_ipv6 nf_defrag_ipv6 bnep rfcomm ip6table_filter bluetooth ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse iTCO_wdt iTCO_vendor_support snd_hda_codec_hdmi snd_hda_codec_realtek hid_multitouch joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal coretemp evdev kvm_intel kvm crc32c_intel rfkill ghash_clmulni_intel aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul glue_helper efi_pstore microcode i2c_i801 efivars pcspkr lpc_ich mfd_core snd_hda_intel snd_hda_codec i915 snd_hwdep battery ac snd_pcm snd_page_alloc video tpm_tis snd_timer drm_kms_helper tpm snd tpm_bios drm soundcore mei_me i2c_algo_bit mei mperf i2c_core button processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif ahci libahci libata scsi_mod ehci_pci ehci_hcd xhci_hcd usbcore usb_common thermal thermal_sys [last unloaded: cfg80211]
[ 4952.179799] CPU: 1 PID: 34 Comm: kworker/1:1 Not tainted 3.11-2-amd64 #1 Debian 3.11.10-1
[ 4952.179800] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 4952.179804] Workqueue: MWIFIEX_WORK_QUEUE mwifiex_main_work_queue [mwifiex]
[ 4952.179806]  0000000000000009 ffffffff814740f8 ffff88011786dd40 ffffffff810583d2
[ 4952.179809]  ffff8801176b6000 ffff88011786dd90 0000000000000001 ffff88010a924b30
[ 4952.179811]  ffff8800c5d12fc0 ffffffff81058437 ffffffffa0035b68 ffff880100000020
[ 4952.179813] Call Trace:
[ 4952.179818]  [<ffffffff814740f8>] ? dump_stack+0x41/0x51
[ 4952.179822]  [<ffffffff810583d2>] ? warn_slowpath_common+0x72/0x90
[ 4952.179825]  [<ffffffff81058437>] ? warn_slowpath_fmt+0x47/0x50
[ 4952.179830]  [<ffffffff81019085>] ? read_tsc+0x5/0x20
[ 4952.179836]  [<ffffffffa002468d>] ? usb_submit_urb+0x33d/0x350 [usbcore]
[ 4952.179839]  [<ffffffffa03be119>] ? mwifiex_usb_host_to_card+0xe9/0x220 [mwifiex_usb]
[ 4952.179842]  [<ffffffffa03e89f5>] ? mwifiex_exec_next_cmd+0x3b5/0x4c0 [mwifiex]
[ 4952.179845]  [<ffffffffa03e56c2>] ? mwifiex_main_process+0x4d2/0x510 [mwifiex]
[ 4952.179848]  [<ffffffff81070f06>] ? process_one_work+0x176/0x430
[ 4952.179850]  [<ffffffff81071ad6>] ? worker_thread+0x116/0x3b0
[ 4952.179852]  [<ffffffff810719c0>] ? rescuer_thread+0x330/0x330
[ 4952.179854]  [<ffffffff81077edf>] ? kthread+0xaf/0xc0
[ 4952.179857]  [<ffffffff81077e30>] ? kthread_create_on_node+0x110/0x110
[ 4952.179860]  [<ffffffff814813bc>] ? ret_from_fork+0x7c/0xb0
[ 4952.179863]  [<ffffffff81077e30>] ? kthread_create_on_node+0x110/0x110
[ 4952.179864] ---[ end trace 89518d9f09ee73e3 ]---
[ 4952.179866] usb 1-3: mwifiex_usb_host_to_card: usb_submit_urb failed
[ 4952.179868] usb 1-3: DNLD_CMD: host to card failed
[ 4952.179883] usb 1-3: failed to get signal information
[ 4952.179914] usb 1-3: mwifiex_usb_host_to_card: usb_submit_urb failed
[ 4952.179916] usb 1-3: DNLD_CMD: host to card failed
[ 4952.179921] usb 1-3: failed to get signal information
[ 4958.144800] usb 1-3: mwifiex_usb_host_to_card: usb_submit_urb failed
[ 4958.144804] usb 1-3: DNLD_CMD: host to card failed
[ 4958.144839] usb 1-3: failed to get signal information
[ 4958.144906] usb 1-3: mwifiex_usb_host_to_card: usb_submit_urb failed
[ 4958.144908] usb 1-3: DNLD_CMD: host to card failed
[ 4958.144938] usb 1-3: failed to get signal information
[ 4964.141382] usb 1-3: mwifiex_usb_host_to_card: usb_submit_urb failed
[ 4964.141387] usb 1-3: DNLD_CMD: host to card failed
Comment 1 Jakub Zeman 2014-01-05 10:49:28 UTC
I upgraded to 3.12.6 and it's still there. Before the crash there are following problems with blocked khubd task:

[  602.084704] INFO: task khubd:123 blocked for more than 120 seconds.
[  602.084721]       Not tainted 3.12-1-amd64 #1
[  602.084723] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  602.084725] khubd           D ffff88003655bb80     0   123      2 0x00000000
[  602.084730]  ffff88003655b840 0000000000000046 00000000000142c0 ffff880036609fd8
[  602.084734]  00000000000142c0 ffff880036609fd8 ffff8801170c3088 ffff8801170c3130
[  602.084737]  ffff8801170c31f0 0000000000000000 0000000000000000 ffff880036609c08
[  602.084740] Call Trace:
[  602.084750]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  602.084755]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  602.084759]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  602.084773]  [<ffffffffa03ea466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  602.084792]  [<ffffffffa0027f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  602.084802]  [<ffffffffa00281a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  602.084811]  [<ffffffffa00290a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  602.084816]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  602.084820]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  602.084824]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  602.084828]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  602.084838]  [<ffffffffa00287e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  602.084847]  [<ffffffffa001bb6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  602.084851]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  602.084861]  [<ffffffffa002042d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  602.084864]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  602.084874]  [<ffffffffa001f520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  602.084876]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  602.084880]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  602.084883]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  602.084886]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
Comment 2 Jakub Zeman 2014-01-07 16:07:03 UTC
Another dump:

[ 3565.755976] ------------[ cut here ]------------
[ 3565.755996] WARNING: CPU: 0 PID: 4658 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[ 3565.755998] Modules linked in: tun btusb parport_pc ppdev lp parport bnep rfcomm bluetooth nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse hid_multitouch hid_sensor_gyro_3d hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel iTCO_wdt iTCO_vendor_support evdev aesni_intel mwifiex_usb aes_x86_64 ablk_helper mwifiex cryptd lrw gf128mul glue_helper cfg80211 rfkill snd_hda_codec_hdmi efi_pstore snd_hda_codec_realtek battery pcspkr i915 i2c_i801 efivars ac snd_hda_intel video lpc_ich snd_hda_codec drm_kms_helper mfd_core snd_hwdep snd_pcm drm tpm_tis snd_page_alloc tpm snd_timer i2c_algo_bit tpm_bios snd i2c_core soundcore button mei_me mei processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci ehci_hcd xhci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[ 3565.756048] CPU: 0 PID: 4658 Comm: kworker/u8:1 Not tainted 3.12-1-amd64 #1 Debian 3.12.6-2
[ 3565.756050] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 3565.756055] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[ 3565.756056]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[ 3565.756059]  0000000000000000 ffff880085369dc0 0000000000000000 ffff8800c487b958
[ 3565.756060]  ffff8800365d1000 ffffffffa03d5058 0000000000000001 0000000000000000
[ 3565.756062] Call Trace:
[ 3565.756067]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[ 3565.756071]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[ 3565.756077]  [<ffffffffa03d5058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[ 3565.756083]  [<ffffffffa03b52bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[ 3565.756088]  [<ffffffffa03b5328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[ 3565.756093]  [<ffffffffa03b1015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[ 3565.756096]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[ 3565.756098]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[ 3565.756100]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[ 3565.756102]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[ 3565.756104]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 3565.756107]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[ 3565.756108]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 3565.756110] ---[ end trace 7df7a986db689a50 ]---
[ 3565.756144] cfg80211: Calling CRDA to update world regulatory domain
[ 3565.760608] cfg80211: World regulatory domain updated:
[ 3565.760611] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 3565.760612] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3565.760614] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3565.760615] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 3565.760616] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3565.760617] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3651.078594] usb 1-3: data: -ENOSR is returned
[ 3652.349551] usb 1-3: data: -ENOSR is returned
[ 3667.868047] usb 1-3: data: -ENOSR is returned
[ 3668.599133] usb 1-3: data: -ENOSR is returned
[ 3671.473579] usb 1-3: data: -ENOSR is returned
[ 3686.456422] usb 1-3: data: -ENOSR is returned
[ 3686.505050] ------------[ cut here ]------------
[ 3686.505100] WARNING: CPU: 1 PID: 4658 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[ 3686.505101] Modules linked in: tun btusb parport_pc ppdev lp parport bnep rfcomm bluetooth nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse hid_multitouch hid_sensor_gyro_3d hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel iTCO_wdt iTCO_vendor_support evdev aesni_intel mwifiex_usb aes_x86_64 ablk_helper mwifiex cryptd lrw gf128mul glue_helper cfg80211 rfkill snd_hda_codec_hdmi efi_pstore snd_hda_codec_realtek battery pcspkr i915 i2c_i801 efivars ac snd_hda_intel video lpc_ich snd_hda_codec drm_kms_helper mfd_core snd_hwdep snd_pcm drm tpm_tis snd_page_alloc tpm snd_timer i2c_algo_bit tpm_bios snd i2c_core soundcore button mei_me mei processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci ehci_hcd xhci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[ 3686.505152] CPU: 1 PID: 4658 Comm: kworker/u8:1 Tainted: G        W    3.12-1-amd64 #1 Debian 3.12.6-2
[ 3686.505153] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 3686.505158] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[ 3686.505159]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[ 3686.505162]  0000000000000000 ffff880085369dc0 0000000000000000 ffff8800d2df11d8
[ 3686.505166]  ffff8800365d1000 ffffffffa03d5058 0000000000000001 0000000000000000
[ 3686.505168] Call Trace:
[ 3686.505183]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[ 3686.505187]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[ 3686.505194]  [<ffffffffa03d5058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[ 3686.505200]  [<ffffffffa03b52bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[ 3686.505206]  [<ffffffffa03b5328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[ 3686.505212]  [<ffffffffa03b1015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[ 3686.505215]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[ 3686.505217]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[ 3686.505219]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[ 3686.505222]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[ 3686.505224]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 3686.505227]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[ 3686.505229]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 3686.505230] ---[ end trace 7df7a986db689a51 ]---
[ 3686.505256] cfg80211: Calling CRDA to update world regulatory domain
[ 3686.509312] cfg80211: World regulatory domain updated:
[ 3686.509315] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 3686.509317] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3686.509318] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3686.509320] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 3686.509321] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3686.509322] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3711.248961] usb 1-3: data: -ENOSR is returned
[ 3806.595044] ------------[ cut here ]------------
[ 3806.595076] WARNING: CPU: 1 PID: 4658 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[ 3806.595079] Modules linked in: tun btusb parport_pc ppdev lp parport bnep rfcomm bluetooth nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse hid_multitouch hid_sensor_gyro_3d hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel iTCO_wdt iTCO_vendor_support evdev aesni_intel mwifiex_usb aes_x86_64 ablk_helper mwifiex cryptd lrw gf128mul glue_helper cfg80211 rfkill snd_hda_codec_hdmi efi_pstore snd_hda_codec_realtek battery pcspkr i915 i2c_i801 efivars ac snd_hda_intel video lpc_ich snd_hda_codec drm_kms_helper mfd_core snd_hwdep snd_pcm drm tpm_tis snd_page_alloc tpm snd_timer i2c_algo_bit tpm_bios snd i2c_core soundcore button mei_me mei processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci ehci_hcd xhci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[ 3806.595141] CPU: 1 PID: 4658 Comm: kworker/u8:1 Tainted: G        W    3.12-1-amd64 #1 Debian 3.12.6-2
[ 3806.595142] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 3806.595147] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[ 3806.595149]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[ 3806.595151]  0000000000000000 ffff880085369dc0 0000000000000000 ffff8800cad1d358
[ 3806.595153]  ffff8800365d1000 ffffffffa03d5058 0000000000000001 0000000000000000
[ 3806.595155] Call Trace:
[ 3806.595160]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[ 3806.595165]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[ 3806.595170]  [<ffffffffa03d5058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[ 3806.595176]  [<ffffffffa03b52bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[ 3806.595182]  [<ffffffffa03b5328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[ 3806.595187]  [<ffffffffa03b1015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[ 3806.595189]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[ 3806.595191]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[ 3806.595193]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[ 3806.595196]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[ 3806.595197]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 3806.595200]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[ 3806.595202]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 3806.595203] ---[ end trace 7df7a986db689a52 ]---
[ 3806.595240] cfg80211: Calling CRDA to update world regulatory domain
[ 3806.600808] cfg80211: World regulatory domain updated:
[ 3806.600812] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 3806.600814] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3806.600817] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3806.600818] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 3806.600820] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3806.600822] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3807.622371] usb 1-3: data: -ENOSR is returned
[ 3807.622409] usb 1-3: data: -ENOSR is returned
[ 4143.062359] systemd-logind[2787]: New session c1 of user jzeman.
[ 4143.134905] systemd-logind[2787]: Removed session c1.
[ 4143.135277] systemd-logind[2787]: New session c2 of user jzeman.
[ 4143.208905] systemd-logind[2787]: Removed session c2.
[ 4143.209262] systemd-logind[2787]: New session c3 of user jzeman.
[ 4143.280360] systemd-logind[2787]: Removed session c3.
[ 4143.280704] systemd-logind[2787]: New session c4 of user jzeman.
[ 4143.429232] systemd-logind[2787]: Removed session c4.
[ 4143.429579] systemd-logind[2787]: New session c5 of user jzeman.
[ 4167.828087] usb 1-3: data: -ENOSR is returned
[ 4167.828436] usb 1-3: data: -ENOSR is returned
[ 5126.915406] usb 1-3: data: -ENOSR is returned
[ 6207.455408] ------------[ cut here ]------------
[ 6207.455429] WARNING: CPU: 3 PID: 4865 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[ 6207.455430] Modules linked in: tun btusb parport_pc ppdev lp parport bnep rfcomm bluetooth nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse hid_multitouch hid_sensor_gyro_3d hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel iTCO_wdt iTCO_vendor_support evdev aesni_intel mwifiex_usb aes_x86_64 ablk_helper mwifiex cryptd lrw gf128mul glue_helper cfg80211 rfkill snd_hda_codec_hdmi efi_pstore snd_hda_codec_realtek battery pcspkr i915 i2c_i801 efivars ac snd_hda_intel video lpc_ich snd_hda_codec drm_kms_helper mfd_core snd_hwdep snd_pcm drm tpm_tis snd_page_alloc tpm snd_timer i2c_algo_bit tpm_bios snd i2c_core soundcore button mei_me mei processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci ehci_hcd xhci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[ 6207.455481] CPU: 3 PID: 4865 Comm: kworker/u8:2 Tainted: G        W    3.12-1-amd64 #1 Debian 3.12.6-2
[ 6207.455482] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 6207.455487] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[ 6207.455489]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[ 6207.455491]  0000000000000000 ffff880117163dc0 0000000000000000 ffff8800cd830458
[ 6207.455493]  ffff8800365d1000 ffffffffa03d5058 0000000000000001 0000000000000000
[ 6207.455495] Call Trace:
[ 6207.455500]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[ 6207.455504]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[ 6207.455510]  [<ffffffffa03d5058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[ 6207.455516]  [<ffffffffa03b52bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[ 6207.455522]  [<ffffffffa03b5328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[ 6207.455526]  [<ffffffffa03b1015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[ 6207.455529]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[ 6207.455531]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[ 6207.455533]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[ 6207.455535]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[ 6207.455537]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 6207.455540]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[ 6207.455541]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 6207.455543] ---[ end trace 7df7a986db689a53 ]---
[ 6207.455581] cfg80211: Calling CRDA to update world regulatory domain
[ 6207.460979] cfg80211: World regulatory domain updated:
[ 6207.460983] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 6207.460985] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 6207.460987] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 6207.460989] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 6207.460991] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 6207.460993] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 6208.480709] usb 1-3: data: -ENOSR is returned
[ 6208.480744] usb 1-3: data: -ENOSR is returned
[ 6327.602679] usb 1-3: data: -ENOSR is returned
[ 6567.592719] ------------[ cut here ]------------
[ 6567.592739] WARNING: CPU: 0 PID: 4658 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[ 6567.592741] Modules linked in: tun btusb parport_pc ppdev lp parport bnep rfcomm bluetooth nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse hid_multitouch hid_sensor_gyro_3d hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel iTCO_wdt iTCO_vendor_support evdev aesni_intel mwifiex_usb aes_x86_64 ablk_helper mwifiex cryptd lrw gf128mul glue_helper cfg80211 rfkill snd_hda_codec_hdmi efi_pstore snd_hda_codec_realtek battery pcspkr i915 i2c_i801 efivars ac snd_hda_intel video lpc_ich snd_hda_codec drm_kms_helper mfd_core snd_hwdep snd_pcm drm tpm_tis snd_page_alloc tpm snd_timer i2c_algo_bit tpm_bios snd i2c_core soundcore button mei_me mei processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci ehci_hcd xhci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[ 6567.592790] CPU: 0 PID: 4658 Comm: kworker/u8:1 Tainted: G        W    3.12-1-amd64 #1 Debian 3.12.6-2
[ 6567.592791] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 6567.592796] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[ 6567.592797]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[ 6567.592800]  0000000000000000 ffff880085369dc0 0000000000000000 ffff8800d15118d8
[ 6567.592802]  ffff8800365d1000 ffffffffa03d5058 0000000000000001 0000000000000000
[ 6567.592804] Call Trace:
[ 6567.592808]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[ 6567.592812]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[ 6567.592818]  [<ffffffffa03d5058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[ 6567.592824]  [<ffffffffa03b52bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[ 6567.592829]  [<ffffffffa03b5328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[ 6567.592834]  [<ffffffffa03b1015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[ 6567.592837]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[ 6567.592839]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[ 6567.592841]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[ 6567.592843]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[ 6567.592845]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 6567.592847]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[ 6567.592849]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[ 6567.592850] ---[ end trace 7df7a986db689a54 ]---
Comment 3 Bing Zhao 2014-01-07 19:20:58 UTC
Comment #2 is a different issue which could be fixed by this patch from mainline linux kernel:
4c4d684 cfg80211: fix WARN_ON for re-association to the expired BSS
Comment 4 Bing Zhao 2014-01-09 02:42:57 UTC
Hi Jakub, any chance you could try this new firmware image usb8797_uapsta.bin v14.68.29.p26 on your Surface Pro 1?

git://git.marvell.com/mwifiex-firmware.git master

For "sme.c:658 __cfg80211_connect_result" issue you still need to apply patch "4c4d684 cfg80211: fix WARN_ON for re-association to the expired BSS" or update to a new kernel with that patch included.
Comment 5 Jakub Zeman 2014-01-09 12:32:03 UTC
Hi,

thank you for updated firmware.

I did following:
1) I used new firmware from git://git.marvell.com/mwifiex-firmware.git master
2) I applied patch 4c4d684a55fc01dac6bee696efc56b96d0e6c03a.patch and rebuild kernel Debian linux image 3.12.6

I must say that the reliability is much better (with previous version of firmware the crash happend almost immediatelly) but when I tried download about 150MB file I got following kernel crash:
[  324.378419] ------------[ cut here ]------------
[  324.378454] WARNING: CPU: 3 PID: 158 at /root/linux-3.12.6/net/wireless/sme.c:662 __cfg80211_connect_result+0x3db/0x420 [cfg80211]()
[  324.378455] Modules linked in: btusb parport_pc ppdev lp parport nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables bnep rfcomm bluetooth xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse iTCO_wdt iTCO_vendor_support hid_multitouch hid_sensor_magn_3d hid_sensor_als hid_sensor_accel_3d hid_sensor_gyro_3d hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon mwifiex_usb mwifiex cfg80211 rfkill x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel evdev aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul efi_pstore glue_helper pcspkr efivars snd_hda_codec_hdmi lpc_ich mfd_core i2c_i801 snd_hda_codec_realtek ac battery i915 video tpm_tis drm_kms_helper snd_hda_intel tpm drm snd_hda_codec tpm_bios i2c_algo_bit i2c_core snd_hwdep snd_pcm snd_page_alloc button snd_timer mei_me processor snd mei soundcore ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata scsi_mod xhci_hcd ehci_pci ehci_hcd usbcore usb_common thermal thermal_sys
[  324.378511] CPU: 3 PID: 158 Comm: kworker/u8:5 Not tainted 3.12-1-amd64 #1 Debian 3.12.6-2
[  324.378512] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[  324.378517] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[  324.378519]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[  324.378522]  0000000000000000 ffff880036911dc0 0000000000000000 ffff8800c4a636d8
[  324.378524]  ffff8800d2914c00 ffffffffa049c02b 0000000000000001 0000000000000000
[  324.378526] Call Trace:
[  324.378531]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[  324.378535]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[  324.378541]  [<ffffffffa049c02b>] ? __cfg80211_connect_result+0x3db/0x420 [cfg80211]
[  324.378547]  [<ffffffffa047c2bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[  324.378552]  [<ffffffffa047c328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[  324.378557]  [<ffffffffa0478015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[  324.378560]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[  324.378562]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[  324.378564]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[  324.378567]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  324.378569]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  324.378572]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  324.378574]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  324.378575] ---[ end trace a2b8ea4f40d4b929 ]---
[  324.378606] cfg80211: Calling CRDA to update world regulatory domain
[  324.385491] cfg80211: World regulatory domain updated:
[  324.385494] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[  324.385496] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  324.385498] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  324.385500] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[  324.385501] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  324.385503] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  325.410495] usb 3-3: data: -ENOSR is returned
[  325.410505] usb 3-3: data: -ENOSR is returned
[  396.024464] usb 3-3: data: -ENOSR is returned
[  445.078295] ------------[ cut here ]------------
[  445.078314] WARNING: CPU: 0 PID: 158 at /root/linux-3.12.6/net/wireless/sme.c:662 __cfg80211_connect_result+0x3db/0x420 [cfg80211]()
[  445.078315] Modules linked in: btusb parport_pc ppdev lp parport nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables bnep rfcomm bluetooth xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse iTCO_wdt iTCO_vendor_support hid_multitouch hid_sensor_magn_3d hid_sensor_als hid_sensor_accel_3d hid_sensor_gyro_3d hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon mwifiex_usb mwifiex cfg80211 rfkill x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel evdev aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul efi_pstore glue_helper pcspkr efivars snd_hda_codec_hdmi lpc_ich mfd_core i2c_i801 snd_hda_codec_realtek ac battery i915 video tpm_tis drm_kms_helper snd_hda_intel tpm drm snd_hda_codec tpm_bios i2c_algo_bit i2c_core snd_hwdep snd_pcm snd_page_alloc button snd_timer mei_me processor snd mei soundcore ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata scsi_mod xhci_hcd ehci_pci ehci_hcd usbcore usb_common thermal thermal_sys
[  445.078380] CPU: 0 PID: 158 Comm: kworker/u8:5 Tainted: G        W    3.12-1-amd64 #1 Debian 3.12.6-2
[  445.078381] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[  445.078386] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[  445.078387]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[  445.078390]  0000000000000000 ffff880036911dc0 0000000000000000 ffff8800da8f21d8
[  445.078392]  ffff8800d2914c00 ffffffffa049c02b 0000000000000001 0000000000000000
[  445.078394] Call Trace:
[  445.078400]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[  445.078404]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[  445.078410]  [<ffffffffa049c02b>] ? __cfg80211_connect_result+0x3db/0x420 [cfg80211]
[  445.078416]  [<ffffffffa047c2bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[  445.078422]  [<ffffffffa047c328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[  445.078426]  [<ffffffffa0478015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[  445.078429]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[  445.078432]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[  445.078434]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[  445.078436]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  445.078438]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  445.078441]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  445.078443]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  445.078444] ---[ end trace a2b8ea4f40d4b92a ]---
[  445.078473] cfg80211: Calling CRDA to update world regulatory domain
[  445.083275] cfg80211: World regulatory domain updated:
[  445.083278] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[  445.083280] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  445.083282] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  445.083283] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[  445.083284] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  445.083285] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Comment 6 Jakub Zeman 2014-01-09 13:10:18 UTC
There is one other issue which is probably causing no real problems but it's quit annoying:
[   56.270395] usb 3-3: data: -ENOSR is returned
[   56.270417] usb 3-3: data: -ENOSR is returned
[   58.289193] usb 3-3: data: -ENOSR is returned
[   58.289241] usb 3-3: data: -ENOSR is returned
[   58.389617] usb 3-3: data: -ENOSR is returned
[   75.193160] usb 3-3: data: -ENOSR is returned
[   75.193227] usb 3-3: data: -ENOSR is returned
[  138.215425] usb 3-3: data: -ENOSR is returned
[  138.215451] usb 3-3: data: -ENOSR is returned
[  139.744525] usb 3-3: data: -ENOSR is returned
[  158.538083] usb 3-3: data: -ENOSR is returned

After some time it fills whole log.
Comment 7 Jakub Zeman 2014-01-09 13:59:42 UTC
When I try new firmware without the kernel patch it works without problems (there is just usb 3-3: data: -ENOSR is returned).
Comment 8 Bing Zhao 2014-01-09 19:10:56 UTC
Perhaps the cfg80211 patch isn't suitable for stable kernel 3.12.x.
Anyway it's good to know that the new firmware works for you.
The "-ENOSR is returned" message has been removed in latest driver in kernel 3.13.
Comment 9 Jakub Zeman 2014-01-10 20:18:02 UTC
I found another problem. When I try to disable WiFi and enable it again I got following error (and after that the kernel is unstable and after some times freezes):

[  241.986115] INFO: task khubd:143 blocked for more than 120 seconds.
[  241.986125]       Not tainted 3.12-1-amd64 #1
[  241.986127] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  241.986130] khubd           D ffff8800364b9b00     0   143      2 0x00000000
[  241.986137]  ffff8800364b97c0 0000000000000046 00000000000142c0 ffff8800366effd8
[  241.986143]  00000000000142c0 ffff8800366effd8 ffff8800d7dfa888 ffff8800d7dfa930
[  241.986148]  ffff8800d7dfa9f0 0000000000000000 0000000000000000 ffff8800366efc08
[  241.986153] Call Trace:
[  241.986174]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  241.986177]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  241.986180]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  241.986192]  [<ffffffffa03fd466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  241.986209]  [<ffffffffa006ff1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  241.986217]  [<ffffffffa00701a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  241.986225]  [<ffffffffa00710a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  241.986229]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  241.986231]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  241.986233]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  241.986236]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  241.986243]  [<ffffffffa00707e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  241.986251]  [<ffffffffa0063b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  241.986254]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  241.986262]  [<ffffffffa006842d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  241.986265]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  241.986272]  [<ffffffffa0067520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  241.986274]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  241.986276]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  241.986279]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  241.986281]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
Comment 10 Jakub Zeman 2014-01-11 22:30:39 UTC
Another dmesg which happens regularly (I don't touch enable/disable wifi):


[  242.034378] INFO: task khubd:120 blocked for more than 120 seconds.
[  242.034386]       Not tainted 3.12-1-amd64 #1
[  242.034388] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.034390] khubd           D ffff880037286400     0   120      2 0x00000000
[  242.034397]  ffff8800372860c0 0000000000000046 00000000000142c0 ffff88003692ffd8
[  242.034402]  00000000000142c0 ffff88003692ffd8 ffff8801175bb888 ffff8801175bb930
[  242.034407]  ffff8801175bb9f0 0000000000000000 0000000000000000 ffff88003692fc08
[  242.034411] Call Trace:
[  242.034423]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  242.034429]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  242.034435]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  242.034456]  [<ffffffffa03af466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  242.034482]  [<ffffffffa0014f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  242.034495]  [<ffffffffa00151a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  242.034509]  [<ffffffffa00160a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  242.034514]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  242.034520]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  242.034525]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  242.034530]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  242.034544]  [<ffffffffa00157e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  242.034557]  [<ffffffffa0008b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  242.034562]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  242.034576]  [<ffffffffa000d42d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  242.034580]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  242.034593]  [<ffffffffa000c520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  242.034597]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  242.034601]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  242.034606]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  242.034609]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  249.720417] usb 1-3: data: -ENOSR is returned
[  362.104410] INFO: task khubd:120 blocked for more than 120 seconds.
[  362.104416]       Not tainted 3.12-1-amd64 #1
[  362.104418] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  362.104420] khubd           D ffff880037286400     0   120      2 0x00000000
[  362.104427]  ffff8800372860c0 0000000000000046 00000000000142c0 ffff88003692ffd8
[  362.104431]  00000000000142c0 ffff88003692ffd8 ffff8801175bb888 ffff8801175bb930
[  362.104435]  ffff8801175bb9f0 0000000000000000 0000000000000000 ffff88003692fc08
[  362.104438] Call Trace:
[  362.104449]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  362.104455]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  362.104460]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  362.104478]  [<ffffffffa03af466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  362.104501]  [<ffffffffa0014f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  362.104512]  [<ffffffffa00151a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  362.104523]  [<ffffffffa00160a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  362.104528]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  362.104532]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  362.104536]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  362.104541]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  362.104553]  [<ffffffffa00157e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  362.104564]  [<ffffffffa0008b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  362.104569]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  362.104580]  [<ffffffffa000d42d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  362.104584]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  362.104595]  [<ffffffffa000c520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  362.104598]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  362.104602]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  362.104606]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  362.104610]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  374.176920] usb 1-3: data: -ENOSR is returned
[  394.228511] usb 1-3: data: -ENOSR is returned
[  482.168929] INFO: task khubd:120 blocked for more than 120 seconds.
[  482.168940]       Not tainted 3.12-1-amd64 #1
[  482.168943] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  482.168947] khubd           D ffff880037286400     0   120      2 0x00000000
[  482.168966]  ffff8800372860c0 0000000000000046 00000000000142c0 ffff88003692ffd8
[  482.168969]  00000000000142c0 ffff88003692ffd8 ffff8801175bb888 ffff8801175bb930
[  482.168971]  ffff8801175bb9f0 0000000000000000 0000000000000000 ffff88003692fc08
[  482.168973] Call Trace:
[  482.168980]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  482.168983]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  482.168986]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  482.168999]  [<ffffffffa03af466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  482.169014]  [<ffffffffa0014f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  482.169020]  [<ffffffffa00151a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  482.169026]  [<ffffffffa00160a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  482.169029]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  482.169031]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  482.169033]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  482.169036]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  482.169042]  [<ffffffffa00157e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  482.169048]  [<ffffffffa0008b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  482.169051]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  482.169057]  [<ffffffffa000d42d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  482.169059]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  482.169065]  [<ffffffffa000c520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  482.169067]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  482.169069]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  482.169071]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  482.169073]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  484.282264] usb 1-3: data: -ENOSR is returned
[  494.857427] usb 1-3: data: -ENOSR is returned
[  504.293472] usb 1-3: data: -ENOSR is returned
[  504.293502] usb 1-3: data: -ENOSR is returned
[  514.299331] usb 1-3: data: -ENOSR is returned
[  529.473506] usb 1-3: data: -ENOSR is returned
[  529.473536] usb 1-3: data: -ENOSR is returned
[  544.316702] usb 1-3: data: -ENOSR is returned
[  602.230379] INFO: task khubd:120 blocked for more than 120 seconds.
[  602.230386]       Not tainted 3.12-1-amd64 #1
[  602.230387] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  602.230389] khubd           D ffff880037286400     0   120      2 0x00000000
[  602.230394]  ffff8800372860c0 0000000000000046 00000000000142c0 ffff88003692ffd8
[  602.230399]  00000000000142c0 ffff88003692ffd8 ffff8801175bb888 ffff8801175bb930
[  602.230402]  ffff8801175bb9f0 0000000000000000 0000000000000000 ffff88003692fc08
[  602.230405] Call Trace:
[  602.230415]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  602.230420]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  602.230424]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  602.230443]  [<ffffffffa03af466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  602.230464]  [<ffffffffa0014f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  602.230475]  [<ffffffffa00151a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  602.230487]  [<ffffffffa00160a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  602.230492]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  602.230496]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  602.230500]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  602.230504]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  602.230516]  [<ffffffffa00157e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  602.230528]  [<ffffffffa0008b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  602.230534]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  602.230546]  [<ffffffffa000d42d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  602.230551]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  602.230562]  [<ffffffffa000c520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  602.230566]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  602.230569]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  602.230573]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  602.230577]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  634.658411] usb 1-3: data: -ENOSR is returned
[  677.509132] usb 1-3: data: -ENOSR is returned
[  722.290556] INFO: task khubd:120 blocked for more than 120 seconds.
[  722.290567]       Not tainted 3.12-1-amd64 #1
[  722.290570] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  722.290574] khubd           D ffff880037286400     0   120      2 0x00000000
[  722.290594]  ffff8800372860c0 0000000000000046 00000000000142c0 ffff88003692ffd8
[  722.290596]  00000000000142c0 ffff88003692ffd8 ffff8801175bb888 ffff8801175bb930
[  722.290598]  ffff8801175bb9f0 0000000000000000 0000000000000000 ffff88003692fc08
[  722.290600] Call Trace:
[  722.290608]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  722.290611]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  722.290613]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  722.290626]  [<ffffffffa03af466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  722.290641]  [<ffffffffa0014f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  722.290647]  [<ffffffffa00151a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  722.290653]  [<ffffffffa00160a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  722.290656]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  722.290659]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  722.290661]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  722.290664]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  722.290670]  [<ffffffffa00157e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  722.290676]  [<ffffffffa0008b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  722.290679]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  722.290685]  [<ffffffffa000d42d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  722.290687]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  722.290693]  [<ffffffffa000c520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  722.290695]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  722.290697]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  722.290700]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  722.290701]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  822.707157] usb 1-3: data: -ENOSR is returned
[  842.344384] INFO: task khubd:120 blocked for more than 120 seconds.
[  842.344389]       Not tainted 3.12-1-amd64 #1
[  842.344390] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  842.344391] khubd           D ffff880037286400     0   120      2 0x00000000
[  842.344395]  ffff8800372860c0 0000000000000046 00000000000142c0 ffff88003692ffd8
[  842.344398]  00000000000142c0 ffff88003692ffd8 ffff8801175bb888 ffff8801175bb930
[  842.344400]  ffff8801175bb9f0 0000000000000000 0000000000000000 ffff88003692fc08
[  842.344402] Call Trace:
[  842.344410]  [<ffffffff81350711>] ? rpm_resume+0x191/0x660
[  842.344414]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  842.344417]  [<ffffffff81350cc5>] ? pm_runtime_forbid+0x35/0x50
[  842.344432]  [<ffffffffa03af466>] ? mwifiex_usb_resume+0xc6/0x130 [mwifiex_usb]
[  842.344448]  [<ffffffffa0014f1e>] ? usb_resume_interface.isra.5+0x9e/0xf0 [usbcore]
[  842.344455]  [<ffffffffa00151a2>] ? usb_resume_both+0x62/0x120 [usbcore]
[  842.344462]  [<ffffffffa00160a1>] ? usb_runtime_resume+0x11/0x20 [usbcore]
[  842.344465]  [<ffffffff8134fa17>] ? __rpm_callback+0x27/0x70
[  842.344468]  [<ffffffff8134fab8>] ? rpm_callback+0x58/0x80
[  842.344470]  [<ffffffff813509e6>] ? rpm_resume+0x466/0x660
[  842.344473]  [<ffffffff81350c23>] ? __pm_runtime_resume+0x43/0x70
[  842.344480]  [<ffffffffa00157e6>] ? usb_autoresume_device+0x16/0x40 [usbcore]
[  842.344486]  [<ffffffffa0008b6d>] ? usb_remote_wakeup.part.29+0xd/0x30 [usbcore]
[  842.344489]  [<ffffffff8148f559>] ? mutex_lock+0x9/0x30
[  842.344496]  [<ffffffffa000d42d>] ? hub_thread+0xf0d/0x1360 [usbcore]
[  842.344498]  [<ffffffff8107c3c0>] ? wake_up_atomic_t+0x30/0x30
[  842.344505]  [<ffffffffa000c520>] ? usb_reset_device+0x170/0x170 [usbcore]
[  842.344507]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  842.344509]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  842.344512]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  842.344514]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
Comment 11 Jakub Zeman 2014-01-13 07:46:01 UTC
Another dmesg with different crash (this time it's not probably connected with suspend/resume):

[  178.266438] ------------[ cut here ]------------
[  178.266451] WARNING: CPU: 0 PID: 159 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[  178.266452] Modules linked in: btusb mwifiex_usb mwifiex cfg80211 parport_pc ppdev lp parport nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter bnep ip6_tables rfcomm bluetooth rfkill xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse iTCO_wdt iTCO_vendor_support hid_multitouch hid_sensor_gyro_3d hid_sensor_als hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_generic hid_sensor_hub usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm evdev crc32_pclmul crc32c_intel efi_pstore ghash_clmulni_intel snd_hda_codec_hdmi aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul glue_helper efivars pcspkr i2c_i801 snd_hda_codec_realtek lpc_ich mfd_core i915 snd_hda_intel snd_hda_codec drm_kms_helper snd_hwdep battery snd_pcm drm video snd_page_alloc i2c_algo_bit i2c_core snd_timer tpm_tis ac snd tpm processor tpm_bios soundcore mei_me button mei ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci xhci_hcd ehci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[  178.266513] CPU: 0 PID: 159 Comm: kworker/u8:5 Not tainted 3.12-1-amd64 #1 Debian 3.12.6-2
[  178.266514] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[  178.266519] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[  178.266520]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[  178.266523]  0000000000000000 ffff880036feddc0 0000000000000000 ffff8800da8700d8
[  178.266525]  ffff8800d2188400 ffffffffa0766058 0000000000000001 0000000000000000
[  178.266527] Call Trace:
[  178.266532]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[  178.266536]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[  178.266541]  [<ffffffffa0766058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[  178.266546]  [<ffffffffa07462bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[  178.266550]  [<ffffffffa0746328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[  178.266553]  [<ffffffffa0742015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[  178.266556]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[  178.266558]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[  178.266561]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[  178.266563]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  178.266565]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  178.266568]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  178.266570]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  178.266571] ---[ end trace 666a4a7efeb752e2 ]---
[  178.266605] cfg80211: Calling CRDA to update world regulatory domain
[  178.272747] cfg80211: World regulatory domain updated:
[  178.272750] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[  178.272752] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  178.272753] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  178.272754] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[  178.272756] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  178.272757] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  236.331108] usb 3-3: data: -ENOSR is returned
[  241.952430] usb 3-3: data: -ENOSR is returned
[  241.952457] usb 3-3: data: -ENOSR is returned
[  241.986958] ------------[ cut here ]------------
[  241.986970] WARNING: CPU: 1 PID: 159 at /build/linux-xS3nxO/linux-3.12.6/net/wireless/sme.c:658 __cfg80211_connect_result+0x408/0x410 [cfg80211]()
[  241.986971] Modules linked in: btusb mwifiex_usb mwifiex cfg80211 parport_pc ppdev lp parport nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter bnep ip6_tables rfcomm bluetooth rfkill xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack iptable_filter ip_tables x_tables binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse iTCO_wdt iTCO_vendor_support hid_multitouch hid_sensor_gyro_3d hid_sensor_als hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_generic hid_sensor_hub usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm evdev crc32_pclmul crc32c_intel efi_pstore ghash_clmulni_intel snd_hda_codec_hdmi aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul glue_helper efivars pcspkr i2c_i801 snd_hda_codec_realtek lpc_ich mfd_core i915 snd_hda_intel snd_hda_codec drm_kms_helper snd_hwdep battery snd_pcm drm video snd_page_alloc i2c_algo_bit i2c_core snd_timer tpm_tis ac snd tpm processor tpm_bios soundcore mei_me button mei ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata ehci_pci xhci_hcd ehci_hcd scsi_mod usbcore usb_common thermal thermal_sys
[  241.987033] CPU: 1 PID: 159 Comm: kworker/u8:5 Tainted: G        W    3.12-1-amd64 #1 Debian 3.12.6-2
[  241.987034] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[  241.987039] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[  241.987041]  0000000000000009 ffffffff8148c9d5 0000000000000000 ffffffff8105b572
[  241.987043]  0000000000000000 ffff880036feddc0 0000000000000000 ffff8800c56ff7d8
[  241.987045]  ffff8800d2188400 ffffffffa0766058 0000000000000001 0000000000000000
[  241.987047] Call Trace:
[  241.987053]  [<ffffffff8148c9d5>] ? dump_stack+0x41/0x51
[  241.987056]  [<ffffffff8105b572>] ? warn_slowpath_common+0x72/0x90
[  241.987061]  [<ffffffffa0766058>] ? __cfg80211_connect_result+0x408/0x410 [cfg80211]
[  241.987065]  [<ffffffffa07462bd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[  241.987070]  [<ffffffffa0746328>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[  241.987073]  [<ffffffffa0742015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[  241.987076]  [<ffffffff810746bd>] ? process_one_work+0x16d/0x420
[  241.987078]  [<ffffffff81075286>] ? worker_thread+0x116/0x3b0
[  241.987080]  [<ffffffff81075170>] ? rescuer_thread+0x330/0x330
[  241.987083]  [<ffffffff8107b76f>] ? kthread+0xaf/0xc0
[  241.987085]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  241.987087]  [<ffffffff81499a8c>] ? ret_from_fork+0x7c/0xb0
[  241.987089]  [<ffffffff8107b6c0>] ? kthread_create_on_node+0x110/0x110
[  241.987091] ---[ end trace 666a4a7efeb752e3 ]---
[  241.987119] cfg80211: Calling CRDA to update world regulatory domain
[  241.992426] cfg80211: World regulatory domain updated:
[  241.992429] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[  241.992430] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  241.992432] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  241.992433] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[  241.992434] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[  241.992435] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Comment 12 Bing Zhao 2014-01-14 04:07:52 UTC
Jakub, are you able to test the kernel 3.13rc7 from wireless-testing.git tree?
This cfg80211 sme warning doesn't seem to happen on 3.13rcX on Surface Pro 2,
although they have issues with autosuspend and command timeout. See bug 64111 FYI.
Comment 13 Jakub Zeman 2014-01-14 18:31:20 UTC
I compiled tag v3.13-rc7 from  http://git.kernel.org/pub/scm/linux/kernel/git/linville/wireless-testing.git

uname -a
Linux jzeman-sfpro 3.13.0-rc7 #1 SMP Tue Jan 14 14:48:32 CET 2014 x86_64 GNU/Linux

At first look it seems more stable but after some time I got:
[ 1380.830724] ------------[ cut here ]------------
[ 1380.830736] WARNING: CPU: 1 PID: 138 at net/wireless/sme.c:662 __cfg80211_connect_result+0x3db/0x420 [cfg80211]()
[ 1380.830737] Modules linked in: btusb mwifiex_usb mwifiex cfg80211 nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp parport_pc ppdev nf_conntrack_ipv4 nf_defrag_ipv4 lp parport xt_conntrack nf_conntrack iptable_filter ip_tables x_tables rfcomm bnep bluetooth rfkill binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse snd_hda_codec_hdmi snd_hda_codec_realtek iTCO_wdt iTCO_vendor_support hid_multitouch hid_sensor_gyro_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_accel_3d hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio hid_sensor_hub uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon x86_pkg_temp_thermal intel_powerclamp coretemp kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul snd_hda_intel snd_hda_codec glue_helper snd_hwdep snd_pcm snd_page_alloc snd_timer i2c_i801 snd pcspkr soundcore lpc_ich mfd_core joydev evdev ac mei_me i915 mei battery tpm_tis tpm video drm_kms_helper drm i2c_algo_bit button i2c_core processor ext4 crc16 mbcache jbd2 hid_generic sg usbhid hid sd_mod crc_t10dif crct10dif_common ehci_pci xhci_hcd ehci_hcd ahci libahci libata scsi_mod usbcore usb_common thermal thermal_sys
[ 1380.830798] CPU: 1 PID: 138 Comm: kworker/u8:4 Not tainted 3.13.0-rc7 #1
[ 1380.830799] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 1380.830803] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
[ 1380.830805]  0000000000000009 ffffffff8149bb3f 0000000000000000 ffffffff8105b772
[ 1380.830807]  0000000000000000 ffff88011603ddc0 0000000000000000 ffff8800b0c4fa98
[ 1380.830809]  ffff8800c37a2c00 ffffffffa074a7bb 0000000000000001 0000000000000000
[ 1380.830811] Call Trace:
[ 1380.830816]  [<ffffffff8149bb3f>] ? dump_stack+0x41/0x51
[ 1380.830820]  [<ffffffff8105b772>] ? warn_slowpath_common+0x72/0x90
[ 1380.830825]  [<ffffffffa074a7bb>] ? __cfg80211_connect_result+0x3db/0x420 [cfg80211]
[ 1380.830829]  [<ffffffffa072a2dd>] ? cfg80211_process_wdev_events+0x17d/0x1b0 [cfg80211]
[ 1380.830833]  [<ffffffffa072a348>] ? cfg80211_process_rdev_events+0x38/0x90 [cfg80211]
[ 1380.830837]  [<ffffffffa0726015>] ? cfg80211_event_work+0x15/0x20 [cfg80211]
[ 1380.830840]  [<ffffffff8107492d>] ? process_one_work+0x16d/0x420
[ 1380.830842]  [<ffffffff810754f6>] ? worker_thread+0x116/0x3b0
[ 1380.830844]  [<ffffffff810753e0>] ? rescuer_thread+0x330/0x330
[ 1380.830846]  [<ffffffff8107b7c1>] ? kthread+0xc1/0xe0
[ 1380.830848]  [<ffffffff8107b700>] ? kthread_create_on_node+0x180/0x180
[ 1380.830851]  [<ffffffff814a8b4c>] ? ret_from_fork+0x7c/0xb0
[ 1380.830853]  [<ffffffff8107b700>] ? kthread_create_on_node+0x180/0x180
[ 1380.830854] ---[ end trace b7b476fc2879d48d ]---
[ 1380.830885] cfg80211: Calling CRDA to update world regulatory domain
[ 1380.834426] cfg80211: World regulatory domain updated:
[ 1380.834429] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 1380.834431] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 1380.834432] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 1380.834434] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 1380.834435] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 1380.834436] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)

Wireless is UP and working even after the crash.
Comment 14 Jakub Zeman 2014-01-15 07:49:42 UTC
Another crash which happens every time I press power button. So it probably happens when Marvel 88W8797 goes to suspend state (when I reload the driver everyching starts working well - so I needn't to reboot):

[ 1598.741828] ------------[ cut here ]------------
[ 1598.741838] WARNING: CPU: 1 PID: 2884 at fs/sysfs/group.c:214 device_del+0x3b/0x1b0()
[ 1598.741840] sysfs group ffffffff8187dfc0 not found for kobject 'hidraw6'
[ 1598.741842] Modules linked in: tun btusb mwifiex_usb mwifiex cfg80211 nf_conntrack_ipv6 nf_defrag_ipv6 ip6table_filter ip6_tables xt_tcpudp nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack parport_pc ppdev iptable_filter lp ip_tables parport x_tables bnep rfcomm bluetooth rfkill binfmt_misc uinput nfsd auth_rpcgss oid_registry nfs_acl nfs lockd fscache sunrpc nls_utf8 nls_cp437 vfat fat fuse snd_hda_codec_hdmi snd_hda_codec_realtek iTCO_wdt iTCO_vendor_support hid_multitouch hid_sensor_gyro_3d hid_sensor_accel_3d hid_sensor_magn_3d hid_sensor_als hid_sensor_trigger industrialio_triggered_buffer kfifo_buf hid_sensor_iio_common industrialio joydev hid_sensor_hub hid_generic usbhid hid uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core videodev media tpm_infineon evdev snd_hda_intel lpc_ich snd_hda_codec mfd_core snd_hwdep x86_pkg_temp_thermal intel_powerclamp snd_pcm coretemp snd_page_alloc kvm_intel kvm crc32_pclmul crc32c_intel ghash_clmulni_intel aesni_intel aes_x86_64 ablk_helper cryptd lrw gf128mul glue_helper snd_timer snd soundcore pcspkr i2c_i801 battery i915 ac video tpm_tis drm_kms_helper tpm drm i2c_algo_bit button i2c_core mei_me mei processor ext4 crc16 mbcache jbd2 sg sd_mod crc_t10dif crct10dif_common ahci libahci libata scsi_mod ehci_pci ehci_hcd thermal thermal_sys xhci_hcd usbcore usb_common
[ 1598.741929] CPU: 1 PID: 2884 Comm: upowerd Not tainted 3.13.0-rc7 #1
[ 1598.741931] Hardware name: Microsoft Corporation Surface with Windows 8 Pro/Surface with Windows 8 Pro, BIOS 1.05.0050 08/20/2013
[ 1598.741933]  0000000000000009 ffffffff8149bb3f ffff8800c8047e00 ffffffff8105b772
[ 1598.741937]  ffff880036a9f400 ffff8800c8047e50 ffff880119266070 ffff880118ed78e8
[ 1598.741941]  ffff880116764ce0 ffffffff8105b7d7 ffffffff8170cc50 ffffffff00000028
[ 1598.741944] Call Trace:
[ 1598.741951]  [<ffffffff8149bb3f>] ? dump_stack+0x41/0x51
[ 1598.741957]  [<ffffffff8105b772>] ? warn_slowpath_common+0x72/0x90
[ 1598.741961]  [<ffffffff8105b7d7>] ? warn_slowpath_fmt+0x47/0x50
[ 1598.741965]  [<ffffffff8134cd0b>] ? device_del+0x3b/0x1b0
[ 1598.741969]  [<ffffffff8134ce89>] ? device_unregister+0x9/0x30
[ 1598.741973]  [<ffffffff8134cf15>] ? device_destroy+0x35/0x40
[ 1598.741981]  [<ffffffffa04cc347>] ? drop_ref.part.2+0x57/0x70 [hid]
[ 1598.741987]  [<ffffffffa04ccc15>] ? hidraw_release+0x95/0xb0 [hid]
[ 1598.741991]  [<ffffffff811772d5>] ? __fput+0xc5/0x210
[ 1598.741996]  [<ffffffff81078b27>] ? task_work_run+0x97/0xd0
[ 1598.742000]  [<ffffffff81012969>] ? do_notify_resume+0x59/0x90
[ 1598.742005]  [<ffffffff814a8eb2>] ? int_signal+0x12/0x17
[ 1598.742007] ---[ end trace 79df656844833479 ]---
Comment 15 Bing Zhao 2014-01-15 08:25:25 UTC
comment #13 is a warning printed by cfg80211 stack. I can check more on why cfg80211 warns on that. Wi-Fi works fine in this case as you already mentioned.

comment #14 might not be 8797 related, based on the stack trace. Could you do an experiment: disable mwifiex driver in kernel .config, re-compile, and test again by pressing the power button.

After moving to 3.13-rc7 kernel, have you ever got the same problems as in comment #9 and comment #10?
Comment 16 Jakub Zeman 2014-01-15 08:34:28 UTC
> After moving to 3.13-rc7 kernel, have you ever got the same problems as in
> comment #9 and comment #10?

No it didn't happen since I upgraded to 3.13-rc7. So it seems it's fixed.
Comment 17 Jakub Zeman 2014-01-29 17:50:00 UTC
The reproduction for 3.13.0 kernel is completely different so I created new bug 69661

Note You need to log in before you can comment on or make changes to this bug.