You are not logged in.

#1 2012-11-26 09:28:51

whoops
Member
Registered: 2009-03-19
Posts: 794

freezes after resume from hibernate, wifi adapter gone after reboot

Hi!

I encountered this problem 2 times now - does not occur always.

After resume from hibernate, the following things just freeze:
- sudo [anything]
- iwconfig
- ifconfig
- rmmod
- netcfg
- strace [with most things]
- reboot (any combination of methods including command line, magic keys, strg+alt+entf,...)
- ...and more. Hardly managed to get anything done, trying to figure out what's wrong.

Sometimes it's possible to kill the processes, but mostly they just stay frozen.

journalctl says:

Nov 26 09:19:32 xarch kernel: ath: phy0: Unable to reset hardware; reset status -22 (freq 2437 MHz)
Nov 26 09:19:32 xarch kernel: ------------[ cut here ]------------
Nov 26 09:19:32 xarch kernel: WARNING: at net/mac80211/util.c:1295 ieee80211_reconfig+0x65c/0x1540 [mac80211]()
Nov 26 09:19:32 xarch kernel: Hardware name: System Product Name
Nov 26 09:19:32 xarch kernel: Hardware became unavailable upon resume. This could be a software issue prior to suspend or a ...e issue.
Nov 26 09:19:32 xarch kernel: Modules linked in: ablk_helper cryptd aes_x86_64 aes_generic hwmon_vid snd_hda_codec_hdmi edac...b_common
Nov 26 09:19:32 xarch kernel: Pid: 26203, comm: systemd-sleep Tainted: P           O 3.6.7-4-ck #1
Nov 26 09:19:32 xarch kernel: Call Trace:
Nov 26 09:19:32 xarch kernel:  [<ffffffff81055b0f>] warn_slowpath_common+0x7f/0xc0
Nov 26 09:19:32 xarch kernel:  [<ffffffff81055c06>] warn_slowpath_fmt+0x46/0x50
Nov 26 09:19:32 xarch kernel:  [<ffffffffa06d014c>] ieee80211_reconfig+0x65c/0x1540 [mac80211]
Nov 26 09:19:32 xarch kernel:  [<ffffffff8133c089>] ? input_pass_event+0x49/0xf0
Nov 26 09:19:32 xarch kernel:  [<ffffffffa06c1c7d>] ieee80211_resume+0x2d/0x70 [mac80211]
Nov 26 09:19:32 xarch kernel:  [<ffffffffa0670f41>] wiphy_resume+0xa1/0xb0 [cfg80211]
Nov 26 09:19:32 xarch kernel:  [<ffffffffa0670ea0>] ? wiphy_suspend+0x80/0x80 [cfg80211]
Nov 26 09:19:32 xarch kernel:  [<ffffffff81325d4b>] dpm_run_callback.isra.4+0x3b/0x70
Nov 26 09:19:32 xarch kernel:  [<ffffffff81326a68>] device_resume+0xa8/0x160
Nov 26 09:19:32 xarch kernel:  [<ffffffff81326f64>] dpm_resume+0x104/0x230
Nov 26 09:19:32 xarch kernel:  [<ffffffff8108f066>] hibernation_snapshot+0x146/0x350
Nov 26 09:19:32 xarch kernel:  [<ffffffff8108f97e>] hibernate+0x11e/0x1b0
Nov 26 09:19:32 xarch kernel:  [<ffffffff8108dbd3>] state_store+0x83/0xc0
Nov 26 09:19:32 xarch kernel:  [<ffffffff8124ac3f>] kobj_attr_store+0xf/0x20
Nov 26 09:19:32 xarch kernel:  [<ffffffff811d7afd>] sysfs_write_file+0xcd/0x140
Nov 26 09:19:32 xarch kernel:  [<ffffffff81168428>] vfs_write+0xa8/0x180
Nov 26 09:19:32 xarch kernel:  [<ffffffff8116876a>] sys_write+0x4a/0xa0
Nov 26 09:19:32 xarch kernel:  [<ffffffff81485bed>] system_call_fastpath+0x1a/0x1f
Nov 26 09:19:32 xarch kernel: ---[ end trace 892a6b6b0cb69aa3 ]---
Nov 26 09:19:32 xarch kernel: dpm_run_callback(): wiphy_resume+0x0/0xb0 [cfg80211] returns -22
Nov 26 09:19:32 xarch kernel: PM: Device phy0 failed to restore: error -22
Nov 26 09:19:32 xarch kernel: PM: restore of devices complete after 817.841 msecs
Nov 26 09:19:32 xarch kernel: PM: Image restored successfully.
Nov 26 09:19:32 xarch kernel: Restarting tasks ... done.
Nov 26 09:19:32 xarch kernel: PM: Basic memory bitmaps freed

...and later...

Nov 26 09:34:30 xarch dhcpcd[1080]: wlan0: releasing lease of 192.168.1.11
Nov 26 09:34:30 xarch kernel: ------------[ cut here ]------------
Nov 26 09:34:30 xarch kernel: WARNING: at net/mac80211/driver-ops.h:12 ieee80211_bss_info_change_notify+0x2fb/0x300 [mac80211]()
Nov 26 09:34:30 xarch kernel: Hardware name: System Product Name
Nov 26 09:34:30 xarch kernel: wlan0:  Failed check-sdata-in-driver check, flags: 0x4
Nov 26 09:34:30 xarch kernel: Modules linked in: ablk_helper cryptd aes_x86_64 aes_generic hwmon_vid snd_hda_codec_hdmi edac...b_common
Nov 26 09:34:30 xarch kernel: Pid: 1080, comm: dhcpcd Tainted: P        W  O 3.6.7-4-ck #1
Nov 26 09:34:30 xarch kernel: Call Trace:
Nov 26 09:34:30 xarch kernel:  [<ffffffff81055b0f>] warn_slowpath_common+0x7f/0xc0
Nov 26 09:34:30 xarch kernel:  [<ffffffff81055c06>] warn_slowpath_fmt+0x46/0x50
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06aaa6b>] ieee80211_bss_info_change_notify+0x2fb/0x300 [mac80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06aab53>] ieee80211_ifa_changed+0xe3/0x100 [mac80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffff814820dd>] notifier_call_chain+0x4d/0x70
Nov 26 09:34:30 xarch kernel:  [<ffffffff8107fe78>] __blocking_notifier_call_chain+0x58/0x80
Nov 26 09:34:30 xarch kernel:  [<ffffffff8107feb6>] blocking_notifier_call_chain+0x16/0x20
Nov 26 09:34:30 xarch kernel:  [<ffffffff813e629c>] __inet_del_ifa+0x15c/0x2b0
Nov 26 09:34:30 xarch kernel:  [<ffffffff813e71fa>] inet_rtm_deladdr+0x10a/0x160
Nov 26 09:34:30 xarch kernel:  [<ffffffff81393ae4>] rtnetlink_rcv_msg+0x114/0x300
Nov 26 09:34:30 xarch kernel:  [<ffffffff813939d0>] ? __rtnl_unlock+0x20/0x20
Nov 26 09:34:30 xarch kernel:  [<ffffffff813ac491>] netlink_rcv_skb+0xa1/0xb0
Nov 26 09:34:30 xarch kernel:  [<ffffffff813920e5>] rtnetlink_rcv+0x25/0x40
Nov 26 09:34:30 xarch kernel:  [<ffffffff813abded>] netlink_unicast+0x19d/0x220
Nov 26 09:34:30 xarch kernel:  [<ffffffff813ac23a>] netlink_sendmsg+0x30a/0x390
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136b85a>] sock_sendmsg+0xda/0xf0
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136bc41>] __sys_sendmsg+0x371/0x380
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136bf9b>] ? move_addr_to_user+0x7b/0xd0
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136de49>] sys_sendmsg+0x49/0x90
Nov 26 09:34:30 xarch kernel:  [<ffffffff81485bed>] system_call_fastpath+0x1a/0x1f
Nov 26 09:34:30 xarch kernel: ---[ end trace 892a6b6b0cb69aa4 ]---
Nov 26 09:34:30 xarch dhcpcd[1080]: wlan0: removing interface
Nov 26 09:34:30 xarch kernel: wlan0: deauthenticating from 48:5b:39:e8:54:98 by local choice (reason=3)
Nov 26 09:34:30 xarch kernel: ------------[ cut here ]------------
Nov 26 09:34:30 xarch kernel: WARNING: at net/mac80211/driver-ops.h:12 ieee80211_send_deauth_disassoc+0x24f/0x280 [mac80211]()
Nov 26 09:34:30 xarch kernel: Hardware name: System Product Name
Nov 26 09:34:30 xarch kernel: wlan0:  Failed check-sdata-in-driver check, flags: 0x4
Nov 26 09:34:30 xarch kernel: Modules linked in: ablk_helper cryptd aes_x86_64 aes_generic hwmon_vid snd_hda_codec_hdmi edac...b_common
Nov 26 09:34:30 xarch kernel: Pid: 27593, comm: ip Tainted: P        W  O 3.6.7-4-ck #1
Nov 26 09:34:30 xarch kernel: Call Trace:
Nov 26 09:34:30 xarch kernel:  [<ffffffff81055b0f>] warn_slowpath_common+0x7f/0xc0
Nov 26 09:34:30 xarch kernel:  [<ffffffff81055c06>] warn_slowpath_fmt+0x46/0x50
Nov 26 09:34:30 xarch kernel:  [<ffffffff81378be9>] ? __netdev_alloc_skb+0x99/0xe0
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06e46af>] ieee80211_send_deauth_disassoc+0x24f/0x280 [mac80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06e63c3>] ieee80211_set_disassoc+0x4c3/0x570 [mac80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06ea904>] ieee80211_mgd_deauth+0x174/0x180 [mac80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06c0a98>] ieee80211_deauth+0x18/0x20 [mac80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa06897dd>] cfg80211_mlme_down+0x7d/0xd0 [cfg80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa068cdeb>] __cfg80211_disconnect+0x1bb/0x1d0 [cfg80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffffa0670be9>] cfg80211_netdev_notifier_call+0x519/0x650 [cfg80211]
Nov 26 09:34:30 xarch kernel:  [<ffffffff813e7817>] ? inetdev_event+0x37/0x4b0
Nov 26 09:34:30 xarch kernel:  [<ffffffff81074a3f>] ? __queue_work+0xcf/0x410
Nov 26 09:34:30 xarch kernel:  [<ffffffff8144baed>] ? packet_notifier+0xbd/0x1e0
Nov 26 09:34:30 xarch kernel:  [<ffffffff814820dd>] notifier_call_chain+0x4d/0x70
Nov 26 09:34:30 xarch kernel:  [<ffffffff8107fd4e>] __raw_notifier_call_chain+0xe/0x10
Nov 26 09:34:30 xarch kernel:  [<ffffffff8107fd66>] raw_notifier_call_chain+0x16/0x20
Nov 26 09:34:30 xarch kernel:  [<ffffffff81380aa6>] call_netdevice_notifiers+0x36/0x60
Nov 26 09:34:30 xarch kernel:  [<ffffffff81380b05>] __dev_close_many+0x35/0xc0
Nov 26 09:34:30 xarch kernel:  [<ffffffff81380bbd>] __dev_close+0x2d/0x40
Nov 26 09:34:30 xarch kernel:  [<ffffffff81385b01>] __dev_change_flags+0xa1/0x180
Nov 26 09:34:30 xarch kernel:  [<ffffffff81385c98>] dev_change_flags+0x28/0x70
Nov 26 09:34:30 xarch kernel:  [<ffffffff81392b4a>] do_setlink+0x38a/0xa20
Nov 26 09:34:30 xarch kernel:  [<ffffffff81114349>] ? get_page_from_freelist+0x319/0x640
Nov 26 09:34:30 xarch kernel:  [<ffffffff81264df1>] ? nla_parse+0x31/0xe0
Nov 26 09:34:30 xarch kernel:  [<ffffffff813942ce>] rtnl_newlink+0x37e/0x560
Nov 26 09:34:30 xarch kernel:  [<ffffffff81393ae4>] rtnetlink_rcv_msg+0x114/0x300
Nov 26 09:34:30 xarch kernel:  [<ffffffff81114349>] ? get_page_from_freelist+0x319/0x640
Nov 26 09:34:30 xarch kernel:  [<ffffffff813939d0>] ? __rtnl_unlock+0x20/0x20
Nov 26 09:34:30 xarch kernel:  [<ffffffff813ac491>] netlink_rcv_skb+0xa1/0xb0
Nov 26 09:34:30 xarch kernel:  [<ffffffff813920e5>] rtnetlink_rcv+0x25/0x40
Nov 26 09:34:30 xarch kernel:  [<ffffffff813abded>] netlink_unicast+0x19d/0x220
Nov 26 09:34:30 xarch kernel:  [<ffffffff813ac23a>] netlink_sendmsg+0x30a/0x390
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136b85a>] sock_sendmsg+0xda/0xf0
Nov 26 09:34:30 xarch kernel:  [<ffffffff8110d6b7>] ? unlock_page+0x27/0x30
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136bc41>] __sys_sendmsg+0x371/0x380
Nov 26 09:34:30 xarch kernel:  [<ffffffff81136199>] ? handle_mm_fault+0x259/0x320
Nov 26 09:34:30 xarch kernel:  [<ffffffff81481dd4>] ? do_page_fault+0x2c4/0x580
Nov 26 09:34:30 xarch kernel:  [<ffffffff8113aa3c>] ? do_brk+0x1ac/0x330
Nov 26 09:34:30 xarch kernel:  [<ffffffff8136de49>] sys_sendmsg+0x49/0x90
Nov 26 09:34:30 xarch kernel:  [<ffffffff81485bed>] system_call_fastpath+0x1a/0x1f
Nov 26 09:34:30 xarch kernel: ---[ end trace 892a6b6b0cb69aa5 ]---

... and a lot more of that. FInally, this is me trying to reboot (normally first, then hitting magic keys etc):

Nov 26 09:48:11 xarch systemd-logind[982]: System is rebooting.
Nov 26 09:48:11 xarch systemd[1]: Deactivating swap /dev/sda2...
Nov 26 09:48:11 xarch systemd[1]: Deactivating swap /dev/sda2...
Nov 26 09:48:11 xarch systemd[1]: Deactivating swap /dev/sda2...
Nov 26 09:48:11 xarch systemd[1]: Stopping Sound Card.
Nov 26 09:48:11 xarch systemd[1]: Stopped target Sound Card.
Nov 26 09:48:11 xarch systemd[1]: Stopping Stop Read-Ahead Data Collection 10s After Completed Startup.
Nov 26 09:48:11 xarch systemd[1]: Stopped Stop Read-Ahead Data Collection 10s After Completed Startup.
Nov 26 09:48:11 xarch systemd[1]: Stopping Disk Manager...
Nov 26 09:48:11 xarch systemd[1]: Stopping Daemon for power management...
Nov 26 09:48:11 xarch systemd[1]: Stopping RealtimeKit Scheduling Policy Service...
Nov 26 09:48:11 xarch systemd[1]: Stopping Authorization Manager...
Nov 26 09:48:11 xarch systemd[1]: Stopping Graphical Interface.
Nov 26 09:48:11 xarch systemd[1]: Stopped target Graphical Interface.
Nov 26 09:48:11 xarch systemd[1]: Stopping Multi-User.
Nov 26 09:48:11 xarch systemd[1]: Stopped target Multi-User.
Nov 26 09:48:11 xarch systemd[1]: Stopping Adaptive readahead daemon...
Nov 26 09:48:11 xarch polkitd[3405]: Unregistered Authentication Agent for unix-process:28066:7194685 (system bus name :1.20,...om bus)
Nov 26 09:48:11 xarch acpid[977]: exiting
Nov 26 09:48:11 xarch dbus[984]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
Nov 26 09:48:11 xarch dbus-daemon[984]: dbus[984]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1'...ervice'
Nov 26 09:48:11 xarch systemd[1]: Stopping Periodic Command Scheduler...
Nov 26 09:48:11 xarch systemd[1]: Stopping Login Service...
Nov 26 09:48:11 xarch systemd[1]: Stopping D-Bus System Message Bus...
Nov 26 09:48:11 xarch systemd[1]: Stopping Arch Daemons.
Nov 26 09:48:11 xarch systemd[1]: Stopped target Arch Daemons.
Nov 26 09:48:11 xarch systemd[1]: Stopping Login Prompts.
Nov 26 09:48:11 xarch systemd[1]: Stopped target Login Prompts.
Nov 26 09:48:11 xarch systemd[1]: Stopping Getty on tty2...
Nov 26 09:48:11 xarch systemd[1]: Stopping Getty on tty1...
Nov 26 09:48:11 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:48:11 xarch systemd[1]: Stopped Periodic Command Scheduler.
Nov 26 09:48:11 xarch systemd[1]: Stopped Login Service.
Nov 26 09:48:11 xarch login[28008]: pam_unix(login:session): session closed for user asche
Nov 26 09:48:11 xarch login[991]: pam_unix(login:session): session closed for user asche
Nov 26 09:48:11 xarch login[991]: pam_systemd(login:session): Failed to connect to system bus: Did not receive a reply. Poss... broken.
Nov 26 09:48:11 xarch login[28008]: pam_systemd(login:session): Failed to connect to system bus: Did not receive a reply. Pos...broken.
Nov 26 09:48:12 xarch su[5071]: pam_unix(su:session): session closed for user root
Nov 26 09:48:12 xarch su[6804]: pam_unix(su:session): session closed for user root
Nov 26 09:48:12 xarch su[10290]: pam_unix(su:session): session closed for user root
Nov 26 09:48:12 xarch su[14288]: pam_unix(su:session): session closed for user root
Nov 26 09:48:12 xarch su[24626]: pam_unix(su:session): session closed for user root
Nov 26 09:48:12 xarch systemd[1]: Stopped Adaptive readahead daemon.
Nov 26 09:48:12 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:48:15 xarch systemd[1]: Stopped Permit User Sessions.
Nov 26 09:48:15 xarch systemd[1]: Stopping Remote File Systems.
Nov 26 09:48:15 xarch systemd[1]: Stopped target Remote File Systems.
Nov 26 09:49:41 xarch systemd[1]: ntpd.service stopping timed out. Killing.
Nov 26 09:51:11 xarch systemd[1]: ntpd.service still around after SIGKILL. Ignoring.
Nov 26 09:51:38 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:38 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:51:39 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:39 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:51:39 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:39 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:51:40 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:40 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:51:40 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:40 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:51:40 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:40 xarch systemd[1]: Started Store Sound Card State.
Nov 26 09:51:40 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:40 xarch systemd[1]: alsa-store.service start request repeated too quickly, refusing to start.
Nov 26 09:51:40 xarch systemd[1]: Failed to start Store Sound Card State.
Nov 26 09:51:40 xarch systemd[1]: Unit alsa-store.service entered failed state
Nov 26 09:51:40 xarch systemd[1]: Starting Store Sound Card State...
Nov 26 09:51:40 xarch systemd[1]: alsa-store.service start request repeated too quickly, refusing to start.
Nov 26 09:51:40 xarch systemd[1]: Failed to start Store Sound Card State.

After reboot, my pci wifi adapter does not work any more. It is here:

04:07.0 Network controller: Atheros Communications Inc. Device 0003 (rev 01)
	Subsystem: Atheros Communications Inc. Device 3051
	Flags: medium devsel
	Memory at feb00000 (32-bit, non-prefetchable) [disabled] [size=64K]
	Capabilities: [40] #80 [0000]
	Capabilities: [80] #00 [0000]

... but does not show up in iwconfig or anywhere (would probably fix itsself with another reboot, but still trying to figure out stuff).


Any hints?

Last edited by whoops (2012-11-26 09:29:27)

Offline

#2 2012-12-07 09:16:27

whoops
Member
Registered: 2009-03-19
Posts: 794

Re: freezes after resume from hibernate, wifi adapter gone after reboot

This morning, I rebooted for half an hour without success:

After resume from hibernate, most of my machine froze like described above.
After first reboot, it worked for 1 minute, then I had a full freeze (nothing worked... no mouse, no normal keys, no magic keys, screen frozen...)
After second reboot, my wifi adapter was gone. It showed up in lspci and looked like this:

04:07.0 Network controller: Atheros Communications Inc. AR5416 Wireless Network Adapter [AR5008 802.11(a)bgn] (rev 01)
	Subsystem: Atheros Communications Inc. Device 3071
	Flags: 66MHz, medium devsel, IRQ 22
	Memory at feb70000 (32-bit, non-prefetchable) [size=64K]
	Capabilities: [40] #80 [0000]

...but not in ifconfig. I found the following in dmesg:

[    7.106070] ath: phy0: address test failed addr: 0x00008000 - wr:0x00080008 != rd:0x00000008
[    7.106181] ath: phy0: Unable to initialize hardware; initialization status: -19
[    7.106287] ath9k 0000:04:07.0: Failed to initialize device

Problem persisted over 10 poweroffs/reboots (also hit the "on" button a few times with the pwoer cable disconnected, trying to make it "reset" or something. Did not help this time, might have been coincidence the last time that worked.

Offline

Board footer

Powered by FluxBB