eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [199931.773963] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [199931.774264] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [199933.593170] e1000e: eth0 NIC Link is Down [199941.905025] eth0: no IPv6 routers present [199945.877284] iwlagn 0000:03:00.0: RF_KILL bit toggled to disable radio. [199945.954230] usb 3-1: USB disconnect, address 23 [199945.955084] btusb_intr_complete: hci0 urb f2595d80 failed to resubmit (19) [199945.955096] btusb_bulk_complete: hci0 urb f2595900 failed to resubmit (19) [199945.956088] btusb_bulk_complete: hci0 urb f2595f80 failed to resubmit (19) [199945.956312] btusb_send_frame: hci0 urb cbcd8900 submission failed [199949.864670] iwlagn 0000:03:00.0: RF_KILL bit toggled to enable radio. [199950.132849] Registered led device: iwl-phy0::radio [199950.134654] Registered led device: iwl-phy0::assoc [199950.136385] Registered led device: iwl-phy0::RX [199950.138051] Registered led device: iwl-phy0::TX [199950.182049] ADDRCONF(NETDEV_UP): wlan0: link is not ready [199950.433063] usb 3-1: new full speed USB device using uhci_hcd and address 24 [199950.657281] usb 3-1: configuration #1 chosen from 1 choice [199959.419222] iwlagn 0000:03:00.0: RF_KILL bit toggled to disable radio. [199959.594249] usb 3-1: USB disconnect, address 24 [199966.914512] iwlagn 0000:03:00.0: RF_KILL bit toggled to enable radio. [199967.218203] Registered led device: iwl-phy0::radio [199967.218226] Registered led device: iwl-phy0::assoc [199967.218246] Registered led device: iwl-phy0::RX [199967.218265] Registered led device: iwl-phy0::TX [199967.261448] ADDRCONF(NETDEV_UP): wlan0: link is not ready [199967.484087] usb 3-1: new full speed USB device using uhci_hcd and address 25 [199967.657222] usb 3-1: configuration #1 chosen from 1 choice [199997.270021] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [199997.270026] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [200026.484416] wlan0: deauthenticating from 00:13:f7:f0:9b:8a by local choice (reason=3) [200026.533292] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 1) [200026.736029] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 2) [200026.935338] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 3) [200027.133054] wlan0: direct probe to AP 00:13:f7:f0:9b:8a timed out [200073.998565] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 1) [200074.196060] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 2) [200074.397061] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 3) [200074.597060] wlan0: direct probe to AP 00:13:f7:f0:9b:8a timed out [200139.014349] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200139.212032] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [200139.414761] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 3) [200139.417017] wlan0: direct probe responded [200139.417020] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200139.418817] wlan0: authenticated [200139.418837] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200139.422493] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x401 status=0 aid=1) [200139.422496] wlan0: associated [200139.443366] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [200145.333170] e1000e: eth0 NIC Link is Down [200150.072019] wlan0: no IPv6 routers present [200197.668851] wlan0: deauthenticated from 00:18:39:54:e1:69 (Reason: 7) [200199.572181] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200199.574436] wlan0: direct probe responded [200199.574441] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200199.576306] wlan0: authenticated [200199.576333] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200199.578586] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x401 status=0 aid=1) [200199.578589] wlan0: associated [200219.280087] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200223.868611] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200223.907969] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200223.910363] wlan0: direct probe responded [200223.910371] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200223.915722] wlan0: authenticated [200223.915765] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200223.918044] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x401 status=0 aid=1) [200223.918052] wlan0: associated [200245.697116] wlan0: deauthenticated from 00:18:39:54:e1:69 (Reason: 7) [200247.577920] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200247.776054] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [200247.779361] wlan0: direct probe responded [200247.779366] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200247.781221] wlan0: authenticated [200247.781241] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200247.783466] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x421 status=0 aid=1) [200247.783470] wlan0: associated [200268.296092] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200272.119140] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200272.157532] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200272.356111] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [200272.358628] wlan0: direct probe responded [200272.358633] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200272.360405] wlan0: authenticated [200272.360428] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200272.362862] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=1) [200272.362865] wlan0: associated [200276.363536] wlan0: deauthenticated from 00:18:39:54:e1:69 (Reason: 15) [200276.490433] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200276.492830] wlan0: direct probe responded [200276.492835] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200276.494750] wlan0: authenticated [200276.494773] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200276.497075] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=1) [200276.497079] wlan0: associated [200277.700062] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200348.787655] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [200348.841823] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [200348.842021] ADDRCONF(NETDEV_UP): eth0: link is not ready [200349.062814] Registered led device: iwl-phy0::radio [200349.063250] Registered led device: iwl-phy0::assoc [200349.063530] Registered led device: iwl-phy0::RX [200349.063810] Registered led device: iwl-phy0::TX [200349.109856] ADDRCONF(NETDEV_UP): wlan0: link is not ready [200350.404949] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [200350.404956] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [200350.405187] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [200355.947533] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200355.965978] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200356.164069] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [200356.170485] wlan0: direct probe responded [200356.170495] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200356.172376] wlan0: authenticated [200356.172422] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200356.174838] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=1) [200356.174846] wlan0: associated [200356.195551] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [200360.909012] eth0: no IPv6 routers present [200366.444032] wlan0: no IPv6 routers present [200406.657154] e1000e: eth0 NIC Link is Down [200422.231573] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [200422.231577] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [200496.501073] No probe response from AP 00:18:39:54:e1:69 after 500ms, disconnecting. [200498.383434] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200498.388728] wlan0: direct probe responded [200498.388732] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200498.390530] wlan0: authenticated [200498.390549] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200498.394094] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=2) [200498.394098] wlan0: associated [200505.504094] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200505.546390] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200505.585464] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200505.589808] wlan0: direct probe responded [200505.589813] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200505.591637] wlan0: authenticated [200505.591658] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200505.594175] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=2) [200505.594178] wlan0: associated [200533.796035] usb 4-1: new full speed USB device using uhci_hcd and address 7 [200533.967285] usb 4-1: configuration #1 chosen from 2 choices [200533.981767] eth1: register 'cdc_ether' at usb-0000:00:1a.1-1, CDC Ethernet Device, 00:25:2e:26:75:87 [200535.344318] e1000e: eth0 NIC Link is Down [200544.020029] eth1: no IPv6 routers present [200612.500069] No probe response from AP 00:18:39:54:e1:69 after 500ms, disconnecting. [200614.415572] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200614.418008] wlan0: direct probe responded [200614.418011] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200614.426429] wlan0: authenticated [200614.426451] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200614.431632] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=2) [200614.431636] wlan0: associated [200631.789397] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200639.449440] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200639.491807] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200639.494556] wlan0: direct probe responded [200639.494561] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200639.496752] wlan0: authenticated [200639.496777] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200639.499228] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x431 status=0 aid=2) [200639.499232] wlan0: associated [200713.645120] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200721.256122] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200721.297390] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200721.496062] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [200721.498613] wlan0: direct probe responded [200721.498619] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200721.500394] wlan0: authenticated [200721.500427] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200721.502865] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [200721.502870] wlan0: associated [200751.745084] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200757.022623] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [200757.062626] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [200757.067108] wlan0: direct probe responded [200757.067114] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [200757.068997] wlan0: authenticated [200757.069034] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [200757.072505] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [200757.072509] wlan0: associated [201083.233175] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [201087.141417] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [201087.184898] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [201087.187355] wlan0: direct probe responded [201087.187360] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [201087.190141] wlan0: authenticated [201087.190161] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [201087.192596] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [201087.192599] wlan0: associated [201198.501035] No probe response from AP 00:18:39:54:e1:69 after 500ms, disconnecting. [201200.433137] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [201200.632028] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [201200.832648] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 3) [201201.032107] wlan0: direct probe to AP 00:18:39:54:e1:69 timed out [201212.269263] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [201212.272052] wlan0: direct probe responded [201212.272055] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [201212.273889] wlan0: authenticated [201212.273913] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [201212.276252] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=2) [201212.276255] wlan0: associated [201350.309087] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [201354.773611] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [201354.812923] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [201354.815395] wlan0: direct probe responded [201354.815399] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [201354.819122] wlan0: authenticated [201354.819148] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [201354.822337] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=2) [201354.822341] wlan0: associated [201397.172144] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [201443.397268] wlan0: deauthenticating from 00:13:f7:f0:9b:8a by local choice (reason=3) [201443.454981] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 1) [201443.652073] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 2) [201443.852067] wlan0: direct probe to AP 00:13:f7:f0:9b:8a (try 3) [201444.053044] wlan0: direct probe to AP 00:13:f7:f0:9b:8a timed out [201457.095698] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [201457.098242] wlan0: direct probe responded [201457.098247] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [201457.100144] wlan0: authenticated [201457.100196] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [201457.102626] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=2) [201457.102629] wlan0: associated [201688.488055] No probe response from AP 00:18:39:54:e1:69 after 500ms, disconnecting. [201926.097934] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [201926.097938] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [202156.487704] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [202156.491892] wlan0: direct probe responded [202156.491900] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [202156.493734] wlan0: authenticated [202156.493778] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [202156.496202] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [202156.496210] wlan0: associated [202217.905192] e1000e: eth0 NIC Link is Down [202576.100991] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [202576.101001] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [202632.240756] wlan0: deauthenticated from 00:18:39:54:e1:69 (Reason: 7) [202634.154830] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [202634.170234] wlan0: direct probe responded [202634.170240] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [202634.173792] wlan0: authenticated [202634.173818] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [202634.176146] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [202634.176150] wlan0: associated [202679.501072] No probe response from AP 00:18:39:54:e1:69 after 500ms, disconnecting. [202681.508870] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [202681.511320] wlan0: direct probe responded [202681.511323] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [202681.513247] wlan0: authenticated [202681.513279] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [202681.516318] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [202681.516324] wlan0: associated [202809.501121] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [202814.375889] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [202814.415953] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [202814.418510] wlan0: direct probe responded [202814.418516] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [202814.420369] wlan0: authenticated [202814.420389] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [202814.422849] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [202814.422852] wlan0: associated [202948.373130] e1000e: eth0 NIC Link is Down [202952.500064] No probe response from AP 00:18:39:54:e1:69 after 500ms, disconnecting. [202963.589924] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX [202963.589928] 0000:00:19.0: eth0: 10/100 speed: disabling TSO [202974.812420] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [202975.014760] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [202975.019056] wlan0: direct probe responded [202975.019061] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [202975.024226] wlan0: authenticated [202975.024656] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [202975.027359] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [202975.027363] wlan0: associated [203005.137144] e1000e: eth0 NIC Link is Down [203156.561168] usb 4-1: USB disconnect, address 7 [203156.562937] eth1: unregister 'cdc_ether' usb-0000:00:1a.1-1, CDC Ethernet Device [204068.592094] usb 4-1: new full speed USB device using uhci_hcd and address 8 [204068.763549] usb 4-1: configuration #1 chosen from 2 choices [204068.776995] eth1: register 'cdc_ether' at usb-0000:00:1a.1-1, CDC Ethernet Device, 00:25:2e:26:75:87 [204079.017443] eth1: no IPv6 routers present [204277.768091] usb 4-1: USB disconnect, address 8 [204277.769674] eth1: unregister 'cdc_ether' usb-0000:00:1a.1-1, CDC Ethernet Device [205834.601323] wlan0: deauthenticated from 00:18:39:54:e1:69 (Reason: 7) [205836.515507] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [205836.519900] wlan0: direct probe responded [205836.519910] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [205836.526410] wlan0: authenticated [205836.526465] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [205836.533555] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [205836.533559] wlan0: associated [205956.420172] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [205960.294101] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [205960.335447] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [205960.338232] wlan0: direct probe responded [205960.338236] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [205960.340108] wlan0: authenticated [205960.340126] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [205960.342534] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [205960.342537] wlan0: associated [206072.661311] wlan0: deauthenticated from 00:18:39:54:e1:69 (Reason: 7) [206074.601603] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [206074.605315] wlan0: direct probe responded [206074.605319] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [206074.608623] wlan0: authenticated [206074.608646] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [206074.614195] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [206074.614199] wlan0: associated [215870.281075] usb 3-1: USB disconnect, address 25 [215870.609231] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [215875.166660] PM: Syncing filesystems ... done. [215875.311351] PM: Preparing system for mem sleep [215875.311357] Freezing user space processes ... (elapsed 0.00 seconds) done. [215875.312684] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [215875.312745] PM: Entering mem sleep [215875.312759] Suspending console(s) (use no_console_suspend to debug) [215875.537586] PM: suspend of drv:psmouse dev:serio2 complete after 224.317 msecs [215875.584180] sd 2:0:0:0: [sda] Synchronizing SCSI cache [215875.584263] sd 2:0:0:0: [sda] Stopping disk [215878.147672] PM: suspend of drv:sd dev:2:0:0:0 complete after 2563.494 msecs [215878.539999] PM: suspend of drv:psmouse dev:serio1 complete after 379.888 msecs [215879.144085] PM: suspend of drv:atkbd dev:serio0 complete after 604.038 msecs [215879.193385] ACPI handle has no context! [215879.193397] sdhci-pci 0000:15:00.2: PCI INT C disabled [215879.193408] ACPI handle has no context! [215879.213188] ACPI handle has no context! [215879.356313] ata_piix 0000:00:1f.1: PCI INT C disabled [215879.356339] ehci_hcd 0000:00:1d.7: PCI INT D disabled [215879.356358] uhci_hcd 0000:00:1d.2: PCI INT C disabled [215879.356375] uhci_hcd 0000:00:1d.1: PCI INT B disabled [215879.356391] uhci_hcd 0000:00:1d.0: PCI INT A disabled [215879.356404] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [215879.460349] HDA Intel 0000:00:1b.0: PCI INT B disabled [215879.476083] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.656 msecs [215879.476101] ehci_hcd 0000:00:1a.7: PCI INT C disabled [215879.476116] uhci_hcd 0000:00:1a.1: PCI INT B disabled [215879.476135] uhci_hcd 0000:00:1a.0: PCI INT A disabled [215879.477737] e1000e 0000:00:19.0: PCI INT A disabled [215879.477746] e1000e 0000:00:19.0: PME# enabled [215879.477753] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [215879.492386] PM: suspend of devices complete after 4179.237 msecs [215879.492389] PM: suspend devices took 4.180 seconds [215879.492876] ricoh-mmc: Suspending. [215879.492905] ricoh-mmc: Controller is now re-enabled. [215879.516080] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [215879.524067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [215879.532066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [215879.556069] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [215879.585066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [215879.585257] PM: late suspend of devices complete after 92.862 msecs [215879.625063] ACPI: Preparing to enter system sleep state S3 [215879.765241] Disabling non-boot CPUs ... [215879.765256] CPU0 attaching NULL sched-domain. [215879.765259] CPU1 attaching NULL sched-domain. [215879.813022] CPU0 attaching NULL sched-domain. [215879.916045] CPU 1 is now offline [215879.916048] SMP alternatives: switching to UP code [215879.922245] Extended CMOS year: 2000 [215879.922245] Back to C! [215879.922245] CPU0: Thermal monitoring enabled (TM2) [215879.922245] Extended CMOS year: 2000 [215879.922245] Enabling non-boot CPUs ... [215879.922245] SMP alternatives: switching to SMP code [215879.928146] Booting processor 1 APIC 0x1 ip 0x6000 [215879.922245] Initializing CPU#1 [215879.922245] CPU: L1 I cache: 32K, L1 D cache: 32K [215879.922245] CPU: L2 cache: 4096K [215879.922245] CPU: Physical Processor ID: 0 [215879.922245] CPU: Processor Core ID: 1 [215879.922245] CPU1: Thermal monitoring enabled (TM2) [215880.016126] CPU1: Intel(R) Core(TM)2 Duo CPU T7300 @ 2.00GHz stepping 0a [215880.016200] CPU0 attaching NULL sched-domain. [215880.044019] CPU0 attaching sched-domain: [215880.044022] domain 0: span 0-1 level MC [215880.044025] groups: 0 1 [215880.044029] CPU1 attaching sched-domain: [215880.044031] domain 0: span 0-1 level MC [215880.044033] groups: 1 0 [215880.060181] CPU1 is up [215880.061402] ACPI: Waking up from system sleep state S3 [215880.449249] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [215880.449412] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [215880.493062] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [215880.501069] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [215880.501117] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [215880.509068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [215880.517073] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [215880.517137] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [215880.525072] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [215880.533070] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [215880.533153] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [215880.533262] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [215880.533397] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [215880.533535] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [215880.533670] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [215880.533818] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [215880.541070] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [215880.549069] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [215880.549113] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [215880.557066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [215880.565067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [215880.565127] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [215880.573070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [215880.581068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [215880.581119] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [215880.589073] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [215880.597072] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [215880.597142] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [215880.605068] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [215880.613067] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [215880.613134] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [215880.613287] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [215880.613379] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [215880.613540] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [215880.613547] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007) [215880.613621] pci 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [215880.613634] pci 0000:02:00.0: restoring config space at offset 0xc (was 0x1, writing 0x0) [215880.613656] pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) [215880.613666] pci 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xdf2ffc00) [215880.613674] pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [215880.613684] pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [215880.613824] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [215880.628083] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) [215880.628098] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [215880.644077] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [215880.644089] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [215880.644184] ricoh-mmc: Resuming. [215880.644205] ricoh-mmc: Controller is now disabled. [215880.644692] PM: early resume of devices complete after 195.585 msecs [215880.693363] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [215880.693373] e1000e 0000:00:19.0: setting latency timer to 64 [215880.693382] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [215880.693389] e1000e 0000:00:19.0: PME# disabled [215880.693470] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [215881.006163] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 312.827 msecs [215881.006176] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [215881.006185] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [215881.006215] usb usb3: root hub lost power or was reset [215881.006484] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [215881.006730] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [215881.006740] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [215881.006752] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [215881.006786] usb usb4: root hub lost power or was reset [215881.007016] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [215881.007238] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [215881.007252] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [215881.007268] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [215881.007293] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [215881.007306] HDA Intel 0000:00:1b.0: setting latency timer to 64 [215881.007367] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [215881.007609] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [215881.007877] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [215881.007886] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [215881.007898] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [215881.007935] usb usb5: root hub lost power or was reset [215881.007966] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [215881.007979] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [215881.008014] usb usb6: root hub lost power or was reset [215881.008334] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [215881.008558] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [215881.008569] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [215881.008580] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [215881.008617] usb usb7: root hub lost power or was reset [215881.008862] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [215881.009074] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [215881.009085] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [215881.009098] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [215881.009132] pci 0000:00:1e.0: setting latency timer to 64 [215881.009148] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [215881.009156] ata_piix 0000:00:1f.1: setting latency timer to 64 [215881.009186] ahci 0000:00:1f.2: setting latency timer to 64 [215881.009560] ata2: port disabled. ignoring. [215881.173507] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [215881.173511] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [215881.174561] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [215881.175300] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [215881.196492] ata1.00: configured for UDMA/33 [215881.329131] ata5: SATA link down (SStatus 0 SControl 300) [215882.182916] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1173.629 msecs [215882.320149] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.040 msecs [215882.377186] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [215882.383200] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [215882.383203] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [215882.565172] PM: resume of drv:usb dev:usb1 complete after 148.285 msecs [215882.696148] PM: resume of drv:usb dev:usb2 complete after 130.957 msecs [215882.808158] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [215882.950899] PM: resume of drv:usb dev:2-5 complete after 252.352 msecs [215882.950951] sd 2:0:0:0: [sda] Starting disk [215883.345154] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [215883.346952] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [215883.346958] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [215883.347076] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [215883.347082] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [215883.349491] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [215883.349497] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [215883.349623] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [215883.349628] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [215883.350647] ata3.00: configured for UDMA/100 [215883.382708] PM: resume of drv:sd dev:2:0:0:0 complete after 431.758 msecs [215883.384168] thinkpad_acpi: ACPI backlight control delay disabled [215883.387072] PM: resume of devices complete after 2742.338 msecs [215883.387268] PM: resume devices took 2.740 seconds [215883.387295] PM: Finishing wakeup. [215883.387297] Restarting tasks ... done. [215885.149092] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [215885.204295] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [215885.204520] ADDRCONF(NETDEV_UP): eth0: link is not ready [215886.778612] Registered led device: iwl-phy0::radio [215886.778637] Registered led device: iwl-phy0::assoc [215886.778656] Registered led device: iwl-phy0::RX [215886.778677] Registered led device: iwl-phy0::TX [215887.223528] ADDRCONF(NETDEV_UP): wlan0: link is not ready [215887.921141] usb 3-1: new full speed USB device using uhci_hcd and address 26 [215888.096230] usb 3-1: configuration #1 chosen from 1 choice [215888.931233] wlan0: deauthenticating from 00:24:6c:32:b5:92 by local choice (reason=3) [215888.972209] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [215889.172046] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 2) [215889.174534] wlan0: direct probe responded [215889.174537] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [215889.178108] wlan0: authenticated [215889.178132] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [215889.195561] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [215889.195564] wlan0: associated [215889.216150] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [215899.852036] wlan0: no IPv6 routers present [216791.857456] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [216791.871911] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [216791.878595] wlan0: direct probe to AP 00:1a:1e:d5:b4:b2 (try 1) [216791.882326] wlan0: direct probe responded [216791.882337] wlan0: authenticate with AP 00:1a:1e:d5:b4:b2 (try 1) [216791.886811] wlan0: authenticated [216791.886857] wlan0: associate with AP 00:1a:1e:d5:b4:b2 (try 1) [216791.898495] wlan0: RX AssocResp from 00:1a:1e:d5:b4:b2 (capab=0x431 status=0 aid=4) [216791.898499] wlan0: associated [216912.224279] wlan0: deauthenticating from 00:1a:1e:d5:b4:b2 by local choice (reason=3) [216912.238551] wlan0: deauthenticating from 00:1a:1e:d5:b4:b2 by local choice (reason=3) [216912.249051] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [216912.251530] wlan0: direct probe responded [216912.251538] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [216912.255576] wlan0: authenticated [216912.264409] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [216912.276109] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [216912.276117] wlan0: associated [217031.676219] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [217031.687225] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [217031.697426] wlan0: direct probe to AP 00:1a:1e:d5:b4:b2 (try 1) [217031.699733] wlan0: direct probe responded [217031.699742] wlan0: authenticate with AP 00:1a:1e:d5:b4:b2 (try 1) [217031.725092] wlan0: authenticated [217031.725115] wlan0: associate with AP 00:1a:1e:d5:b4:b2 (try 1) [217031.734784] wlan0: RX AssocResp from 00:1a:1e:d5:b4:b2 (capab=0x431 status=0 aid=2) [217031.734787] wlan0: associated [217152.545215] wlan0: deauthenticating from 00:1a:1e:d5:b4:b2 by local choice (reason=3) [217152.558840] wlan0: deauthenticating from 00:1a:1e:d5:b4:b2 by local choice (reason=3) [217152.566325] wlan0: direct probe to AP 00:24:6c:32:b5:9a (try 1) [217152.764097] wlan0: direct probe to AP 00:24:6c:32:b5:9a (try 2) [217152.964073] wlan0: direct probe to AP 00:24:6c:32:b5:9a (try 3) [217153.165021] wlan0: direct probe to AP 00:24:6c:32:b5:9a timed out [217164.198622] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [217164.201037] wlan0: direct probe responded [217164.201042] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [217164.206336] wlan0: authenticated [217164.206381] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [217164.217272] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [217164.217280] wlan0: associated [217391.705250] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [217391.719256] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [217391.723962] wlan0: direct probe to AP 00:24:6c:32:b5:9a (try 1) [217391.921104] wlan0: direct probe to AP 00:24:6c:32:b5:9a (try 2) [217392.120069] wlan0: direct probe to AP 00:24:6c:32:b5:9a (try 3) [217392.320078] wlan0: direct probe to AP 00:24:6c:32:b5:9a timed out [217403.464262] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [217403.472549] wlan0: direct probe responded [217403.472554] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [217403.673073] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 2) [217403.682240] wlan0: authenticated [217403.682315] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [217403.699251] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [217403.699260] wlan0: associated [218471.696123] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [218471.708078] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [218471.714192] wlan0: direct probe to AP 00:24:6c:12:88:02 (try 1) [218471.912068] wlan0: direct probe to AP 00:24:6c:12:88:02 (try 2) [218472.112078] wlan0: direct probe to AP 00:24:6c:12:88:02 (try 3) [218472.312081] wlan0: direct probe to AP 00:24:6c:12:88:02 timed out [218483.438858] wlan0: deauthenticating from 00:24:6c:12:88:02 by local choice (reason=3) [218483.448424] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [218483.452070] wlan0: direct probe responded [218483.452078] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [218483.649098] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 2) [218483.693540] wlan0: authenticated [218483.693591] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [218483.729337] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [218483.729341] wlan0: associated [218711.704213] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [218711.717930] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [218711.723899] wlan0: direct probe to AP 00:24:6c:12:88:02 (try 1) [218711.921108] wlan0: direct probe to AP 00:24:6c:12:88:02 (try 2) [218711.924232] wlan0: direct probe responded [218711.924241] wlan0: authenticate with AP 00:24:6c:12:88:02 (try 1) [218711.932946] wlan0: authenticated [218711.933025] wlan0: associate with AP 00:24:6c:12:88:02 (try 1) [218711.939134] wlan0: RX AssocResp from 00:24:6c:12:88:02 (capab=0x31 status=0 aid=18) [218711.939141] wlan0: associated [218799.797296] iwlagn 0000:03:00.0: iwl_tx_agg_start on ra = 00:24:6c:12:88:02 tid = 0 [218831.227057] cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0 [218832.257214] wlan0: deauthenticating from 00:24:6c:12:88:02 by local choice (reason=3) [218832.271259] wlan0: deauthenticating from 00:24:6c:12:88:02 by local choice (reason=3) [218832.278261] wlan0: direct probe to AP 00:24:6c:12:88:0a (try 1) [218832.477107] wlan0: direct probe to AP 00:24:6c:12:88:0a (try 2) [218832.676683] wlan0: direct probe to AP 00:24:6c:12:88:0a (try 3) [218832.876062] wlan0: direct probe to AP 00:24:6c:12:88:0a timed out [218843.984605] wlan0: deauthenticating from 00:24:6c:12:88:0a by local choice (reason=3) [218843.992258] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [218844.193124] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 2) [218844.393104] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 3) [218844.395676] wlan0: direct probe responded [218844.395684] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [218844.403934] wlan0: authenticated [218844.403988] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [218844.429464] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [218844.429473] wlan0: associated [219311.648455] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [219311.662275] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [219311.662302] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [219311.669519] wlan0: direct probe to AP 00:24:6c:12:88:0a (try 1) [219311.872040] wlan0: direct probe to AP 00:24:6c:12:88:0a (try 2) [219312.069877] wlan0: direct probe to AP 00:24:6c:12:88:0a (try 3) [219312.268066] wlan0: direct probe to AP 00:24:6c:12:88:0a timed out [219323.309803] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [219323.508133] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 2) [219323.510371] wlan0: direct probe responded [219323.510378] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [219323.708059] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 2) [219323.712358] wlan0: authenticated [219323.712430] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [219323.734957] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [219323.734974] wlan0: associated [223391.872311] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [223391.886428] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [223391.894066] wlan0: direct probe to AP 00:1a:1e:d5:b4:b2 (try 1) [223391.896963] wlan0: direct probe responded [223391.896971] wlan0: authenticate with AP 00:1a:1e:d5:b4:b2 (try 1) [223391.900466] wlan0: authenticated [223391.928334] wlan0: associate with AP 00:1a:1e:d5:b4:b2 (try 1) [223391.936389] wlan0: RX AssocResp from 00:1a:1e:d5:b4:b2 (capab=0x431 status=0 aid=8) [223391.936393] wlan0: associated [223512.396274] wlan0: deauthenticating from 00:1a:1e:d5:b4:b2 by local choice (reason=3) [223512.409981] wlan0: deauthenticating from 00:1a:1e:d5:b4:b2 by local choice (reason=3) [223512.418368] wlan0: direct probe to AP 00:1a:1e:c5:c3:52 (try 1) [223512.421448] wlan0: direct probe responded [223512.421456] wlan0: authenticate with AP 00:1a:1e:c5:c3:52 (try 1) [223512.432566] wlan0: authenticated [223512.448181] wlan0: associate with AP 00:1a:1e:c5:c3:52 (try 1) [223512.498456] wlan0: RX AssocResp from 00:1a:1e:c5:c3:52 (capab=0x431 status=0 aid=6) [223512.498462] wlan0: associated [225286.345117] usb 3-1: USB disconnect, address 26 [225288.957152] wlan0: deauthenticating from 00:1a:1e:c5:c3:52 by local choice (reason=3) [225289.112887] ------------[ cut here ]------------ [225289.112940] WARNING: at /build/buildd/linux-2.6.32/net/wireless/core.c:614 wdev_cleanup_work+0xa7/0xd0 [cfg80211]() [225289.112947] Hardware name: 7663AQ2 [225289.112951] Modules linked in: cdc_ether usbnet mii snd_usb_audio snd_usb_lib nls_iso8859_1 nls_cp437 vfat fat usb_storage usblp aes_i586 aes_generic binfmt_misc rfcomm sco bridge stp ppdev bnep l2cap pci_stub vboxpci vboxnetadp vboxnetflt vboxdrv nf_nat_ftp nf_conntrack_ftp nf_nat_irc nf_conntrack_irc xt_state ipt_LOG xt_limit ipt_REDIRECT iptable_nat nf_nat xt_tcpudp xt_owner nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 iptable_filter ip_tables x_tables snd_hda_codec_analog thinkpad_acpi snd_seq_dummy fbcon snd_hda_intel snd_seq_oss tileblit arc4 snd_seq_midi snd_hda_codec snd_rawmidi joydev font snd_hwdep pcmcia snd_seq_midi_event nvidia(P) bitblit softcursor snd_pcm_oss snd_seq snd_mixer_oss snd_pcm iwlagn snd_seq_device snd_timer yenta_socket sdhci_pci iwlcore ricoh_mmc psmouse snd_page_alloc vga16fb uvcvideo rsrc_nonstatic sdhci snd mac80211 soundcore btusb led_class pcmcia_core video serio_raw bluetooth intel_agp vgastate videodev output lp parport cfg80211 v4l1_compat nvram tpm_tis tpm tpm_bios agpgart ohci1394 ieee1394 ahci e1000e [225289.113162] Pid: 9, comm: events/0 Tainted: P W 2.6.32-32-generic #62-Ubuntu [225289.113168] Call Trace: [225289.113193] [] warn_slowpath_common+0x72/0xa0 [225289.113222] [] ? wdev_cleanup_work+0xa7/0xd0 [cfg80211] [225289.113251] [] ? wdev_cleanup_work+0xa7/0xd0 [cfg80211] [225289.113261] [] warn_slowpath_null+0x1a/0x20 [225289.113290] [] wdev_cleanup_work+0xa7/0xd0 [cfg80211] [225289.113301] [] run_workqueue+0x8e/0x150 [225289.113331] [] ? wdev_cleanup_work+0x0/0xd0 [cfg80211] [225289.113341] [] worker_thread+0x84/0xe0 [225289.113352] [] ? autoremove_wake_function+0x0/0x50 [225289.113361] [] ? worker_thread+0x0/0xe0 [225289.113369] [] kthread+0x74/0x80 [225289.113377] [] ? kthread+0x0/0x80 [225289.113386] [] kernel_thread_helper+0x7/0x10 [225289.113392] ---[ end trace 1f09cf51c19c91a7 ]--- [225297.969480] PM: Syncing filesystems ... done. [225298.527550] PM: Preparing system for mem sleep [225298.527562] Freezing user space processes ... (elapsed 0.07 seconds) done. [225298.605962] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [225298.606081] PM: Entering mem sleep [225298.606106] Suspending console(s) (use no_console_suspend to debug) [225298.839575] PM: suspend of drv:psmouse dev:serio2 complete after 232.467 msecs [225298.884579] sd 2:0:0:0: [sda] Synchronizing SCSI cache [225298.886302] sd 2:0:0:0: [sda] Stopping disk [225301.196498] PM: suspend of drv:sd dev:2:0:0:0 complete after 2311.923 msecs [225301.594231] PM: suspend of drv:psmouse dev:serio1 complete after 381.851 msecs [225302.200288] PM: suspend of drv:atkbd dev:serio0 complete after 605.981 msecs [225302.249724] ACPI handle has no context! [225302.249746] sdhci-pci 0000:15:00.2: PCI INT C disabled [225302.249763] ACPI handle has no context! [225302.269278] ACPI handle has no context! [225302.440480] ata_piix 0000:00:1f.1: PCI INT C disabled [225302.440523] ehci_hcd 0000:00:1d.7: PCI INT D disabled [225302.440551] uhci_hcd 0000:00:1d.2: PCI INT C disabled [225302.440573] uhci_hcd 0000:00:1d.1: PCI INT B disabled [225302.440597] uhci_hcd 0000:00:1d.0: PCI INT A disabled [225302.440620] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [225302.544462] HDA Intel 0000:00:1b.0: PCI INT B disabled [225302.560165] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.498 msecs [225302.560190] ehci_hcd 0000:00:1a.7: PCI INT C disabled [225302.560216] uhci_hcd 0000:00:1a.1: PCI INT B disabled [225302.560238] uhci_hcd 0000:00:1a.0: PCI INT A disabled [225306.542745] e1000e 0000:00:19.0: PCI INT A disabled [225306.542763] e1000e 0000:00:19.0: PME# enabled [225306.542785] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [225306.556150] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 3995.896 msecs [225306.556648] PM: suspend of devices complete after 7949.794 msecs [225306.556655] PM: suspend devices took 7.952 seconds [225306.557430] ricoh-mmc: Suspending. [225306.557467] ricoh-mmc: Controller is now re-enabled. [225306.580174] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [225306.588161] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [225306.596154] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [225306.620156] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [225306.648134] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [225306.648435] PM: late suspend of devices complete after 91.772 msecs [225306.688136] ACPI: Preparing to enter system sleep state S3 [225306.828447] Disabling non-boot CPUs ... [225306.828493] CPU0 attaching NULL sched-domain. [225306.828501] CPU1 attaching NULL sched-domain. [225306.876033] CPU0 attaching NULL sched-domain. [225306.980042] CPU 1 is now offline [225306.980048] SMP alternatives: switching to UP code [225306.996245] Extended CMOS year: 2000 [225306.996245] Back to C! [225306.996245] CPU0: Thermal monitoring enabled (TM2) [225306.996245] Extended CMOS year: 2000 [225306.996245] Enabling non-boot CPUs ... [225306.996245] SMP alternatives: switching to SMP code [225307.002185] Booting processor 1 APIC 0x1 ip 0x6000 [225306.996148] Initializing CPU#1 [225306.996148] CPU: L1 I cache: 32K, L1 D cache: 32K [225306.996148] CPU: L2 cache: 4096K [225306.996148] CPU: Physical Processor ID: 0 [225306.996148] CPU: Processor Core ID: 1 [225306.996148] CPU1: Thermal monitoring enabled (TM2) [225307.092056] CPU1: Intel(R) Core(TM)2 Duo CPU T7300 @ 2.00GHz stepping 0a [225307.092123] CPU0 attaching NULL sched-domain. [225307.120023] CPU0 attaching sched-domain: [225307.120027] domain 0: span 0-1 level MC [225307.120029] groups: 0 1 [225307.120033] CPU1 attaching sched-domain: [225307.120035] domain 0: span 0-1 level MC [225307.120037] groups: 1 0 [225307.184184] CPU1 is up [225307.185408] ACPI: Waking up from system sleep state S3 [225307.573245] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [225307.573382] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [225307.617061] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [225307.625068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [225307.625109] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [225307.633066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [225307.641070] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [225307.641124] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [225307.649068] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [225307.657066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [225307.657131] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [225307.657217] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [225307.657331] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [225307.657445] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [225307.657556] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [225307.657666] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [225307.665068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [225307.673070] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [225307.673111] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [225307.681068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [225307.689068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [225307.689112] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [225307.697072] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [225307.705068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [225307.705111] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [225307.713068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [225307.721070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [225307.721131] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [225307.729070] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [225307.737065] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [225307.737119] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [225307.737249] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [225307.737319] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [225307.737459] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [225307.737467] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007) [225307.737539] pci 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [225307.737553] pci 0000:02:00.0: restoring config space at offset 0xc (was 0x1, writing 0x0) [225307.737574] pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) [225307.737585] pci 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xdf2ffc00) [225307.737593] pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [225307.737603] pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [225307.737737] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [225307.752085] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) [225307.752097] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [225307.768079] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [225307.768092] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [225307.768185] ricoh-mmc: Resuming. [225307.768212] ricoh-mmc: Controller is now disabled. [225307.768698] PM: early resume of devices complete after 195.595 msecs [225307.816233] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [225307.816241] e1000e 0000:00:19.0: setting latency timer to 64 [225307.816250] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [225307.816259] e1000e 0000:00:19.0: PME# disabled [225307.816316] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [225308.831575] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 1015.369 msecs [225308.831586] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [225308.831597] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [225308.831626] usb usb3: root hub lost power or was reset [225308.831878] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [225308.832095] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [225308.832103] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [225308.832116] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [225308.832150] usb usb4: root hub lost power or was reset [225308.832414] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [225308.832632] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [225308.832641] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [225308.832652] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [225308.832670] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [225308.832687] HDA Intel 0000:00:1b.0: setting latency timer to 64 [225308.832727] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [225308.832940] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [225308.833162] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [225308.833169] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [225308.833180] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [225308.833208] usb usb5: root hub lost power or was reset [225308.833239] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [225308.833246] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [225308.833274] usb usb6: root hub lost power or was reset [225308.833501] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [225308.833698] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [225308.833706] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [225308.833717] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [225308.833749] usb usb7: root hub lost power or was reset [225308.833988] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [225308.834223] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [225308.834235] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [225308.834251] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [225308.834291] pci 0000:00:1e.0: setting latency timer to 64 [225308.834314] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [225308.834325] ata_piix 0000:00:1f.1: setting latency timer to 64 [225308.834362] ahci 0000:00:1f.2: setting latency timer to 64 [225308.834752] ata2: port disabled. ignoring. [225309.000516] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [225309.000520] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [225309.001557] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [225309.002298] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [225309.025881] ata1.00: configured for UDMA/33 [225309.153121] ata5: SATA link down (SStatus 0 SControl 300) [225309.265111] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [225309.266269] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [225309.266273] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [225309.266392] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [225309.266396] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [225309.268622] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [225309.268625] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [225309.268756] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [225309.268759] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [225309.269665] ata3.00: configured for UDMA/100 [225310.007880] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1173.403 msecs [225310.148062] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 139.993 msecs [225310.206124] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [225310.212139] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [225310.212142] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [225310.396122] PM: resume of drv:usb dev:usb1 complete after 147.300 msecs [225310.528097] PM: resume of drv:usb dev:usb2 complete after 131.958 msecs [225310.640094] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [225310.782839] PM: resume of drv:usb dev:2-5 complete after 252.212 msecs [225310.782880] sd 2:0:0:0: [sda] Starting disk [225310.801458] thinkpad_acpi: ACPI backlight control delay disabled [225310.804222] PM: resume of devices complete after 3035.479 msecs [225310.804511] PM: resume devices took 3.036 seconds [225310.804537] PM: Finishing wakeup. [225310.804539] Restarting tasks ... done. [225314.483514] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [225314.537162] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [225314.537405] ADDRCONF(NETDEV_UP): eth0: link is not ready [225314.996433] Registered led device: iwl-phy0::radio [225314.996456] Registered led device: iwl-phy0::assoc [225314.996476] Registered led device: iwl-phy0::RX [225314.996495] Registered led device: iwl-phy0::TX [225316.912466] ADDRCONF(NETDEV_UP): wlan0: link is not ready [225317.621136] usb 3-1: new full speed USB device using uhci_hcd and address 27 [225317.787366] usb 3-1: configuration #1 chosen from 1 choice [225326.183329] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [225326.230655] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [225326.429065] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [225326.431852] wlan0: direct probe responded [225326.431860] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [225326.433769] wlan0: authenticated [225326.433823] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [225326.436192] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [225326.436200] wlan0: associated [225326.461313] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [225336.477045] wlan0: no IPv6 routers present [226652.576095] usb 3-1: USB disconnect, address 27 [226654.908340] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [226659.043266] PM: Syncing filesystems ... done. [226659.455592] PM: Preparing system for mem sleep [226659.455598] Freezing user space processes ... (elapsed 0.00 seconds) done. [226659.456986] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [226659.457051] PM: Entering mem sleep [226659.457066] Suspending console(s) (use no_console_suspend to debug) [226659.675889] PM: suspend of drv:psmouse dev:serio2 complete after 218.336 msecs [226659.720193] sd 2:0:0:0: [sda] Synchronizing SCSI cache [226659.720279] sd 2:0:0:0: [sda] Stopping disk [226662.283922] PM: suspend of drv:sd dev:2:0:0:0 complete after 2563.732 msecs [226662.671518] PM: suspend of drv:psmouse dev:serio1 complete after 375.405 msecs [226663.276072] PM: suspend of drv:atkbd dev:serio0 complete after 604.516 msecs [226663.325374] ACPI handle has no context! [226663.325385] sdhci-pci 0000:15:00.2: PCI INT C disabled [226663.325396] ACPI handle has no context! [226663.345160] ACPI handle has no context! [226663.492301] ata_piix 0000:00:1f.1: PCI INT C disabled [226663.492335] ehci_hcd 0000:00:1d.7: PCI INT D disabled [226663.492352] uhci_hcd 0000:00:1d.2: PCI INT C disabled [226663.492367] uhci_hcd 0000:00:1d.1: PCI INT B disabled [226663.492381] uhci_hcd 0000:00:1d.0: PCI INT A disabled [226663.492397] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [226663.596327] HDA Intel 0000:00:1b.0: PCI INT B disabled [226663.612081] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.663 msecs [226663.612099] ehci_hcd 0000:00:1a.7: PCI INT C disabled [226663.612114] uhci_hcd 0000:00:1a.1: PCI INT B disabled [226663.612132] uhci_hcd 0000:00:1a.0: PCI INT A disabled [226667.593340] e1000e 0000:00:19.0: PCI INT A disabled [226667.593351] e1000e 0000:00:19.0: PME# enabled [226667.593359] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [226667.604075] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 3991.935 msecs [226667.604357] PM: suspend of devices complete after 8146.928 msecs [226667.604359] PM: suspend devices took 8.148 seconds [226667.604850] ricoh-mmc: Suspending. [226667.604877] ricoh-mmc: Controller is now re-enabled. [226667.628073] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [226667.636065] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [226667.644069] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [226667.668069] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [226667.696059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [226667.696240] PM: late suspend of devices complete after 91.872 msecs [226667.736056] ACPI: Preparing to enter system sleep state S3 [226667.876228] Disabling non-boot CPUs ... [226667.876250] CPU0 attaching NULL sched-domain. [226667.876254] CPU1 attaching NULL sched-domain. [226667.924021] CPU0 attaching NULL sched-domain. [226668.028038] CPU 1 is now offline [226668.028041] SMP alternatives: switching to UP code [226668.034348] Extended CMOS year: 2000 [226668.034348] Back to C! [226668.034348] CPU0: Thermal monitoring enabled (TM2) [226668.034348] Extended CMOS year: 2000 [226668.034348] Enabling non-boot CPUs ... [226668.034348] SMP alternatives: switching to SMP code [226668.040235] Booting processor 1 APIC 0x1 ip 0x6000 [226668.034294] Initializing CPU#1 [226668.034294] CPU: L1 I cache: 32K, L1 D cache: 32K [226668.034294] CPU: L2 cache: 4096K [226668.034294] CPU: Physical Processor ID: 0 [226668.034294] CPU: Processor Core ID: 1 [226668.034294] CPU1: Thermal monitoring enabled (TM2) [226668.128043] CPU1: Intel(R) Core(TM)2 Duo CPU T7300 @ 2.00GHz stepping 0a [226668.128098] CPU0 attaching NULL sched-domain. [226668.156022] CPU0 attaching sched-domain: [226668.156025] domain 0: span 0-1 level MC [226668.156028] groups: 0 1 [226668.156032] CPU1 attaching sched-domain: [226668.156034] domain 0: span 0-1 level MC [226668.156036] groups: 1 0 [226668.220184] CPU1 is up [226668.221407] ACPI: Waking up from system sleep state S3 [226668.561248] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [226668.561380] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [226668.605057] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [226668.613071] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [226668.613115] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [226668.621069] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [226668.629066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [226668.629124] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [226668.637066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [226668.645070] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [226668.645136] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [226668.645223] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [226668.645337] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [226668.645451] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [226668.645561] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [226668.645674] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [226668.653069] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [226668.661068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [226668.661107] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [226668.669072] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [226668.677069] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [226668.677112] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [226668.685066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [226668.693070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [226668.693114] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [226668.701070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [226668.709065] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [226668.709129] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [226668.717068] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [226668.725069] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [226668.725124] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [226668.725263] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [226668.725331] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [226668.725477] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [226668.725485] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007) [226668.725559] pci 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [226668.725572] pci 0000:02:00.0: restoring config space at offset 0xc (was 0x1, writing 0x0) [226668.725593] pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) [226668.725604] pci 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xdf2ffc00) [226668.725611] pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [226668.725622] pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [226668.725762] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [226668.740079] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) [226668.740092] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [226668.756074] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [226668.756087] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [226668.756186] ricoh-mmc: Resuming. [226668.756209] ricoh-mmc: Controller is now disabled. [226668.756690] PM: early resume of devices complete after 195.580 msecs [226668.804617] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [226668.804627] e1000e 0000:00:19.0: setting latency timer to 64 [226668.804636] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [226668.804643] e1000e 0000:00:19.0: PME# disabled [226668.804722] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [226669.819558] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 1014.972 msecs [226669.819571] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [226669.819582] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [226669.819610] usb usb3: root hub lost power or was reset [226669.819832] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [226669.820052] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [226669.820060] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [226669.820073] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [226669.820100] usb usb4: root hub lost power or was reset [226669.820338] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [226669.820557] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [226669.820566] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [226669.820577] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [226669.820593] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [226669.820604] HDA Intel 0000:00:1b.0: setting latency timer to 64 [226669.820647] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [226669.820891] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [226669.821133] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [226669.821140] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [226669.821154] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [226669.821186] usb usb5: root hub lost power or was reset [226669.821220] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [226669.821229] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [226669.821259] usb usb6: root hub lost power or was reset [226669.821487] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [226669.821742] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [226669.821752] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [226669.821766] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [226669.821809] usb usb7: root hub lost power or was reset [226669.822057] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [226669.822259] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [226669.822268] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [226669.822278] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [226669.822312] pci 0000:00:1e.0: setting latency timer to 64 [226669.822328] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [226669.822335] ata_piix 0000:00:1f.1: setting latency timer to 64 [226669.822370] ahci 0000:00:1f.2: setting latency timer to 64 [226669.822732] ata2: port disabled. ignoring. [226669.989589] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [226669.989593] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [226669.990649] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [226669.991397] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [226670.013923] ata1.00: configured for UDMA/33 [226670.141134] ata5: SATA link down (SStatus 0 SControl 300) [226670.253124] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [226670.254320] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [226670.254323] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [226670.254437] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [226670.254440] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [226670.256701] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [226670.256704] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [226670.256825] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [226670.256828] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [226670.257774] ata3.00: configured for UDMA/100 [226670.995835] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1173.372 msecs [226671.136098] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 140.070 msecs [226671.193148] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [226671.199160] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [226671.199162] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [226671.380120] PM: resume of drv:usb dev:usb1 complete after 147.306 msecs [226671.512092] PM: resume of drv:usb dev:usb2 complete after 131.956 msecs [226671.624094] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [226671.766840] PM: resume of drv:usb dev:2-5 complete after 252.325 msecs [226671.766880] sd 2:0:0:0: [sda] Starting disk [226671.786304] thinkpad_acpi: ACPI backlight control delay disabled [226671.789052] PM: resume of devices complete after 3032.319 msecs [226671.789264] PM: resume devices took 3.032 seconds [226671.789291] PM: Finishing wakeup. [226671.789292] Restarting tasks ... done. [226673.280233] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [226673.337424] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [226673.337973] ADDRCONF(NETDEV_UP): eth0: link is not ready [226674.578078] Registered led device: iwl-phy0::radio [226674.578101] Registered led device: iwl-phy0::assoc [226674.578121] Registered led device: iwl-phy0::RX [226674.578140] Registered led device: iwl-phy0::TX [226675.370181] ADDRCONF(NETDEV_UP): wlan0: link is not ready [226676.072038] usb 3-1: new full speed USB device using uhci_hcd and address 28 [226676.250164] usb 3-1: configuration #1 chosen from 1 choice [226682.240417] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [226682.280499] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [226682.480066] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [226682.483180] wlan0: direct probe responded [226682.483188] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [226682.485063] wlan0: authenticated [226682.485110] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [226682.487534] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=1) [226682.487540] wlan0: associated [226682.512606] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [226692.722148] wlan0: no IPv6 routers present [229690.328094] usb 3-1: USB disconnect, address 28 [229693.905215] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [229698.075209] PM: Syncing filesystems ... done. [229698.236428] PM: Preparing system for mem sleep [229698.236434] Freezing user space processes ... (elapsed 0.00 seconds) done. [229698.237782] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [229698.237849] PM: Entering mem sleep [229698.237862] Suspending console(s) (use no_console_suspend to debug) [229698.468070] PM: suspend of drv:psmouse dev:serio2 complete after 229.745 msecs [229698.516174] sd 2:0:0:0: [sda] Synchronizing SCSI cache [229698.516272] sd 2:0:0:0: [sda] Stopping disk [229701.087034] PM: suspend of drv:sd dev:2:0:0:0 complete after 2570.863 msecs [229701.474042] PM: suspend of drv:psmouse dev:serio1 complete after 373.937 msecs [229702.076075] PM: suspend of drv:atkbd dev:serio0 complete after 601.996 msecs [229702.125401] ACPI handle has no context! [229702.125414] sdhci-pci 0000:15:00.2: PCI INT C disabled [229702.125425] ACPI handle has no context! [229702.145174] ACPI handle has no context! [229702.284324] ata_piix 0000:00:1f.1: PCI INT C disabled [229702.284349] ehci_hcd 0000:00:1d.7: PCI INT D disabled [229702.284370] uhci_hcd 0000:00:1d.2: PCI INT C disabled [229702.284384] uhci_hcd 0000:00:1d.1: PCI INT B disabled [229702.284402] uhci_hcd 0000:00:1d.0: PCI INT A disabled [229702.284413] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [229702.388346] HDA Intel 0000:00:1b.0: PCI INT B disabled [229702.404077] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.635 msecs [229702.404088] ehci_hcd 0000:00:1a.7: PCI INT C disabled [229702.404103] uhci_hcd 0000:00:1a.1: PCI INT B disabled [229702.404118] uhci_hcd 0000:00:1a.0: PCI INT A disabled [229706.385072] e1000e 0000:00:19.0: PCI INT A disabled [229706.385083] e1000e 0000:00:19.0: PME# enabled [229706.385091] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [229706.396075] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 3991.950 msecs [229706.396364] PM: suspend of devices complete after 8158.149 msecs [229706.396367] PM: suspend devices took 8.160 seconds [229706.396843] ricoh-mmc: Suspending. [229706.396866] ricoh-mmc: Controller is now re-enabled. [229706.420073] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [229706.428066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [229706.436067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [229706.460066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [229706.488057] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [229706.488231] PM: late suspend of devices complete after 91.862 msecs [229706.528056] ACPI: Preparing to enter system sleep state S3 [229706.668226] Disabling non-boot CPUs ... [229706.668244] CPU0 attaching NULL sched-domain. [229706.668248] CPU1 attaching NULL sched-domain. [229706.716019] CPU0 attaching NULL sched-domain. [229706.820025] CPU 1 is now offline [229706.820028] SMP alternatives: switching to UP code [229706.826239] Extended CMOS year: 2000 [229706.826239] Back to C! [229706.826239] CPU0: Thermal monitoring enabled (TM2) [229706.826239] Extended CMOS year: 2000 [229706.826239] Enabling non-boot CPUs ... [229706.826239] SMP alternatives: switching to SMP code [229706.832106] Booting processor 1 APIC 0x1 ip 0x6000 [229706.826184] Initializing CPU#1 [229706.826184] CPU: L1 I cache: 32K, L1 D cache: 32K [229706.826184] CPU: L2 cache: 4096K [229706.826184] CPU: Physical Processor ID: 0 [229706.826184] CPU: Processor Core ID: 1 [229706.826184] CPU1: Thermal monitoring enabled (TM2) [229706.920030] CPU1: Intel(R) Core(TM)2 Duo CPU T7300 @ 2.00GHz stepping 0a [229706.920083] CPU0 attaching NULL sched-domain. [229706.948020] CPU0 attaching sched-domain: [229706.948023] domain 0: span 0-1 level MC [229706.948025] groups: 0 1 [229706.948030] CPU1 attaching sched-domain: [229706.948032] domain 0: span 0-1 level MC [229706.948034] groups: 1 0 [229707.012179] CPU1 is up [229707.013401] ACPI: Waking up from system sleep state S3 [229707.353246] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [229707.353388] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [229707.397061] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [229707.405069] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [229707.405111] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [229707.413066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [229707.421066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [229707.421119] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [229707.429067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [229707.437070] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [229707.437140] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [229707.437233] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [229707.437354] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [229707.437466] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [229707.437574] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [229707.437685] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [229707.445066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [229707.453068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [229707.453114] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [229707.461071] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [229707.469068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [229707.469110] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [229707.477068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [229707.485070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [229707.485112] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [229707.493069] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [229707.501068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [229707.501134] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [229707.509070] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [229707.517070] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [229707.517129] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [229707.517264] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [229707.517331] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [229707.517467] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [229707.517475] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007) [229707.517548] pci 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [229707.517561] pci 0000:02:00.0: restoring config space at offset 0xc (was 0x1, writing 0x0) [229707.517582] pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) [229707.517593] pci 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xdf2ffc00) [229707.517601] pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [229707.517611] pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [229707.517753] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [229707.532075] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) [229707.532087] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [229707.548081] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [229707.548091] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [229707.548189] ricoh-mmc: Resuming. [229707.548211] ricoh-mmc: Controller is now disabled. [229707.548697] PM: early resume of devices complete after 195.591 msecs [229707.597568] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [229707.597576] e1000e 0000:00:19.0: setting latency timer to 64 [229707.597585] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [229707.597595] e1000e 0000:00:19.0: PME# disabled [229707.597657] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [229708.615538] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 1017.997 msecs [229708.615549] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [229708.615560] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [229708.615590] usb usb3: root hub lost power or was reset [229708.615807] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [229708.616054] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [229708.616064] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [229708.616076] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [229708.616116] usb usb4: root hub lost power or was reset [229708.616396] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [229708.616643] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [229708.616654] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [229708.616663] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [229708.616682] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [229708.616693] HDA Intel 0000:00:1b.0: setting latency timer to 64 [229708.616739] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [229708.616963] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [229708.617151] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [229708.617158] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [229708.617172] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [229708.617204] usb usb5: root hub lost power or was reset [229708.617236] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [229708.617249] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [229708.617288] usb usb6: root hub lost power or was reset [229708.617569] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [229708.617768] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [229708.617775] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [229708.617785] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [229708.617819] usb usb7: root hub lost power or was reset [229708.618101] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [229708.618333] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [229708.618348] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [229708.618363] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [229708.618403] pci 0000:00:1e.0: setting latency timer to 64 [229708.618424] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [229708.618435] ata_piix 0000:00:1f.1: setting latency timer to 64 [229708.618473] ahci 0000:00:1f.2: setting latency timer to 64 [229708.618852] ata2: port disabled. ignoring. [229708.785509] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [229708.785513] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [229708.786549] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [229708.787292] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [229708.809879] ata1.00: configured for UDMA/33 [229708.937112] ata5: SATA link down (SStatus 0 SControl 300) [229709.049112] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [229709.050295] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [229709.050299] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [229709.050410] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [229709.050413] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [229709.052661] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [229709.052665] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [229709.052768] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [229709.052771] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [229709.053704] ata3.00: configured for UDMA/100 [229709.791763] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1173.186 msecs [229709.928095] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 136.139 msecs [229709.986137] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [229709.992159] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [229709.992162] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [229710.176122] PM: resume of drv:usb dev:usb1 complete after 147.286 msecs [229710.308094] PM: resume of drv:usb dev:usb2 complete after 131.952 msecs [229710.420099] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [229710.562842] PM: resume of drv:usb dev:2-5 complete after 252.230 msecs [229710.562878] sd 2:0:0:0: [sda] Starting disk [229710.587395] thinkpad_acpi: ACPI backlight control delay disabled [229710.590138] PM: resume of devices complete after 3041.397 msecs [229710.590333] PM: resume devices took 3.040 seconds [229710.590359] PM: Finishing wakeup. [229710.590361] Restarting tasks ... done. [229712.261288] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [229712.317118] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [229712.317367] ADDRCONF(NETDEV_UP): eth0: link is not ready [229713.703332] Registered led device: iwl-phy0::radio [229713.703354] Registered led device: iwl-phy0::assoc [229713.703374] Registered led device: iwl-phy0::RX [229713.703392] Registered led device: iwl-phy0::TX [229714.331776] ADDRCONF(NETDEV_UP): wlan0: link is not ready [229715.025032] usb 3-1: new full speed USB device using uhci_hcd and address 29 [229715.254184] usb 3-1: configuration #1 chosen from 1 choice [229743.527218] wlan0: deauthenticating from bc:ae:c5:7e:b6:08 by local choice (reason=3) [229743.576307] wlan0: direct probe to AP bc:ae:c5:7e:b6:08 (try 1) [229743.579663] wlan0: direct probe responded [229743.579666] wlan0: authenticate with AP bc:ae:c5:7e:b6:08 (try 1) [229743.585851] wlan0: authenticated [229743.585921] wlan0: associate with AP bc:ae:c5:7e:b6:08 (try 1) [229743.590098] wlan0: RX AssocResp from bc:ae:c5:7e:b6:08 (capab=0x411 status=0 aid=9) [229743.590104] wlan0: associated [229743.617419] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [229743.617542] cfg80211: Calling CRDA for country: US [229743.707153] cfg80211: Received country IE: [229743.707162] cfg80211: Regulatory domain: US [229743.707166] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [229743.707174] (2402000 KHz - 2477000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm) [229743.707179] cfg80211: CRDA thinks this should applied: [229743.707184] cfg80211: Regulatory domain: US [229743.707188] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [229743.707196] (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm) [229743.707203] (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm) [229743.707210] (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [229743.707217] (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [229743.707223] (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm) [229743.707230] (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm) [229743.707235] cfg80211: We intersect both of these and get: [229743.707239] cfg80211: Regulatory domain: 98 [229743.707244] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [229743.707251] (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm) [229743.707262] cfg80211: Leaving channel 5180 MHz intact on phy0 - no rule found in band on Country IE [229743.707268] cfg80211: Leaving channel 5200 MHz intact on phy0 - no rule found in band on Country IE [229743.707275] cfg80211: Leaving channel 5220 MHz intact on phy0 - no rule found in band on Country IE [229743.707281] cfg80211: Leaving channel 5240 MHz intact on phy0 - no rule found in band on Country IE [229743.707287] cfg80211: Leaving channel 5260 MHz intact on phy0 - no rule found in band on Country IE [229743.707293] cfg80211: Leaving channel 5280 MHz intact on phy0 - no rule found in band on Country IE [229743.707300] cfg80211: Leaving channel 5300 MHz intact on phy0 - no rule found in band on Country IE [229743.707306] cfg80211: Leaving channel 5320 MHz intact on phy0 - no rule found in band on Country IE [229743.707312] cfg80211: Leaving channel 5745 MHz intact on phy0 - no rule found in band on Country IE [229743.707318] cfg80211: Leaving channel 5765 MHz intact on phy0 - no rule found in band on Country IE [229743.707324] cfg80211: Leaving channel 5785 MHz intact on phy0 - no rule found in band on Country IE [229743.707330] cfg80211: Leaving channel 5805 MHz intact on phy0 - no rule found in band on Country IE [229743.707336] cfg80211: Leaving channel 5825 MHz intact on phy0 - no rule found in band on Country IE [229743.707343] cfg80211: Current regulatory domain updated by AP to: US [229743.707348] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [229743.707356] (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm) [229751.537974] iwlagn 0000:03:00.0: iwl_tx_agg_start on ra = bc:ae:c5:7e:b6:08 tid = 0 [229753.968014] wlan0: no IPv6 routers present [262787.417102] usb 3-1: USB disconnect, address 29 [262791.028261] wlan0: deauthenticating from bc:ae:c5:7e:b6:08 by local choice (reason=3) [262791.028453] iwlagn 0000:03:00.0: Microcode SW error detected. Restarting 0x82000000. [262797.023513] PM: Syncing filesystems ... done. [262797.109642] PM: Preparing system for mem sleep [262797.109654] Freezing user space processes ... (elapsed 0.00 seconds) done. [262797.112262] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [262797.112374] PM: Entering mem sleep [262797.112400] Suspending console(s) (use no_console_suspend to debug) [262797.341811] PM: suspend of drv:psmouse dev:serio2 complete after 228.405 msecs [262797.368533] sd 2:0:0:0: [sda] Synchronizing SCSI cache [262797.370617] sd 2:0:0:0: [sda] Stopping disk [262799.730563] PM: suspend of drv:sd dev:2:0:0:0 complete after 2362.034 msecs [262800.129578] PM: suspend of drv:psmouse dev:serio1 complete after 385.188 msecs [262800.736299] PM: suspend of drv:atkbd dev:serio0 complete after 606.644 msecs [262800.785731] ACPI handle has no context! [262800.785753] sdhci-pci 0000:15:00.2: PCI INT C disabled [262800.785768] ACPI handle has no context! [262800.805276] ACPI handle has no context! [262800.984470] ata_piix 0000:00:1f.1: PCI INT C disabled [262800.984512] ehci_hcd 0000:00:1d.7: PCI INT D disabled [262800.984536] uhci_hcd 0000:00:1d.2: PCI INT C disabled [262800.984558] uhci_hcd 0000:00:1d.1: PCI INT B disabled [262800.984582] uhci_hcd 0000:00:1d.0: PCI INT A disabled [262800.984605] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [262801.088444] HDA Intel 0000:00:1b.0: PCI INT B disabled [262801.104153] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.502 msecs [262801.104176] ehci_hcd 0000:00:1a.7: PCI INT C disabled [262801.104201] uhci_hcd 0000:00:1a.1: PCI INT B disabled [262801.104224] uhci_hcd 0000:00:1a.0: PCI INT A disabled [262805.086427] e1000e 0000:00:19.0: PCI INT A disabled [262805.086443] e1000e 0000:00:19.0: PME# enabled [262805.086461] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [262805.100152] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 3995.913 msecs [262805.100669] PM: suspend of devices complete after 7987.487 msecs [262805.100676] PM: suspend devices took 7.992 seconds [262805.101446] ricoh-mmc: Suspending. [262805.101482] ricoh-mmc: Controller is now re-enabled. [262805.124192] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [262805.132157] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [262805.140155] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [262805.164160] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [262805.192137] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [262805.192451] PM: late suspend of devices complete after 91.764 msecs [262805.232140] ACPI: Preparing to enter system sleep state S3 [262805.372456] Disabling non-boot CPUs ... [262805.372494] CPU0 attaching NULL sched-domain. [262805.372502] CPU1 attaching NULL sched-domain. [262805.420036] CPU0 attaching NULL sched-domain. [262805.524050] CPU 1 is now offline [262805.524056] SMP alternatives: switching to UP code [262805.540188] Extended CMOS year: 2000 [262805.540188] Back to C! [262805.540188] CPU0: Thermal monitoring enabled (TM2) [262805.540188] Extended CMOS year: 2000 [262805.540188] Enabling non-boot CPUs ... [262805.540188] SMP alternatives: switching to SMP code [262805.546104] Booting processor 1 APIC 0x1 ip 0x6000 [262805.540086] Initializing CPU#1 [262805.540086] CPU: L1 I cache: 32K, L1 D cache: 32K [262805.540086] CPU: L2 cache: 4096K [262805.540086] CPU: Physical Processor ID: 0 [262805.540086] CPU: Processor Core ID: 1 [262805.540086] CPU1: Thermal monitoring enabled (TM2) [262805.636073] CPU1: Intel(R) Core(TM)2 Duo CPU T7300 @ 2.00GHz stepping 0a [262805.636146] CPU0 attaching NULL sched-domain. [262805.664019] CPU0 attaching sched-domain: [262805.664023] domain 0: span 0-1 level MC [262805.664025] groups: 0 1 [262805.664029] CPU1 attaching sched-domain: [262805.664031] domain 0: span 0-1 level MC [262805.664033] groups: 1 0 [262805.728186] CPU1 is up [262805.729406] ACPI: Waking up from system sleep state S3 [262806.117251] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [262806.117417] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [262806.161064] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [262806.169068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [262806.169112] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [262806.177066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [262806.185070] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [262806.185139] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [262806.193070] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [262806.201072] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [262806.201155] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [262806.201261] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [262806.201404] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [262806.201539] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [262806.201672] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [262806.201815] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [262806.209070] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [262806.217069] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [262806.217120] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [262806.225068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [262806.233068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [262806.233126] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [262806.241070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [262806.249072] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [262806.249122] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [262806.257070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [262806.265070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [262806.265145] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [262806.273070] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [262806.281066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [262806.281134] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [262806.281292] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [262806.281380] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [262806.281538] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [262806.281545] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007) [262806.281619] pci 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [262806.281633] pci 0000:02:00.0: restoring config space at offset 0xc (was 0x1, writing 0x0) [262806.281654] pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) [262806.281664] pci 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xdf2ffc00) [262806.281672] pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [262806.281683] pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [262806.281823] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [262806.296085] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) [262806.296099] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [262806.312079] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [262806.312092] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [262806.312185] ricoh-mmc: Resuming. [262806.312208] ricoh-mmc: Controller is now disabled. [262806.312699] PM: early resume of devices complete after 195.591 msecs [262806.360668] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [262806.360678] e1000e 0000:00:19.0: setting latency timer to 64 [262806.360687] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [262806.360696] e1000e 0000:00:19.0: PME# disabled [262806.360768] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [262807.375563] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 1014.927 msecs [262807.375574] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [262807.375587] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [262807.375619] usb usb3: root hub lost power or was reset [262807.375846] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [262807.376088] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [262807.376098] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [262807.376114] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [262807.376154] usb usb4: root hub lost power or was reset [262807.376426] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [262807.376633] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [262807.376644] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [262807.376654] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [262807.376673] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [262807.376682] HDA Intel 0000:00:1b.0: setting latency timer to 64 [262807.376723] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [262807.376943] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [262807.377144] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [262807.377151] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [262807.377160] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [262807.377189] usb usb5: root hub lost power or was reset [262807.377222] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [262807.377232] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [262807.377259] usb usb6: root hub lost power or was reset [262807.377553] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [262807.377780] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [262807.377790] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [262807.377804] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [262807.377839] usb usb7: root hub lost power or was reset [262807.378086] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [262807.378299] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [262807.378308] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [262807.378319] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [262807.378350] pci 0000:00:1e.0: setting latency timer to 64 [262807.378364] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [262807.378369] ata_piix 0000:00:1f.1: setting latency timer to 64 [262807.378402] ahci 0000:00:1f.2: setting latency timer to 64 [262807.378772] ata2: port disabled. ignoring. [262807.545510] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [262807.545514] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [262807.546600] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [262807.547339] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [262807.569753] ata1.00: configured for UDMA/33 [262807.697127] ata5: SATA link down (SStatus 0 SControl 300) [262807.809111] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [262807.810269] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [262807.810273] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [262807.810392] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [262807.810395] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [262807.812621] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [262807.812625] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [262807.812728] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [262807.812732] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [262807.813667] ata3.00: configured for UDMA/100 [262808.567214] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1188.714 msecs [262808.704097] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 136.686 msecs [262808.761135] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [262808.767145] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [262808.767148] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [262808.948120] PM: resume of drv:usb dev:usb1 complete after 147.303 msecs [262809.080095] PM: resume of drv:usb dev:usb2 complete after 131.960 msecs [262809.192091] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [262809.334837] PM: resume of drv:usb dev:2-5 complete after 252.316 msecs [262809.334873] sd 2:0:0:0: [sda] Starting disk [262809.356779] thinkpad_acpi: ACPI backlight control delay disabled [262809.359440] PM: resume of devices complete after 3046.697 msecs [262809.359633] PM: resume devices took 3.044 seconds [262809.359660] PM: Finishing wakeup. [262809.359662] Restarting tasks ... done. [262812.393645] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [262812.449239] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [262812.449726] ADDRCONF(NETDEV_UP): eth0: link is not ready [262813.245209] Registered led device: iwl-phy0::radio [262813.245231] Registered led device: iwl-phy0::assoc [262813.245250] Registered led device: iwl-phy0::RX [262813.245270] Registered led device: iwl-phy0::TX [262814.463389] ADDRCONF(NETDEV_UP): wlan0: link is not ready [262815.169032] usb 3-1: new full speed USB device using uhci_hcd and address 30 [262815.343240] usb 3-1: configuration #1 chosen from 1 choice [262823.556163] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [262823.601198] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [262823.800148] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 2) [262824.000594] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 3) [262824.200052] wlan0: direct probe to AP 00:18:39:54:e1:69 timed out [262842.104863] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [262842.108977] wlan0: direct probe responded [262842.108980] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [262842.111275] wlan0: authenticated [262842.111294] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [262842.113654] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=2) [262842.113657] wlan0: associated [262842.128286] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [262852.988039] wlan0: no IPv6 routers present [267152.712377] usb 3-1: USB disconnect, address 30 [267156.884092] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [267161.637294] PM: Syncing filesystems ... done. [267161.764655] PM: Preparing system for mem sleep [267161.764661] Freezing user space processes ... (elapsed 0.00 seconds) done. [267161.765927] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [267161.765993] PM: Entering mem sleep [267161.766006] Suspending console(s) (use no_console_suspend to debug) [267161.994125] PM: suspend of drv:psmouse dev:serio2 complete after 227.653 msecs [267162.040183] sd 2:0:0:0: [sda] Synchronizing SCSI cache [267162.040267] sd 2:0:0:0: [sda] Stopping disk [267164.610332] PM: suspend of drv:sd dev:2:0:0:0 complete after 2570.149 msecs [267164.999693] PM: suspend of drv:psmouse dev:serio1 complete after 375.559 msecs [267165.604069] PM: suspend of drv:atkbd dev:serio0 complete after 604.341 msecs [267165.653392] ACPI handle has no context! [267165.653403] sdhci-pci 0000:15:00.2: PCI INT C disabled [267165.653412] ACPI handle has no context! [267165.673167] ACPI handle has no context! [267165.816294] ata_piix 0000:00:1f.1: PCI INT C disabled [267165.816329] ehci_hcd 0000:00:1d.7: PCI INT D disabled [267165.816344] uhci_hcd 0000:00:1d.2: PCI INT C disabled [267165.816361] uhci_hcd 0000:00:1d.1: PCI INT B disabled [267165.816375] uhci_hcd 0000:00:1d.0: PCI INT A disabled [267165.816390] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [267165.920342] HDA Intel 0000:00:1b.0: PCI INT B disabled [267165.936085] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.666 msecs [267165.936101] ehci_hcd 0000:00:1a.7: PCI INT C disabled [267165.936118] uhci_hcd 0000:00:1a.1: PCI INT B disabled [267165.936137] uhci_hcd 0000:00:1a.0: PCI INT A disabled [267169.917330] e1000e 0000:00:19.0: PCI INT A disabled [267169.917339] e1000e 0000:00:19.0: PME# enabled [267169.917348] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [267169.932101] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 3995.958 msecs [267169.932385] PM: suspend of devices complete after 8166.032 msecs [267169.932388] PM: suspend devices took 8.168 seconds [267169.932878] ricoh-mmc: Suspending. [267169.932905] ricoh-mmc: Controller is now re-enabled. [267169.957083] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [267169.965073] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [267169.973073] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [267169.997067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [267170.025064] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [267170.025251] PM: late suspend of devices complete after 92.855 msecs [267170.065068] ACPI: Preparing to enter system sleep state S3 [267170.205230] Disabling non-boot CPUs ... [267170.205245] CPU0 attaching NULL sched-domain. [267170.205249] CPU1 attaching NULL sched-domain. [267170.253022] CPU0 attaching NULL sched-domain. [267170.356047] CPU 1 is now offline [267170.356050] SMP alternatives: switching to UP code [267170.362248] Extended CMOS year: 2000 [267170.362248] Back to C! [267170.362248] CPU0: Thermal monitoring enabled (TM2) [267170.362248] Extended CMOS year: 2000 [267170.362248] Enabling non-boot CPUs ... [267170.362248] SMP alternatives: switching to SMP code [267170.368119] Booting processor 1 APIC 0x1 ip 0x6000 [267170.362248] Initializing CPU#1 [267170.362248] CPU: L1 I cache: 32K, L1 D cache: 32K [267170.362248] CPU: L2 cache: 4096K [267170.362248] CPU: Physical Processor ID: 0 [267170.362248] CPU: Processor Core ID: 1 [267170.362248] CPU1: Thermal monitoring enabled (TM2) [267170.456038] CPU1: Intel(R) Core(TM)2 Duo CPU T7300 @ 2.00GHz stepping 0a [267170.456099] CPU0 attaching NULL sched-domain. [267170.484020] CPU0 attaching sched-domain: [267170.484023] domain 0: span 0-1 level MC [267170.484026] groups: 0 1 [267170.484030] CPU1 attaching sched-domain: [267170.484032] domain 0: span 0-1 level MC [267170.484034] groups: 1 0 [267170.548185] CPU1 is up [267170.549404] ACPI: Waking up from system sleep state S3 [267170.889245] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [267170.889378] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [267170.933061] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [267170.941069] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [267170.941114] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [267170.949072] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [267170.957068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [267170.957124] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [267170.965067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [267170.973070] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [267170.973143] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [267170.973226] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [267170.973345] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [267170.973456] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [267170.973568] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [267170.973680] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [267170.981068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [267170.989066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [267170.989107] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [267170.997068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [267171.005070] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [267171.005117] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [267171.013068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [267171.021068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [267171.021109] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [267171.029070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [267171.037067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [267171.037124] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [267171.045067] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [267171.053070] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [267171.053128] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [267171.053261] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [267171.053331] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [267171.053466] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [267171.053474] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100007) [267171.053546] pci 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [267171.053559] pci 0000:02:00.0: restoring config space at offset 0xc (was 0x1, writing 0x0) [267171.053581] pci 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x3001) [267171.053591] pci 0000:02:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xdf2ffc00) [267171.053599] pci 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [267171.053610] pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [267171.053744] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [267171.068086] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x802000) [267171.068100] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [267171.084082] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [267171.084096] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [267171.084197] ricoh-mmc: Resuming. [267171.084226] ricoh-mmc: Controller is now disabled. [267171.084729] PM: early resume of devices complete after 195.620 msecs [267171.134560] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [267171.134570] e1000e 0000:00:19.0: setting latency timer to 64 [267171.134579] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [267171.134586] e1000e 0000:00:19.0: PME# disabled [267171.134662] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [267172.151617] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 1017.091 msecs [267172.151628] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [267172.151641] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [267172.151672] usb usb3: root hub lost power or was reset [267172.151925] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [267172.152154] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [267172.152164] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [267172.152180] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [267172.152215] usb usb4: root hub lost power or was reset [267172.152444] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [267172.152655] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [267172.152666] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [267172.152675] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [267172.152694] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [267172.152703] HDA Intel 0000:00:1b.0: setting latency timer to 64 [267172.152748] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [267172.152969] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [267172.153191] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [267172.153198] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [267172.153209] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [267172.153245] usb usb5: root hub lost power or was reset [267172.153271] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [267172.153282] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [267172.153308] usb usb6: root hub lost power or was reset [267172.153535] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [267172.153754] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [267172.153761] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [267172.153770] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [267172.153802] usb usb7: root hub lost power or was reset [267172.154040] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [267172.154245] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [267172.154256] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [267172.154265] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [267172.154297] pci 0000:00:1e.0: setting latency timer to 64 [267172.154317] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [267172.154324] ata_piix 0000:00:1f.1: setting latency timer to 64 [267172.154359] ahci 0000:00:1f.2: setting latency timer to 64 [267172.154718] ata2: port disabled. ignoring. [267172.324519] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [267172.324523] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [267172.325564] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [267172.326305] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [267172.352872] ata1.00: configured for UDMA/33 [267172.476107] ata5: SATA link down (SStatus 0 SControl 300) [267172.588114] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [267172.589336] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [267172.589340] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [267172.589468] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [267172.589472] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [267172.591734] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [267172.591737] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [267172.591865] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [267172.591868] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [267172.592826] ata3.00: configured for UDMA/100 [267173.327556] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1173.108 msecs [267173.464073] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 136.335 msecs [267173.522088] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [267173.527186] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [267173.527189] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [267173.712097] PM: resume of drv:usb dev:usb1 complete after 147.345 msecs [267173.844069] PM: resume of drv:usb dev:usb2 complete after 131.956 msecs [267173.956074] usb 2-5: reset high speed USB device using ehci_hcd and address 2 [267174.098690] PM: resume of drv:usb dev:2-5 complete after 252.195 msecs [267174.098727] sd 2:0:0:0: [sda] Starting disk [267174.112137] thinkpad_acpi: ACPI backlight control delay disabled [267174.114879] PM: resume of devices complete after 3030.109 msecs [267174.115074] PM: resume devices took 3.028 seconds [267174.115100] PM: Finishing wakeup. [267174.115102] Restarting tasks ... done. [267176.141320] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [267176.197225] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [267176.197667] ADDRCONF(NETDEV_UP): eth0: link is not ready [267177.374501] Registered led device: iwl-phy0::radio [267177.374523] Registered led device: iwl-phy0::assoc [267177.374543] Registered led device: iwl-phy0::RX [267177.374562] Registered led device: iwl-phy0::TX [267178.225185] ADDRCONF(NETDEV_UP): wlan0: link is not ready [267178.916079] usb 3-1: new full speed USB device using uhci_hcd and address 31 [267179.102200] usb 3-1: configuration #1 chosen from 1 choice [267197.337004] wlan0: deauthenticating from 00:18:39:54:e1:69 by local choice (reason=3) [267197.380546] wlan0: direct probe to AP 00:18:39:54:e1:69 (try 1) [267197.390776] wlan0: direct probe responded [267197.390780] wlan0: authenticate with AP 00:18:39:54:e1:69 (try 1) [267197.392769] wlan0: authenticated [267197.392791] wlan0: associate with AP 00:18:39:54:e1:69 (try 1) [267197.395130] wlan0: RX AssocResp from 00:18:39:54:e1:69 (capab=0x411 status=0 aid=2) [267197.395133] wlan0: associated [267197.421099] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [267208.027350] wlan0: no IPv6 routers present