[Bug 518196] Re: Intel iwlagn driver hangs from time to time

dave945 dave at dtabor.com
Sun Feb 28 03:51:04 UTC 2010


This time, I booted to linux and it worked (don't know for how long it 
will last):

Feb 27 22:45:43 tabord-laptop kernel: [   12.470749] iwlagn: Intel(R) 
Wireless WiFi Link AGN driver for Linux, 1.3.27ks
Feb 27 22:45:43 tabord-laptop kernel: [   12.470752] iwlagn: 
Copyright(c) 2003-2009 Intel Corporation
Feb 27 22:45:43 tabord-laptop kernel: [   12.470830] iwlagn 
0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
Feb 27 22:45:43 tabord-laptop kernel: [   12.470906] iwlagn 
0000:03:00.0: Detected Intel Wireless WiFi Link 5100AGN REV=0x54
Feb 27 22:45:43 tabord-laptop kernel: [   12.513637] iwlagn 
0000:03:00.0: Tunable channels: 13 802.11bg, 24 802.11a channels
Feb 27 22:45:43 tabord-laptop kernel: [   12.547322] input: HDA Intel 
Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input10
Feb 27 22:45:43 tabord-laptop kernel: [   12.547382] input: HDA Intel 
Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
Feb 27 22:45:43 tabord-laptop kernel: [   12.547429] input: HDA Intel 
Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input12
Feb 27 22:45:43 tabord-laptop kernel: [   12.547473] input: HDA Intel 
Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input13
Feb 27 22:45:43 tabord-laptop kernel: [   12.720221] pcmcia_socket 
pcmcia_socket0: cs: IO port probe 0x100-0x3af: clean.
Feb 27 22:45:43 tabord-laptop kernel: [   12.721953] pcmcia_socket 
pcmcia_socket0: cs: IO port probe 0x3e0-0x4ff: excluding 0x4d0-0x4d7
Feb 27 22:45:43 tabord-laptop kernel: [   12.722685] pcmcia_socket 
pcmcia_socket0: cs: IO port probe 0x820-0x8ff: clean.
Feb 27 22:45:43 tabord-laptop kernel: [   12.723262] pcmcia_socket 
pcmcia_socket0: cs: IO port probe 0xc00-0xcf7: clean.
Feb 27 22:45:43 tabord-laptop kernel: [   12.723993] pcmcia_socket 
pcmcia_socket0: cs: IO port probe 0xa00-0xaff: clean.
Feb 27 22:45:43 tabord-laptop kernel: [   12.816328] 
ADDRCONF(NETDEV_UP): eth0: link is not ready
Feb 27 22:45:43 tabord-laptop kernel: [   12.817604] iwlagn 
0000:03:00.0: firmware: requesting lbm-iwlwifi-5000-2.ucode
Feb 27 22:45:43 tabord-laptop firmware.sh[1074]: Cannot find  firmware 
file 'lbm-iwlwifi-5000-2.ucode'
Feb 27 22:45:43 tabord-laptop kernel: [   12.860701] iwlagn 
0000:03:00.0: firmware: requesting lbm-iwlwifi-5000-1.ucode
Feb 27 22:45:43 tabord-laptop kernel: [   12.934285] Synaptics Touchpad, 
model: 1, fw: 7.0, id: 0x1c0b1, caps: 0xd04791/0xb00000
Feb 27 22:45:43 tabord-laptop kernel: [   12.934290] serio: Synaptics 
pass-through port at isa0060/serio1/input0
Feb 27 22:45:43 tabord-laptop kernel: [   12.949384] iwlagn 
0000:03:00.0: loaded firmware version 8.24.2.12
Feb 27 22:45:43 tabord-laptop kernel: [   13.000010] input: SynPS/2 
Synaptics TouchPad as /devices/platform/i8042/serio1/input/input14
Feb 27 22:45:43 tabord-laptop kernel: [   13.058703] usb 4-2: new full 
speed USB device using uhci_hcd and address 3
Feb 27 22:45:43 tabord-laptop kernel: [   13.098558] Registered led 
device: iwl-phy0::radio
Feb 27 22:45:43 tabord-laptop kernel: [   13.098574] Registered led 
device: iwl-phy0::assoc
Feb 27 22:45:43 tabord-laptop kernel: [   13.098588] Registered led 
device: iwl-phy0::RX
Feb 27 22:45:43 tabord-laptop kernel: [   13.098602] Registered led 
device: iwl-phy0::TX
Feb 27 22:45:43 tabord-laptop kernel: [   13.113982] 
ADDRCONF(NETDEV_UP): wlan0: link is not ready
Feb 27 22:45:43 tabord-laptop kernel: [   13.244215] usb 4-2: 
configuration #1 chosen from 1 choice
Feb 27 22:45:43 tabord-laptop kernel: [   13.250251] Bluetooth: Generic 
Bluetooth USB driver ver 0.5
Feb 27 22:45:43 tabord-laptop kernel: [   13.250354] usbcore: registered 
new interface driver btusb
Feb 27 22:45:43 tabord-laptop kernel: [   13.258142] Bluetooth: BNEP 
(Ethernet Emulation) ver 1.3
Feb 27 22:45:43 tabord-laptop kernel: [   13.258145] Bluetooth: BNEP 
filters: protocol multicast
Feb 27 22:45:43 tabord-laptop kernel: [   13.266938] Bridge firewalling 
registered
Feb 27 22:45:43 tabord-laptop kernel: [   13.305717] mtrr: no more MTRRs 
available
Feb 27 22:45:43 tabord-laptop kernel: [   13.305779] mtrr: no more MTRRs 
available
Feb 27 22:45:46 tabord-laptop kernel: [   15.624822] ppdev: user-space 
parallel port driver
Feb 27 22:45:50 tabord-laptop kernel: [   19.946284] IBM TrackPoint 
firmware: 0x0e, buttons: 3/3
Feb 27 22:45:50 tabord-laptop kernel: [   20.187113] input: TPPS/2 IBM 
TrackPoint as /devices/platform/i8042/serio1/serio2/input/input15
Feb 27 22:46:09 tabord-laptop kernel: [   39.433637] ata2: hard 
resetting link
Feb 27 22:46:10 tabord-laptop kernel: [   39.752569] ata2: SATA link up 
1.5 Gbps (SStatus 113 SControl 300)
Feb 27 22:46:11 tabord-laptop kernel: [   41.229208] ata2.00: configured 
for UDMA/33
Feb 27 22:46:11 tabord-laptop kernel: [   41.230460] ata2: EH complete
Feb 27 22:46:15 tabord-laptop kernel: [   44.733989] 
ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Feb 27 22:46:15 tabord-laptop kernel: [   44.734022] cfg80211: Calling 
CRDA for country: US
Feb 27 22:46:15 tabord-laptop kernel: [   44.735710] cfg80211: 
Regulatory domain: US
Feb 27 22:46:15 tabord-laptop kernel: [   44.735712]     (start_freq - 
end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735714]     (5170000 KHz - 
5190000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735716]     (5190000 KHz - 
5210000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735718]     (5210000 KHz - 
5230000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735720]     (5230000 KHz - 
5250000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735722]     (5735000 KHz - 
5755000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735724]     (5755000 KHz - 
5775000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735726]     (5775000 KHz - 
5795000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735727]     (5795000 KHz - 
5815000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735729]     (5815000 KHz - 
5835000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735732] cfg80211: 
Regulatory domain: US
Feb 27 22:46:15 tabord-laptop kernel: [   44.735733]     (start_freq - 
end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735735]     (2402000 KHz - 
2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735737]     (5170000 KHz - 
5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735739]     (5250000 KHz - 
5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735741]     (5490000 KHz - 
5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735743]     (5735000 KHz - 
5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735746] cfg80211: 
Regulatory domain: 98
Feb 27 22:46:15 tabord-laptop kernel: [   44.735747]     (start_freq - 
end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735749]     (5170000 KHz - 
5190000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735751]     (5190000 KHz - 
5210000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735753]     (5210000 KHz - 
5230000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735755]     (5230000 KHz - 
5250000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735756]     (5735000 KHz - 
5755000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735758]     (5755000 KHz - 
5775000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735760]     (5775000 KHz - 
5795000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735762]     (5795000 KHz - 
5815000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735764]     (5815000 KHz - 
5835000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735816] cfg80211: Current 
regulatory domain updated by AP to: US
Feb 27 22:46:15 tabord-laptop kernel: [   44.735817]     (start_freq - 
end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735819]     (5170000 KHz - 
5190000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735821]     (5190000 KHz - 
5210000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735823]     (5210000 KHz - 
5230000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735825]     (5230000 KHz - 
5250000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735827]     (5735000 KHz - 
5755000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735829]     (5755000 KHz - 
5775000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735830]     (5775000 KHz - 
5795000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735832]     (5795000 KHz - 
5815000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.735834]     (5815000 KHz - 
5835000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
Feb 27 22:46:15 tabord-laptop kernel: [   44.750717] padlock: VIA 
PadLock not detected.
Feb 27 22:46:35 tabord-laptop kernel: [   65.114067] iwlagn 
0000:03:00.0: iwl_tx_agg_start on ra = 00:1f:f3:f8:a1:db tid = 0


Dave


dave945 wrote:
> After unplugging eth0, wlan0 runs for about a minute then quits.  Here
> is the sequence of events:
>
> Feb 27 17:31:43 tabord-laptop kernel: [   47.352057] wlan0: no IPv6 routers present
> Feb 27 17:31:50 tabord-laptop kernel: [   54.364177] e1000e: eth0 NIC Link is Down
> Feb 27 17:31:50 tabord-laptop NetworkManager: <info>  (eth0): carrier now OFF (device state 8, deferring action for 4 seconds)
> Feb 27 17:31:55 tabord-laptop NetworkManager: <info>  (eth0): device state change: 8 -> 2 (reason 40)
> Feb 27 17:31:55 tabord-laptop NetworkManager: <info>  (eth0): deactivating device (reason: 40).
> Feb 27 17:31:55 tabord-laptop NetworkManager: <info>  (eth0): canceled DHCP transaction, dhcp client pid 1794
> Feb 27 17:31:55 tabord-laptop NetworkManager: <WARN>  check_one_route(): (eth0) error -34 returned from rtnl_route_del(): Sucess#012
> Feb 27 17:31:55 tabord-laptop avahi-daemon[968]: Withdrawing address record for 10.0.1.17 on eth0.
> Feb 27 17:31:55 tabord-laptop avahi-daemon[968]: Leaving mDNS multicast group on interface eth0.IPv4 with address 10.0.1.17.
> Feb 27 17:31:55 tabord-laptop avahi-daemon[968]: Interface eth0.IPv4 no longer relevant for mDNS.
> Feb 27 17:31:55 tabord-laptop NetworkManager: <info>  Policy set 'Auto Tabor (5 GHz)' (wlan0) as default for routing and DNS.
> Feb 27 17:32:04 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:32:28 tabord-laptop anacron[2450]: Anacron 2.3 started on 2010-02-27
> Feb 27 17:32:28 tabord-laptop anacron[2450]: Normal exit (0 jobs run)
> Feb 27 17:32:28 tabord-laptop kernel: [   91.927340] CPU0 attaching NULL sched-domain.
> Feb 27 17:32:28 tabord-laptop kernel: [   91.927344] CPU1 attaching NULL sched-domain.
> Feb 27 17:32:28 tabord-laptop kernel: [   91.934050] thinkpad_acpi: EC reports that Thermal Table has changed
> Feb 27 17:32:28 tabord-laptop kernel: [   91.940567] CPU0 attaching sched-domain:
> Feb 27 17:32:28 tabord-laptop kernel: [   91.940570]  domain 0: span 0-1 level MC
> Feb 27 17:32:28 tabord-laptop kernel: [   91.940572]   groups: 0 1
> Feb 27 17:32:28 tabord-laptop kernel: [   91.940576] CPU1 attaching sched-domain:
> Feb 27 17:32:28 tabord-laptop kernel: [   91.940577]  domain 0: span 0-1 level MC
> Feb 27 17:32:28 tabord-laptop kernel: [   91.940579]   groups: 1 0
> Feb 27 17:32:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:32:44 tabord-laptop wpa_supplicant[1356]: Trying to associate with 00:1f:f3:f8:a1:db (SSID='Tabor (5 GHz)' freq=5745 MHz)
> Feb 27 17:32:44 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  completed -> associating
> Feb 27 17:32:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
> Feb 27 17:32:44 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associating -> disconnected
> Feb 27 17:32:44 tabord-laptop kernel: [  107.648220] wlan0: deauthenticating from f8:1e:df:fa:e6:1c by local choice (reason=3)
> Feb 27 17:32:44 tabord-laptop kernel: [  107.651297] wlan0: direct probe to AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:32:44 tabord-laptop kernel: [  107.652818] wlan0: direct probe responded
> Feb 27 17:32:44 tabord-laptop kernel: [  107.652825] wlan0: authenticate with AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:32:44 tabord-laptop kernel: [  107.653295] wlan0: authenticated
> Feb 27 17:32:44 tabord-laptop wpa_supplicant[1356]: Associated with 00:1f:f3:f8:a1:db
> Feb 27 17:32:44 tabord-laptop kernel: [  107.672526] wlan0: associate with AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:32:44 tabord-laptop kernel: [  107.675404] wlan0: RX ReassocResp from 00:1f:f3:f8:a1:db (capab=0x511 status=0 aid=1)
> Feb 27 17:32:44 tabord-laptop kernel: [  107.675410] wlan0: associated
> Feb 27 17:32:44 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> associated
> Feb 27 17:32:44 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> 4-way handshake
> Feb 27 17:32:44 tabord-laptop wpa_supplicant[1356]: WPA: Key negotiation completed with 00:1f:f3:f8:a1:db [PTK=CCMP GTK=TKIP]
> Feb 27 17:32:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-CONNECTED - Connection to 00:1f:f3:f8:a1:db completed (reauth) [id=0 id_str=]
> Feb 27 17:32:44 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  4-way handshake -> group handshake
> Feb 27 17:32:44 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  group handshake -> completed
> Feb 27 17:32:47 tabord-laptop NetworkManager: <debug> [1267309967.003700] periodic_update(): Roamed from BSSID F8:1E:DF:FA:E6:1C (Tabor (5 GHz)) to 00:1F:F3:F8:A1:DB (Tabor (5 GHz))
> Feb 27 17:33:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 8 -> 3 (reason 0)
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): deactivating device (reason: 0).
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): canceled DHCP transaction, dhcp client pid 2302
> Feb 27 17:33:50 tabord-laptop NetworkManager: <WARN>  check_one_route(): (wlan0) error -34 returned from rtnl_route_del(): Sucess#012
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) starting connection 'Auto Tabor (5 GHz)'
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 3 -> 4 (reason 0)
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 4 -> 5 (reason 0)
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0/wireless): access point 'Auto Tabor (5 GHz)' has security, but secrets are required.
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 5 -> 6 (reason 0)
> Feb 27 17:33:50 tabord-laptop avahi-daemon[968]: Withdrawing address record for 10.0.1.10 on wlan0.
> Feb 27 17:33:50 tabord-laptop avahi-daemon[968]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.1.10.
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
> Feb 27 17:33:50 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  completed -> disconnected
> Feb 27 17:33:50 tabord-laptop kernel: [  174.228627] wlan0: deauthenticating from 00:1f:f3:f8:a1:db by local choice (reason=3)
> Feb 27 17:33:50 tabord-laptop kernel: [  174.228679] wlan0: deauthenticating from f8:1e:df:fa:e6:1c by local choice (reason=3)
> Feb 27 17:33:50 tabord-laptop avahi-daemon[968]: Interface wlan0.IPv4 no longer relevant for mDNS.
> Feb 27 17:33:50 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 6 -> 4 (reason 0)
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 4 -> 5 (reason 0)
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0/wireless): connection 'Auto Tabor (5 GHz)' has security, and secrets exist.  No new secrets needed.
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Config: added 'ssid' value 'Tabor (5 GHz)'
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Config: added 'scan_ssid' value '1'
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Config: added 'psk' value '<omitted>'
> Feb 27 17:33:50 tabord-laptop NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed
> Feb 27 17:33:50 tabord-laptop NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  Config: set interface ap_scan to 1
> Feb 27 17:33:50 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> scanning
> Feb 27 17:33:53 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:33:53 tabord-laptop wpa_supplicant[1356]: Trying to associate with 00:1f:f3:f8:a1:db (SSID='Tabor (5 GHz)' freq=5745 MHz)
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  scanning -> associating
> Feb 27 17:33:53 tabord-laptop kernel: [  177.406542] wlan0: deauthenticating from f8:1e:df:fa:e6:1c by local choice (reason=3)
> Feb 27 17:33:53 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:33:53 tabord-laptop kernel: [  177.458130] wlan0: direct probe to AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:33:53 tabord-laptop kernel: [  177.459624] wlan0: direct probe responded
> Feb 27 17:33:53 tabord-laptop kernel: [  177.459631] wlan0: authenticate with AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:33:53 tabord-laptop kernel: [  177.460124] wlan0: authenticated
> Feb 27 17:33:53 tabord-laptop kernel: [  177.460155] wlan0: associate with AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:33:53 tabord-laptop kernel: [  177.463039] wlan0: RX AssocResp from 00:1f:f3:f8:a1:db (capab=0x511 status=0 aid=1)
> Feb 27 17:33:53 tabord-laptop wpa_supplicant[1356]: Associated with 00:1f:f3:f8:a1:db
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associating -> associated
> Feb 27 17:33:53 tabord-laptop kernel: [  177.463045] wlan0: associated
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> 4-way handshake
> Feb 27 17:33:53 tabord-laptop wpa_supplicant[1356]: WPA: Key negotiation completed with 00:1f:f3:f8:a1:db [PTK=CCMP GTK=TKIP]
> Feb 27 17:33:53 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-CONNECTED - Connection to 00:1f:f3:f8:a1:db completed (reauth) [id=0 id_str=]
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  4-way handshake -> group handshake
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  group handshake -> completed
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'Tabor (5 GHz)'.
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 5 -> 7 (reason 0)
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds)
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  dhclient started with pid 2545
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled...
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started...
> Feb 27 17:33:53 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete.
> Feb 27 17:33:54 tabord-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.2
> Feb 27 17:33:54 tabord-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
> Feb 27 17:33:54 tabord-laptop dhclient: All rights reserved.
> Feb 27 17:33:54 tabord-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
> Feb 27 17:33:54 tabord-laptop dhclient: 
> Feb 27 17:33:54 tabord-laptop NetworkManager: <info>  DHCP: device wlan0 state changed normal exit -> preinit
> Feb 27 17:33:54 tabord-laptop dhclient: Listening on LPF/wlan0/00:1e:65:22:14:ae
> Feb 27 17:33:54 tabord-laptop dhclient: Sending on   LPF/wlan0/00:1e:65:22:14:ae
> Feb 27 17:33:54 tabord-laptop dhclient: Sending on   Socket/fallback
> Feb 27 17:33:57 tabord-laptop dhclient: DHCPREQUEST of 10.0.1.10 on wlan0 to 255.255.255.255 port 67
> Feb 27 17:33:58 tabord-laptop kernel: [  182.371787] CPU0 attaching NULL sched-domain.
> Feb 27 17:33:58 tabord-laptop kernel: [  182.371791] CPU1 attaching NULL sched-domain.
> Feb 27 17:33:58 tabord-laptop kernel: [  182.380196] thinkpad_acpi: EC reports that Thermal Table has changed
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384648] CPU0 attaching sched-domain:
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384651]  domain 0: span 0-1 level MC
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384653]   groups: 0 1
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384656]   domain 1: span 0-1 level CPU
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384658]    groups: 0-1 (__cpu_power = 2048)
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384662] CPU1 attaching sched-domain:
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384663]  domain 0: span 0-1 level MC
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384665]   groups: 1 0
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384668]   domain 1: span 0-1 level CPU
> Feb 27 17:33:58 tabord-laptop kernel: [  182.384670]    groups: 0-1 (__cpu_power = 2048)
> Feb 27 17:34:00 tabord-laptop dhclient: DHCPREQUEST of 10.0.1.10 on wlan0 to 255.255.255.255 port 67
> Feb 27 17:34:08 tabord-laptop dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3
> Feb 27 17:34:11 tabord-laptop dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7
> Feb 27 17:34:15 tabord-laptop kernel: [  199.048163] hub 6-0:1.0: port 2 disabled by hub (EMI?), re-enabling...
> Feb 27 17:34:15 tabord-laptop kernel: [  199.048175] usb 6-2: USB disconnect, address 2
> Feb 27 17:34:15 tabord-laptop kernel: [  199.316122] usb 6-2: new low speed USB device using uhci_hcd and address 3
> Feb 27 17:34:16 tabord-laptop kernel: [  199.498150] usb 6-2: configuration #1 chosen from 1 choice
> Feb 27 17:34:16 tabord-laptop kernel: [  199.544688] input: Microsoft Microsoft USB Wireless Mouse as /devices/pci0000:00/0000:00:1d.0/usb6/6-2/6-2:1.0/input/input16
> Feb 27 17:34:16 tabord-laptop kernel: [  199.544869] generic-usb 0003:045E:00B9.0002: input,hidraw0: USB HID v1.11 Mouse [Microsoft Microsoft USB Wireless Mouse] on usb-0000:00:1d.0-2/input0
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  (eth0): carrier now ON (device state 2)
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  (eth0): device state change: 2 -> 3 (reason 40)
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) starting connection 'Auto eth0'
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  (eth0): device state change: 3 -> 4 (reason 0)
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started...
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting...
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  (eth0): device state change: 4 -> 5 (reason 0)
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) successful.
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete.
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
> Feb 27 17:34:16 tabord-laptop kernel: [  200.068886] e1000e: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  (eth0): device state change: 5 -> 7 (reason 0)
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction (timeout in 45 seconds)
> Feb 27 17:34:16 tabord-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.2
> Feb 27 17:34:16 tabord-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
> Feb 27 17:34:16 tabord-laptop dhclient: All rights reserved.
> Feb 27 17:34:16 tabord-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
> Feb 27 17:34:16 tabord-laptop dhclient: 
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  dhclient started with pid 2605
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP6 Configure Get) scheduled...
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP6 Configure Get) started...
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP6 Configure Get) complete.
> Feb 27 17:34:16 tabord-laptop NetworkManager: <info>  DHCP: device eth0 state changed normal exit -> preinit
> Feb 27 17:34:16 tabord-laptop dhclient: Listening on LPF/eth0/00:24:7e:6a:f5:54
> Feb 27 17:34:16 tabord-laptop dhclient: Sending on   LPF/eth0/00:24:7e:6a:f5:54
> Feb 27 17:34:16 tabord-laptop dhclient: Sending on   Socket/fallback
> Feb 27 17:34:18 tabord-laptop dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7
> Feb 27 17:34:18 tabord-laptop dhclient: DHCPREQUEST of 10.0.1.17 on eth0 to 255.255.255.255 port 67
> Feb 27 17:34:18 tabord-laptop dhclient: DHCPACK of 10.0.1.17 from 10.0.1.1
> Feb 27 17:34:18 tabord-laptop dhclient: bound to 10.0.1.17 -- renewal in 39263 seconds.
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>  DHCP: device eth0 state changed preinit -> reboot
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>    address 10.0.1.17
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>    prefix 24 (255.255.255.0)
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>    gateway 10.0.1.1
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>    nameserver '10.0.1.1'
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>    domain name 'hsd1.md.comcast.net.'
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
> Feb 27 17:34:18 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
> Feb 27 17:34:18 tabord-laptop avahi-daemon[968]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.1.17.
> Feb 27 17:34:18 tabord-laptop avahi-daemon[968]: New relevant interface eth0.IPv4 for mDNS.
> Feb 27 17:34:18 tabord-laptop avahi-daemon[968]: Registering new address record for 10.0.1.17 on eth0.IPv4.
> Feb 27 17:34:19 tabord-laptop NetworkManager: <info>  (eth0): device state change: 7 -> 8 (reason 0)
> Feb 27 17:34:19 tabord-laptop NetworkManager: <info>  Policy set 'Auto eth0' (eth0) as default for routing and DNS.
> Feb 27 17:34:19 tabord-laptop NetworkManager: <info>  Activation (eth0) successful, device activated.
> Feb 27 17:34:19 tabord-laptop NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
> Feb 27 17:34:19 tabord-laptop ntpdate[2658]: adjust time server 91.189.94.4 offset 0.002359 sec
> Feb 27 17:34:25 tabord-laptop dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 9
> Feb 27 17:34:34 tabord-laptop dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 11
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 7 -> 3 (reason 0)
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): deactivating device (reason: 0).
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): canceled DHCP transaction, dhcp client pid 2545
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Policy set 'Auto eth0' (eth0) as default for routing and DNS.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) starting connection 'Auto Tabor (5 GHz)'
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 3 -> 4 (reason 0)
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 4 -> 5 (reason 0)
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0/wireless): connection 'Auto Tabor (5 GHz)' has security, and secrets exist.  No new secrets needed.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Config: added 'ssid' value 'Tabor (5 GHz)'
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Config: added 'scan_ssid' value '1'
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Config: added 'psk' value '<omitted>'
> Feb 27 17:34:37 tabord-laptop NetworkManager: nm_setting_802_1x_get_pkcs11_engine_path: assertion `NM_IS_SETTING_802_1X (setting)' failed
> Feb 27 17:34:37 tabord-laptop NetworkManager: nm_setting_802_1x_get_pkcs11_module_path: assertion `NM_IS_SETTING_802_1X (setting)' failed
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Config: set interface ap_scan to 1
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  completed -> disconnected
> Feb 27 17:34:37 tabord-laptop kernel: [  221.176581] wlan0: deauthenticating from 00:1f:f3:f8:a1:db by local choice (reason=3)
> Feb 27 17:34:37 tabord-laptop kernel: [  221.176632] wlan0: deauthenticating from f8:1e:df:fa:e6:1c by local choice (reason=3)
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> scanning
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: Failed to initiate AP scan.
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: Trying to associate with f8:1e:df:fa:e6:1c (SSID='Tabor (5 GHz)' freq=5745 MHz)
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  scanning -> associating
> Feb 27 17:34:37 tabord-laptop kernel: [  221.206141] wlan0: deauthenticating from f8:1e:df:fa:e6:1c by local choice (reason=3)
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:34:37 tabord-laptop kernel: [  221.320964] wlan0: direct probe to AP f8:1e:df:fa:e6:1c (try 1)
> Feb 27 17:34:37 tabord-laptop kernel: [  221.321873] wlan0: direct probe responded
> Feb 27 17:34:37 tabord-laptop kernel: [  221.321881] wlan0: authenticate with AP f8:1e:df:fa:e6:1c (try 1)
> Feb 27 17:34:37 tabord-laptop kernel: [  221.322960] wlan0: authenticated
> Feb 27 17:34:37 tabord-laptop kernel: [  221.322991] wlan0: associate with AP f8:1e:df:fa:e6:1c (try 1)
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: Associated with f8:1e:df:fa:e6:1c
> Feb 27 17:34:37 tabord-laptop kernel: [  221.325800] wlan0: RX AssocResp from f8:1e:df:fa:e6:1c (capab=0x511 status=0 aid=2)
> Feb 27 17:34:37 tabord-laptop kernel: [  221.325806] wlan0: associated
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associating -> associated
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> 4-way handshake
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: WPA: Key negotiation completed with f8:1e:df:fa:e6:1c [PTK=CCMP GTK=TKIP]
> Feb 27 17:34:37 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-CONNECTED - Connection to f8:1e:df:fa:e6:1c completed (reauth) [id=0 id_str=]
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  4-way handshake -> group handshake
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  group handshake -> completed
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'Tabor (5 GHz)'.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 5 -> 7 (reason 0)
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Beginning DHCP transaction (timeout in 45 seconds)
> Feb 27 17:34:37 tabord-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.2
> Feb 27 17:34:37 tabord-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
> Feb 27 17:34:37 tabord-laptop dhclient: All rights reserved.
> Feb 27 17:34:37 tabord-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
> Feb 27 17:34:37 tabord-laptop dhclient: 
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  dhclient started with pid 2677
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) scheduled...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) started...
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP6 Configure Get) complete.
> Feb 27 17:34:37 tabord-laptop NetworkManager: <info>  DHCP: device wlan0 state changed normal exit -> preinit
> Feb 27 17:34:37 tabord-laptop dhclient: Listening on LPF/wlan0/00:1e:65:22:14:ae
> Feb 27 17:34:37 tabord-laptop dhclient: Sending on   LPF/wlan0/00:1e:65:22:14:ae
> Feb 27 17:34:37 tabord-laptop dhclient: Sending on   Socket/fallback
> Feb 27 17:34:39 tabord-laptop dhclient: DHCPREQUEST of 10.0.1.10 on wlan0 to 255.255.255.255 port 67
> Feb 27 17:34:39 tabord-laptop dhclient: DHCPACK of 10.0.1.10 from 10.0.1.1
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>  DHCP: device wlan0 state changed preinit -> reboot
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>    address 10.0.1.10
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>    prefix 24 (255.255.255.0)
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>    gateway 10.0.1.1
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>    nameserver '10.0.1.1'
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>    domain name 'hsd1.md.comcast.net.'
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
> Feb 27 17:34:39 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
> Feb 27 17:34:39 tabord-laptop avahi-daemon[968]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.1.10.
> Feb 27 17:34:39 tabord-laptop avahi-daemon[968]: New relevant interface wlan0.IPv4 for mDNS.
> Feb 27 17:34:39 tabord-laptop avahi-daemon[968]: Registering new address record for 10.0.1.10 on wlan0.IPv4.
> Feb 27 17:34:39 tabord-laptop dhclient: bound to 10.0.1.10 -- renewal in 38431 seconds.
> Feb 27 17:34:40 tabord-laptop NetworkManager: <info>  Policy set 'Auto eth0' (eth0) as default for routing and DNS.
> Feb 27 17:34:40 tabord-laptop NetworkManager: <info>  (wlan0): device state change: 7 -> 8 (reason 0)
> Feb 27 17:34:40 tabord-laptop NetworkManager: <debug> [1267310080.053171] periodic_update(): Roamed from BSSID 00:1F:F3:F8:A1:DB (Tabor (5 GHz)) to F8:1E:DF:FA:E6:1C (Tabor (5 GHz))
> Feb 27 17:34:40 tabord-laptop NetworkManager: <info>  Activation (wlan0) successful, device activated.
> Feb 27 17:34:40 tabord-laptop NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
> Feb 27 17:34:40 tabord-laptop ntpdate[2729]: adjust time server 91.189.94.4 offset -0.001126 sec
> Feb 27 17:35:04 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:35:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:35:47 tabord-laptop kernel: [  291.248225] e1000e: eth0 NIC Link is Down
> Feb 27 17:35:47 tabord-laptop NetworkManager: <info>  (eth0): carrier now OFF (device state 8, deferring action for 4 seconds)
> Feb 27 17:35:51 tabord-laptop NetworkManager: <info>  (eth0): device state change: 8 -> 2 (reason 40)
> Feb 27 17:35:52 tabord-laptop NetworkManager: <info>  (eth0): deactivating device (reason: 40).
> Feb 27 17:35:52 tabord-laptop NetworkManager: <info>  (eth0): canceled DHCP transaction, dhcp client pid 2605
> Feb 27 17:35:52 tabord-laptop NetworkManager: <WARN>  check_one_route(): (eth0) error -34 returned from rtnl_route_del(): Sucess#012
> Feb 27 17:35:52 tabord-laptop avahi-daemon[968]: Withdrawing address record for 10.0.1.17 on eth0.
> Feb 27 17:35:52 tabord-laptop avahi-daemon[968]: Leaving mDNS multicast group on interface eth0.IPv4 with address 10.0.1.17.
> Feb 27 17:35:52 tabord-laptop NetworkManager: <info>  Policy set 'Auto Tabor (5 GHz)' (wlan0) as default for routing and DNS.
> Feb 27 17:35:52 tabord-laptop avahi-daemon[968]: Interface eth0.IPv4 no longer relevant for mDNS.
> Feb 27 17:36:30 tabord-laptop kernel: [  333.528636] iwlagn 0000:03:00.0: iwl_tx_agg_start on ra = f8:1e:df:fa:e6:1c tid = 0
> Feb 27 17:36:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:38:04 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:38:04 tabord-laptop wpa_supplicant[1356]: Trying to associate with 00:1f:f3:f8:a1:db (SSID='Tabor (5 GHz)' freq=5745 MHz)
> Feb 27 17:38:04 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  completed -> associating
> Feb 27 17:38:04 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
> Feb 27 17:38:04 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associating -> disconnected
> Feb 27 17:38:04 tabord-laptop kernel: [  427.760256] wlan0: deauthenticating from f8:1e:df:fa:e6:1c by local choice (reason=3)
> Feb 27 17:38:04 tabord-laptop kernel: [  427.761411] wlan0: direct probe to AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:38:04 tabord-laptop kernel: [  427.765428] wlan0: direct probe responded
> Feb 27 17:38:04 tabord-laptop kernel: [  427.765435] wlan0: authenticate with AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:38:04 tabord-laptop kernel: [  427.771190] wlan0: authenticated
> Feb 27 17:38:04 tabord-laptop kernel: [  427.784253] wlan0: associate with AP 00:1f:f3:f8:a1:db (try 1)
> Feb 27 17:38:04 tabord-laptop wpa_supplicant[1356]: Associated with 00:1f:f3:f8:a1:db
> Feb 27 17:38:04 tabord-laptop kernel: [  427.787131] wlan0: RX ReassocResp from 00:1f:f3:f8:a1:db (capab=0x511 status=0 aid=1)
> Feb 27 17:38:04 tabord-laptop kernel: [  427.787134] wlan0: associated
> Feb 27 17:38:04 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> associated
> Feb 27 17:38:04 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> 4-way handshake
> Feb 27 17:38:04 tabord-laptop wpa_supplicant[1356]: WPA: Key negotiation completed with 00:1f:f3:f8:a1:db [PTK=CCMP GTK=TKIP]
> Feb 27 17:38:04 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-CONNECTED - Connection to 00:1f:f3:f8:a1:db completed (reauth) [id=0 id_str=]
> Feb 27 17:38:04 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  4-way handshake -> group handshake
> Feb 27 17:38:04 tabord-laptop NetworkManager: <info>  (wlan0): supplicant connection state:  group handshake -> completed
> Feb 27 17:38:05 tabord-laptop NetworkManager: <debug> [1267310285.001873] periodic_update(): Roamed from BSSID F8:1E:DF:FA:E6:1C (Tabor (5 GHz)) to 00:1F:F3:F8:A1:DB (Tabor (5 GHz))
> Feb 27 17:38:49 tabord-laptop kernel: [  472.846477] thinkpad_acpi: EC reports that Thermal Table has changed
> Feb 27 17:38:49 tabord-laptop anacron[2833]: Anacron 2.3 started on 2010-02-27
> Feb 27 17:38:49 tabord-laptop anacron[2833]: Normal exit (0 jobs run)
> Feb 27 17:38:49 tabord-laptop kernel: [  473.080706] CPU0 attaching NULL sched-domain.
> Feb 27 17:38:49 tabord-laptop kernel: [  473.080717] CPU1 attaching NULL sched-domain.
> Feb 27 17:38:49 tabord-laptop kernel: [  473.092683] CPU0 attaching sched-domain:
> Feb 27 17:38:49 tabord-laptop kernel: [  473.092690]  domain 0: span 0-1 level MC
> Feb 27 17:38:49 tabord-laptop kernel: [  473.092696]   groups: 0 1
> Feb 27 17:38:49 tabord-laptop kernel: [  473.092708] CPU1 attaching sched-domain:
> Feb 27 17:38:49 tabord-laptop kernel: [  473.092712]  domain 0: span 0-1 level MC
> Feb 27 17:38:49 tabord-laptop kernel: [  473.092718]   groups: 1 0
> Feb 27 17:39:44 tabord-laptop wpa_supplicant[1356]: CTRL-EVENT-SCAN-RESULTS 
> Feb 27 17:40:57 tabord-laptop kernel: [  601.288300] iwlagn 0000:03:00.0: iwl_tx_agg_start on ra = 00:1f:f3:f8:a1:db tid = 0
>
>

-- 
Intel iwlagn driver hangs from time to time
https://bugs.launchpad.net/bugs/518196
You received this bug notification because you are a member of Kernel
Bugs, which is subscribed to linux in ubuntu.




More information about the kernel-bugs mailing list