--- Begin Message ---
- To: Debian Bug Tracking System <submit@bugs.debian.org>
- Subject: linux-image-3.2.0-4-686-pae: Failure to connect with wireless on 3.2 kernel using AR5416 card. Works on 2.6 kernel.
- From: Ehren Brav <ehrenbrav@yahoo.com>
- Date: Fri, 26 Jul 2013 09:39:11 -0700
- Message-id: <20130726163911.3608.90166.reportbug@localhost.localdomain>
Package: src:linux
Version: 3.2.46-1
Severity: normal
When booting my machine from a cold start each day, if I boot to 3.2, I get
endless requests from NetworkManager to enter the network password. I have a
workaround that consistently works to resolve this issue:
1. I reboot the machine into 2.6.
2. Usually, NetworkManager automatically connects to the network, though
sometimes I need to disable/enable wireless before it will do this.
3. I reboot into 3.2, and it automatically connects, every time.
I believe this is an issue with either ath9k or other wireless modules used by
the kernel (mac80211?). This issue first appeared when I upgraded from squeeze
to wheezy (and thus from 2.6 to 3.2).
My wireless card is an AR5416. I'm attaching an annotated sampling of the
syslog that shows the log messages from the 3.2 failure to connect, the reboot,
and then the 2.6 successful connection.
-- Package-specific info:
** Version:
Linux version 3.2.0-4-686-pae (debian-kernel@lists.debian.org) (gcc version
4.6.3 (Debian 4.6.3-14) ) #1 SMP Debian 3.2.46-1
** Command line:
BOOT_IMAGE=/boot/vmlinuz-3.2.0-4-686-pae root=UUID=e178931c-5592-47dd-91fc-
1a0d1b79fc7d ro quiet
** Not tainted
** Kernel log:
[ 133.602071] wlan0: associate with 00:24:c4:d2:f3:91 (try 1)
[ 133.608573] wlan0: RX ReassocResp from 00:24:c4:d2:f3:91 (capab=0x431
status=0 aid=19)
[ 133.608585] wlan0: associated
[ 133.614386] cfg80211: Calling CRDA for country: US
[ 133.634246] cfg80211: Regulatory domain changed to country: US
[ 133.634258] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 133.634270] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2700 mBm)
[ 133.634280] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
1700 mBm)
[ 133.634290] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 133.634299] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 133.634309] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 133.634318] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
3000 mBm)
[ 193.377440] cfg80211: Calling CRDA to update world regulatory domain
[ 193.396160] cfg80211: World regulatory domain updated:
[ 193.396172] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 193.396183] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 193.396193] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 193.396203] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 193.396212] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 193.396222] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 193.597869] wlan0: authenticate with 00:24:c4:d2:f3:91 (try 1)
[ 193.627981] wlan0: authenticated
[ 193.633415] wlan0: associate with 00:24:c4:d2:f3:91 (try 1)
[ 193.665545] wlan0: RX ReassocResp from 00:24:c4:d2:f3:91 (capab=0x431
status=0 aid=19)
[ 193.665558] wlan0: associated
[ 193.671116] cfg80211: Calling CRDA for country: US
[ 193.690856] cfg80211: Regulatory domain changed to country: US
[ 193.690869] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 193.690880] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2700 mBm)
[ 193.690890] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
1700 mBm)
[ 193.690899] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 193.690909] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 193.690918] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 193.690928] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
3000 mBm)
[ 240.352352] cfg80211: Calling CRDA to update world regulatory domain
[ 240.377552] cfg80211: World regulatory domain updated:
[ 240.377564] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 240.377576] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 240.377586] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 240.377596] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 240.377606] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 240.377616] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 240.711884] wlan0: authenticate with 00:24:c4:d2:f4:21 (try 1)
[ 240.739274] wlan0: authenticated
[ 240.752349] wlan0: associate with 00:24:c4:d2:f4:21 (try 1)
[ 240.782039] wlan0: RX ReassocResp from 00:24:c4:d2:f4:21 (capab=0x431
status=0 aid=83)
[ 240.782052] wlan0: associated
[ 240.790130] cfg80211: Calling CRDA for country: US
[ 240.810738] cfg80211: Regulatory domain changed to country: US
[ 240.810750] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 240.810762] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2700 mBm)
[ 240.810772] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
1700 mBm)
[ 240.810781] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 240.810791] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 240.810801] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 240.810811] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
3000 mBm)
[ 273.373547] cfg80211: Calling CRDA to update world regulatory domain
[ 273.396701] cfg80211: World regulatory domain updated:
[ 273.396713] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 273.396725] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 273.396734] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 273.396744] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 273.396753] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 273.396763] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 273.652627] wlan0: authenticate with 00:24:c4:d2:f4:c1 (try 1)
[ 273.674381] wlan0: authenticated
[ 273.684616] wlan0: associate with 00:24:c4:d2:f4:c1 (try 1)
[ 273.712326] wlan0: RX ReassocResp from 00:24:c4:d2:f4:c1 (capab=0x431
status=0 aid=12)
[ 273.712337] wlan0: associated
[ 273.720290] cfg80211: Calling CRDA for country: US
[ 273.737660] cfg80211: Regulatory domain changed to country: US
[ 273.737672] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 273.737684] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2700 mBm)
[ 273.737694] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
1700 mBm)
[ 273.737703] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 273.737713] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 273.737722] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 273.737732] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
3000 mBm)
[ 304.349953] cfg80211: Calling CRDA to update world regulatory domain
[ 304.372856] cfg80211: World regulatory domain updated:
[ 304.372868] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 304.372879] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 304.372889] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 304.372898] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi,
2000 mBm)
[ 304.372908] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 304.372917] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 304.569305] wlan0: authenticate with 00:24:c4:d2:f3:f1 (try 1)
[ 304.599090] wlan0: authenticated
[ 304.607276] wlan0: associate with 00:24:c4:d2:f3:f1 (try 1)
[ 304.642195] wlan0: RX ReassocResp from 00:24:c4:d2:f3:f1 (capab=0x431
status=0 aid=62)
[ 304.642208] wlan0: associated
[ 304.647838] cfg80211: Calling CRDA for country: US
[ 304.667499] cfg80211: Regulatory domain changed to country: US
[ 304.667511] cfg80211: (start_freq - end_freq @ bandwidth),
(max_antenna_gain, max_eirp)
[ 304.667522] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi,
2700 mBm)
[ 304.667532] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi,
1700 mBm)
[ 304.667541] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 304.667551] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 304.667560] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi,
2000 mBm)
[ 304.667570] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi,
3000 mBm)
** Model information
not available
** Loaded modules:
cryptd
aes_i586
aes_generic
speedstep_lib
cpufreq_conservative
cpufreq_powersave
cpufreq_userspace
cpufreq_stats
binfmt_misc
uinput
fuse
nfsd
nfs
nfs_acl
auth_rpcgss
fscache
lockd
sunrpc
loop
arc4
ath9k
ath9k_common
ath9k_hw
radeon
ath
mac80211
snd_intel8x0
cfg80211
pcmcia
snd_intel8x0m
snd_ac97_codec
ttm
drm_kms_helper
drm
i2c_algo_bit
snd_pcm
iTCO_wdt
snd_page_alloc
snd_seq
snd_seq_device
acpi_cpufreq
yenta_socket
rfkill
pcmcia_rsrc
pcmcia_core
mperf
joydev
snd_timer
snd
iTCO_vendor_support
i2c_core
battery
ac
parport_pc
rng_core
psmouse
dcdbas
soundcore
ac97_bus
serio_raw
power_supply
parport
pcspkr
video
processor
button
shpchp
evdev
ext3
mbcache
jbd
sg
usbhid
hid
sd_mod
sr_mod
crc_t10dif
cdrom
ata_generic
ata_piix
libata
floppy
thermal
thermal_sys
uhci_hcd
ehci_hcd
scsi_mod
usbcore
3c59x
mii
usb_common
** Network interface configuration:
# This file describes the network interfaces available on your system
# and how to activate them. For more information, see interfaces(5).
# The loopback network interface
auto lo
iface lo inet loopback
** Network status:
*** IP interfaces and addresses:
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436 qdisc noqueue state UNKNOWN
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 scope host lo
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
2: eth0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc pfifo_fast state
DOWN qlen 1000
link/ether 00:0b:db:02:0a:0d brd ff:ff:ff:ff:ff:ff
3: wlan0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP qlen
1000
link/ether 00:1d:6a:60:42:8c brd ff:ff:ff:ff:ff:ff
inet 192.168.54.58/22 brd 192.168.55.255 scope global wlan0
inet6 fe80::21d:6aff:fe60:428c/64 scope link
valid_lft forever preferred_lft forever
*** Device statistics:
Inter-| Receive | Transmit
face |bytes packets errs drop fifo frame compressed multicast|bytes
packets errs drop fifo colls carrier compressed
lo: 1296 20 0 0 0 0 0 0 1296
20 0 0 0 0 0 0
wlan0: 1377341 1014 0 0 0 0 0 0 56513
376 0 0 0 0 0 0
eth0: 0 0 0 0 0 0 0 0 0
0 0 0 0 0 0 0
*** Protocol statistics:
Ip:
960 total packets received
1 with invalid addresses
0 forwarded
0 incoming packets discarded
959 incoming packets delivered
326 requests sent out
Icmp:
8 ICMP messages received
0 input ICMP message failed.
ICMP input histogram:
destination unreachable: 8
8 ICMP messages sent
0 ICMP messages failed
ICMP output histogram:
destination unreachable: 8
IcmpMsg:
InType3: 8
OutType3: 8
Tcp:
5 active connections openings
0 passive connection openings
2 failed connection attempts
0 connection resets received
0 connections established
928 segments received
291 segments send out
2 segments retransmited
0 bad segments received.
2 resets sent
Udp:
17 packets received
8 packets to unknown port received.
0 packet receive errors
25 packets sent
UdpLite:
TcpExt:
1 TCP sockets finished time wait in fast timer
1 delayed acks sent
Quick ack mode was activated 4 times
868 packet headers predicted
16 acknowledgments not containing data payload received
1 predicted acknowledgments
2 congestion windows recovered without slow start after partial ack
2 other TCP timeouts
4 DSACKs sent for old packets
2 DSACKs received
TCPSackShiftFallback: 2
IpExt:
InMcastPkts: 9
OutMcastPkts: 11
InOctets: 1359138
OutOctets: 35846
InMcastOctets: 2997
OutMcastOctets: 3077
** PCI devices:
00:00.0 Host bridge [0600]: Intel Corporation 82845 845 [Brookdale] Chipset Host Bridge [8086:1a30] (rev 04)
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ >SERR- <PERR- INTx-
Latency: 0
Region 0: Memory at e8000000 (32-bit, prefetchable) [size=64M]
Capabilities: <access denied>
Kernel driver in use: agpgart-intel
00:01.0 PCI bridge [0604]: Intel Corporation 82845 845 [Brookdale] Chipset AGP Bridge [8086:1a31] (rev 04) (prog-if 00 [Normal decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32
Bus: primary=00, secondary=01, subordinate=01, sec-latency=32
I/O behind bridge: 0000c000-0000cfff
Memory behind bridge: fc000000-fdffffff
Prefetchable memory behind bridge: e0000000-e7ffffff
Secondary status: 66MHz+ FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
BridgeCtl: Parity- SERR+ NoISA+ VGA+ MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
00:1d.0 USB controller [0c03]: Intel Corporation 82801CA/CAM USB Controller #1 [8086:2482] (rev 02) (prog-if 00 [UHCI])
Subsystem: Intel Corporation Latitude C640 [8086:4541]
Physical Slot: 1
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin A routed to IRQ 11
Region 4: I/O ports at bf80 [size=32]
Kernel driver in use: uhci_hcd
00:1e.0 PCI bridge [0604]: Intel Corporation 82801 Mobile PCI Bridge [8086:2448] (rev 42) (prog-if 00 [Normal decode])
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Bus: primary=00, secondary=02, subordinate=10, sec-latency=32
I/O behind bridge: 0000e000-0000ffff
Memory behind bridge: f4000000-fbffffff
Prefetchable memory behind bridge: 34000000-3bffffff
Secondary status: 66MHz- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
BridgeCtl: Parity- SERR+ NoISA+ VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
00:1f.0 ISA bridge [0601]: Intel Corporation 82801CAM ISA Bridge (LPC) [8086:248c] (rev 02)
Control: I/O+ Mem+ BusMaster+ SpecCycle+ MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
00:1f.1 IDE interface [0101]: Intel Corporation 82801CAM IDE U100 Controller [8086:248a] (rev 02) (prog-if 8a [Master SecP PriP])
Subsystem: Intel Corporation Latitude C640 [8086:4541]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin A routed to IRQ 11
Region 0: I/O ports at 01f0 [size=8]
Region 1: I/O ports at 03f4 [size=1]
Region 2: I/O ports at 0170 [size=8]
Region 3: I/O ports at 0374 [size=1]
Region 4: I/O ports at bfa0 [size=16]
Region 5: Memory at 30000000 (32-bit, non-prefetchable) [size=1K]
Kernel driver in use: ata_piix
00:1f.5 Multimedia audio controller [0401]: Intel Corporation 82801CA/CAM AC'97 Audio Controller [8086:2485] (rev 02)
Subsystem: Cirrus Logic Crystal WMD Audio Codec [1013:5959]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin B routed to IRQ 5
Region 0: I/O ports at d800 [size=256]
Region 1: I/O ports at dc80 [size=64]
Kernel driver in use: snd_intel8x0
00:1f.6 Modem [0703]: Intel Corporation 82801CA/CAM AC'97 Modem Controller [8086:2486] (rev 02) (prog-if 00 [Generic])
Subsystem: PCTel Inc Dell Inspiron 2100 internal modem [134d:4c21]
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin B routed to IRQ 5
Region 0: I/O ports at d400 [size=256]
Region 1: I/O ports at dc00 [size=128]
Kernel driver in use: snd_intel8x0m
01:00.0 VGA compatible controller [0300]: Advanced Micro Devices [AMD] nee ATI RV200 [Mobility Radeon 7500] [1002:4c57] (prog-if 00 [VGA controller])
Subsystem: Dell Latitude C640 [1028:012a]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop+ ParErr- Stepping+ SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (2000ns min), Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 11
Region 0: Memory at e0000000 (32-bit, prefetchable) [size=128M]
Region 1: I/O ports at c000 [size=256]
Region 2: Memory at fcff0000 (32-bit, non-prefetchable) [size=64K]
[virtual] Expansion ROM at fc000000 [disabled] [size=128K]
Capabilities: <access denied>
Kernel driver in use: radeon
02:00.0 Ethernet controller [0200]: 3Com Corporation 3c905C-TX/TX-M [Tornado] [10b7:9200] (rev 78)
Subsystem: Dell 3C920 Integrated Fast Ethernet Controller [Latitude C640] [1028:012a]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 32 (2500ns min, 2500ns max), Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 11
Region 0: I/O ports at ec80 [size=128]
Region 1: Memory at f8fffc00 (32-bit, non-prefetchable) [size=128]
Expansion ROM at f9000000 [disabled] [size=128K]
Capabilities: <access denied>
Kernel driver in use: 3c59x
02:01.0 CardBus bridge [0607]: Texas Instruments PCI1420 PC card Cardbus Controller [104c:ac51]
Subsystem: Dell Latitude C640 [1028:012a]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 168, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 11
Region 0: Memory at f4000000 (32-bit, non-prefetchable) [size=4K]
Bus: primary=02, secondary=03, subordinate=06, sec-latency=176
Memory window 0: 38000000-3bffffff (prefetchable)
Memory window 1: 40000000-43ffffff
I/O window 0: 0000f000-0000f0ff
I/O window 1: 0000e800-0000e8ff
BridgeCtl: Parity- SERR- ISA- VGA- MAbort- >Reset+ 16bInt+ PostWrite+
16-bit legacy interface ports at 0001
Kernel driver in use: yenta_cardbus
02:01.1 CardBus bridge [0607]: Texas Instruments PCI1420 PC card Cardbus Controller [104c:ac51]
Subsystem: Dell Latitude C640 [1028:012a]
Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 168, Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 11
Region 0: Memory at f8000000 (32-bit, non-prefetchable) [size=4K]
Bus: primary=02, secondary=07, subordinate=0a, sec-latency=176
Memory window 0: 34000000-37ffffff (prefetchable)
Memory window 1: 3c000000-3fffffff
I/O window 0: 0000e400-0000e4ff
I/O window 1: 0000e000-0000e0ff
BridgeCtl: Parity- SERR- ISA- VGA- MAbort- >Reset+ 16bInt+ PostWrite+
16-bit legacy interface ports at 0001
Kernel driver in use: yenta_cardbus
02:03.0 Network controller [0280]: Atheros Communications Inc. AR5416 Wireless Network Adapter [AR5008 802.11(a)bgn] [168c:0023] (rev 01)
Subsystem: Atheros Communications Inc. Device [168c:2071]
Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 168, Cache Line Size: 32 bytes
Interrupt: pin A routed to IRQ 5
Region 0: Memory at f8fe0000 (32-bit, non-prefetchable) [size=64K]
Capabilities: <access denied>
Kernel driver in use: ath9k
** USB devices:
Bus 001 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 002: ID 413c:3010 Dell Computer Corp. Optical Wheel Mouse
-- System Information:
Debian Release: 7.1
APT prefers stable-updates
APT policy: (500, 'stable-updates'), (500, 'stable'), (500, 'oldstable')
Architecture: i386 (i686)
Kernel: Linux 3.2.0-4-686-pae (SMP w/1 CPU core)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages linux-image-3.2.0-4-686-pae depends on:
ii debconf [debconf-2.0] 1.5.49
ii initramfs-tools [linux-initramfs-tool] 0.109.1
ii kmod 9-3
ii linux-base 3.5
ii module-init-tools 9-3
Versions of packages linux-image-3.2.0-4-686-pae recommends:
ii firmware-linux-free 3.2
ii libc6-i686 2.13-38
Versions of packages linux-image-3.2.0-4-686-pae suggests:
pn debian-kernel-handbook <none>
ii grub-pc 1.99-27+deb7u1
pn linux-doc-3.2 <none>
Versions of packages linux-image-3.2.0-4-686-pae is related to:
ii firmware-atheros 0.36+wheezy.1
pn firmware-bnx2 <none>
pn firmware-bnx2x <none>
pn firmware-brcm80211 <none>
pn firmware-intelwimax <none>
pn firmware-ipw2x00 <none>
pn firmware-ivtv <none>
pn firmware-iwlwifi <none>
pn firmware-libertas <none>
pn firmware-linux <none>
ii firmware-linux-nonfree 0.36+wheezy.1
pn firmware-myricom <none>
pn firmware-netxen <none>
pn firmware-qlogic <none>
pn firmware-ralink <none>
pn firmware-realtek <none>
pn xen-hypervisor <none>
-- debconf information:
linux-image-3.2.0-4-686-pae/postinst/depmod-error-initrd-3.2.0-4-686-pae: false
linux-image-3.2.0-4-686-pae/prerm/removing-running-kernel-3.2.0-4-686-pae: true
linux-image-3.2.0-4-686-pae/postinst/missing-firmware-3.2.0-4-686-pae:
linux-image-3.2.0-4-686-pae/postinst/ignoring-ramdisk:
#########
This is the log from the machine booting into
the 3.2 kernel, failing to connect (repeatedly
asking for authentication), then rebooting into
2.6 and connecting.
#########
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Auto-activating connection 'RAPTOR'.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) starting connection 'RAPTOR'
Jul 24 08:18:34 castor NetworkManager[3047]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0/wireless): access point 'RAPTOR' has security, but secrets are required.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jul 24 08:18:34 castor NetworkManager[3047]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0/wireless): connection 'RAPTOR' has security, and secrets exist. No new secrets needed.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Config: added 'ssid' value 'RAPTOR'
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Config: added 'scan_ssid' value '1'
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Config: added 'auth_alg' value 'OPEN'
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Config: added 'psk' value '<omitted>'
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jul 24 08:18:34 castor NetworkManager[3047]: <info> Config: set interface ap_scan to 1
Jul 24 08:18:34 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jul 24 08:18:34 castor rtkit-daemon[3660]: Successfully made thread 3683 of process 3683 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Jul 24 08:18:34 castor rtkit-daemon[3660]: Supervising 4 threads of 2 processes of 1 users.
Jul 24 08:18:34 castor pulseaudio[3683]: [pulseaudio] pid.c: Daemon already running.
##############
Here's where the
authentication failure
happens.
#############
Jul 24 08:18:35 castor wpa_supplicant[3122]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f4:21 (SSID='RAPTOR' freq=2412 MHz)
Jul 24 08:18:35 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jul 24 08:18:35 castor kernel: [ 545.209710] wlan0: direct probe to 00:24:c4:d2:f4:21 (try 1/3)
Jul 24 08:18:35 castor kernel: [ 545.408154] wlan0: direct probe to 00:24:c4:d2:f4:21 (try 2/3)
Jul 24 08:18:35 castor kernel: [ 545.608146] wlan0: direct probe to 00:24:c4:d2:f4:21 (try 3/3)
Jul 24 08:18:35 castor kernel: [ 545.808203] wlan0: direct probe to 00:24:c4:d2:f4:21 timed out
Jul 24 08:18:36 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jul 24 08:18:36 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jul 24 08:18:36 castor wpa_supplicant[3122]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f4:21 (SSID='RAPTOR' freq=2412 MHz)
Jul 24 08:18:36 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jul 24 08:18:36 castor kernel: [ 546.984805] wlan0: direct probe to 00:24:c4:d2:f4:21 (try 1/3)
Jul 24 08:18:37 castor kernel: [ 547.184188] wlan0: direct probe to 00:24:c4:d2:f4:21 (try 2/3)
Jul 24 08:18:37 castor kernel: [ 547.384091] wlan0: direct probe to 00:24:c4:d2:f4:21 (try 3/3)
Jul 24 08:18:37 castor kernel: [ 547.584078] wlan0: direct probe to 00:24:c4:d2:f4:21 timed out
Jul 24 08:18:37 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jul 24 08:18:38 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jul 24 08:18:38 castor wpa_supplicant[3122]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f3:91 (SSID='RAPTOR' freq=2462 MHz)
Jul 24 08:18:38 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jul 24 08:18:38 castor kernel: [ 548.198419] wlan0: direct probe to 00:24:c4:d2:f3:91 (try 1/3)
Jul 24 08:18:38 castor kernel: [ 548.396124] wlan0: direct probe to 00:24:c4:d2:f3:91 (try 2/3)
Jul 24 08:18:38 castor kernel: [ 548.596125] wlan0: direct probe to 00:24:c4:d2:f3:91 (try 3/3)
Jul 24 08:18:38 castor kernel: [ 548.796137] wlan0: direct probe to 00:24:c4:d2:f3:91 timed out
Jul 24 08:18:38 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jul 24 08:18:38 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jul 24 08:18:39 castor wpa_supplicant[3122]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f4:c1 (SSID='RAPTOR' freq=2437 MHz)
Jul 24 08:18:39 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jul 24 08:18:39 castor kernel: [ 549.577965] wlan0: direct probe to 00:24:c4:d2:f4:c1 (try 1/3)
Jul 24 08:18:39 castor kernel: [ 549.776139] wlan0: direct probe to 00:24:c4:d2:f4:c1 (try 2/3)
Jul 24 08:18:39 castor kernel: [ 549.976181] wlan0: direct probe to 00:24:c4:d2:f4:c1 (try 3/3)
Jul 24 08:18:40 castor kernel: [ 550.176126] wlan0: direct probe to 00:24:c4:d2:f4:c1 timed out
Jul 24 08:18:40 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jul 24 08:18:40 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jul 24 08:18:40 castor wpa_supplicant[3122]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f3:91 (SSID='RAPTOR' freq=2462 MHz)
Jul 24 08:18:40 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jul 24 08:18:40 castor kernel: [ 550.959408] wlan0: direct probe to 00:24:c4:d2:f3:91 (try 1/3)
Jul 24 08:18:41 castor kernel: [ 551.156112] wlan0: direct probe to 00:24:c4:d2:f3:91 (try 2/3)
Jul 24 08:18:41 castor kernel: [ 551.356080] wlan0: direct probe to 00:24:c4:d2:f3:91 (try 3/3)
Jul 24 08:18:41 castor kernel: [ 551.556120] wlan0: direct probe to 00:24:c4:d2:f3:91 timed out
Jul 24 08:18:41 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jul 24 08:18:41 castor NetworkManager[3047]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jul 24 08:18:41 castor wpa_supplicant[3122]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f4:21 (SSID='RAPTOR' freq=2412 MHz)
#########
....more of the same...
#########
#########
Here's where I
reboot the system
into the 2.6 kernel
#########
Jul 24 08:19:23 castor shutdown[3713]: shutting down for system reboot
Jul 24 08:19:23 castor init: Switching to runlevel: 6
Jul 24 08:19:25 castor avahi-daemon[2590]: Got SIGTERM, quitting.
Jul 24 08:19:25 castor avahi-daemon[2590]: avahi-daemon 0.6.31 exiting.
Jul 24 08:19:25 castor kernel: [ 595.619702] fuse exit
Jul 24 08:19:26 castor NetworkManager[3047]: dbus_g_proxy_cancel_call: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed
Jul 24 08:19:26 castor NetworkManager[3047]: dbus_g_proxy_call_no_reply: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed
Jul 24 08:19:26 castor NetworkManager[3047]: <warn> No agents were available for this request.
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): device state change: need-auth -> failed (reason 'no-secrets') [60 120 7]
Jul 24 08:19:26 castor NetworkManager[3047]: <warn> Activation (wlan0) failed for access point (RAPTOR)
Jul 24 08:19:26 castor NetworkManager[3047]: <info> Marking connection 'RAPTOR' invalid.
Jul 24 08:19:26 castor NetworkManager[3047]: <warn> Activation (wlan0) failed.
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): deactivating device (reason 'none') [0]
Jul 24 08:19:26 castor minissdpd[3124]: received signal 15, good-bye
Jul 24 08:19:26 castor NetworkManager[3047]: <info> caught signal 15, shutting down normally.
Jul 24 08:19:26 castor NetworkManager[3047]: <warn> quit request received, terminating...
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (eth0): now unmanaged
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (eth0): cleaning up...
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (eth0): taking down device.
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): now unmanaged
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): cleaning up...
Jul 24 08:19:26 castor NetworkManager[3047]: <info> (wlan0): taking down device.
Jul 24 08:19:26 castor NetworkManager[3047]: <info> exiting (success)
Jul 24 08:19:28 castor acpid: exiting
Jul 24 08:19:28 castor modem-manager[3119]: <info> Caught signal 15, shutting down...
########
....omitting boot
messages...
########
Jul 24 08:20:13 castor kernel: [ 13.556738] ath: EEPROM regdomain: 0x0
Jul 24 08:20:13 castor kernel: [ 13.556745] ath: EEPROM indicates default country code should be used
Jul 24 08:20:13 castor kernel: [ 13.556748] ath: doing EEPROM country->regdmn map search
Jul 24 08:20:13 castor kernel: [ 13.556755] ath: country maps to regdmn code: 0x3a
Jul 24 08:20:13 castor kernel: [ 13.556759] ath: Country alpha2 being used: US
Jul 24 08:20:13 castor kernel: [ 13.556763] ath: Regpair used: 0x3a
Jul 24 08:20:13 castor kernel: [ 13.615373] phy0: Selected rate control algorithm 'ath9k_rate_control'
Jul 24 08:20:13 castor kernel: [ 13.617208] Registered led device: ath9k-phy0::radio
Jul 24 08:20:13 castor kernel: [ 13.617287] Registered led device: ath9k-phy0::assoc
Jul 24 08:20:13 castor kernel: [ 13.617368] Registered led device: ath9k-phy0::tx
Jul 24 08:20:13 castor kernel: [ 13.617443] Registered led device: ath9k-phy0::rx
Jul 24 08:20:13 castor kernel: [ 13.617460] phy0: Atheros AR5416 MAC/BB Rev:2 AR2133 RF Rev:81: mem=0xf0800000, irq=5
#######
....omitted...
#######
Jul 24 08:20:22 castor NetworkManager[3150]: <info> NetworkManager (version 0.9.4.0) is starting...
Jul 24 08:20:22 castor NetworkManager[3150]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
Jul 24 08:20:22 castor acpid: client connected from 3082[0:0]
Jul 24 08:20:22 castor acpid: 1 client rule loaded
Jul 24 08:20:23 castor dbus[2666]: [system] Activating service name='org.freedesktop.PolicyKit1' (using servicehelper)
Jul 24 08:20:23 castor polkitd[3179]: started daemon version 0.105 using authority implementation `local' version `0.105'
Jul 24 08:20:23 castor dbus[2666]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: init!
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: update_system_hostname
Jul 24 08:20:23 castor NetworkManager[3150]: SCPluginIfupdown: management mode: unmanaged
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.0/net/eth0, iface: eth0)
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1e.0/0000:02:03.0/net/wlan0, iface: wlan0)
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1e.0/0000:02:03.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: end _init.
Jul 24 08:20:23 castor NetworkManager[3150]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list.
Jul 24 08:20:23 castor NetworkManager[3150]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Jul 24 08:20:23 castor NetworkManager[3150]: Ifupdown: get unmanaged devices count: 0
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: (163807640) ... get_connections.
Jul 24 08:20:23 castor NetworkManager[3150]: SCPlugin-Ifupdown: (163807640) ... get_connections (managed=false): return empty list.
Jul 24 08:20:23 castor NetworkManager[3150]: keyfile: parsing CaitlinNet ...
Jul 24 08:20:24 castor NetworkManager[3150]: keyfile: read connection 'CaitlinNet'
Jul 24 08:20:24 castor NetworkManager[3150]: keyfile: parsing RAPTOR ...
Jul 24 08:20:24 castor NetworkManager[3150]: keyfile: read connection 'RAPTOR'
Jul 24 08:20:24 castor NetworkManager[3150]: keyfile: parsing NETGEAR_Guest1 ...
Jul 24 08:20:24 castor NetworkManager[3150]: keyfile: read connection 'NETGEAR_Guest1'
Jul 24 08:20:24 castor NetworkManager[3150]: Ifupdown: get unmanaged devices count: 0
Jul 24 08:20:24 castor NetworkManager[3150]: <info> trying to start the modem manager...
Jul 24 08:20:24 castor dbus[2666]: [system] Activating service name='org.freedesktop.ModemManager' (using servicehelper)
Jul 24 08:20:24 castor NetworkManager[3150]: <info> monitoring kernel firmware directory '/lib/firmware'.
Jul 24 08:20:24 castor NetworkManager[3150]: <info> monitoring ifupdown state file '/run/network/ifstate'.
Jul 24 08:20:24 castor NetworkManager[3150]: <info> found WiFi radio killswitch rfkill0 (at /sys/devices/pci0000:00/0000:00:1e.0/0000:02:03.0/ieee80211/phy0/rfkill0) (driver (unknown))
Jul 24 08:20:24 castor minissdpd[3221]: setsockopt(udp, IP_ADD_MEMBERSHIP)(0.0.0.0): No such device
Jul 24 08:20:24 castor minissdpd[3221]: Failed to add IPv4 multicast membership for interface 0.0.0.0.
Jul 24 08:20:24 castor NetworkManager[3150]: <info> WiFi enabled by radio killswitch; enabled by state file
Jul 24 08:20:24 castor NetworkManager[3150]: <info> WWAN enabled by radio killswitch; enabled by state file
Jul 24 08:20:24 castor NetworkManager[3150]: <info> WiMAX enabled by radio killswitch; enabled by state file
Jul 24 08:20:24 castor NetworkManager[3150]: <info> Networking is enabled by state file
Jul 24 08:20:24 castor NetworkManager[3150]: <warn> failed to allocate link cache: (-10) Operation not supported
Jul 24 08:20:24 castor modem-manager[3223]: <info> ModemManager (version 0.5.2.0) starting...
Jul 24 08:20:24 castor dbus[2666]: [system] Successfully activated service 'org.freedesktop.ModemManager'
Jul 24 08:20:24 castor modem-manager[3223]: <info> Loaded plugin Option
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): carrier is OFF
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): new Ethernet device (driver: '3c59x' ifindex: 2)
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): now managed
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): bringing up device.
Jul 24 08:20:24 castor kernel: [ 37.639921] eth0: setting half-duplex.
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): preparing device.
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (eth0): deactivating device (reason 'managed') [2]
Jul 24 08:20:24 castor kernel: [ 37.640714] ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul 24 08:20:24 castor NetworkManager[3150]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.0/net/eth0
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): using nl80211 for WiFi device control
Jul 24 08:20:24 castor NetworkManager[3150]: <warn> (wlan0): driver supports Access Point (AP) mode
Jul 24 08:20:24 castor NetworkManager[3150]: <error> [1374679224.771212] [nm-device-wifi.c:2590] real_update_permanent_hw_address(): (wlan0): unable to read permanent MAC address (error 95)
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): new 802.11 WiFi device (driver: 'ath9k' ifindex: 3)
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): now managed
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): bringing up device.
Jul 24 08:20:24 castor kernel: [ 37.671611] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 24 08:20:24 castor modem-manager[3223]: <info> Loaded plugin Linktop
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): preparing device.
Jul 24 08:20:24 castor NetworkManager[3150]: <info> (wlan0): deactivating device (reason 'managed') [2]
Jul 24 08:20:24 castor dbus[2666]: [system] Activating service name='fi.w1.wpa_supplicant1' (using servicehelper)
Jul 24 08:20:24 castor modem-manager[3223]: <info> Loaded plugin Samsung
Jul 24 08:20:24 castor NetworkManager[3150]: <warn> bluez error getting default adapter: The name org.bluez was not provided by any .service files
Jul 24 08:20:24 castor NetworkManager[3150]: <info> modem-manager is now available
Jul 24 08:20:24 castor modem-manager[3223]: <info> Loaded plugin Generic
Jul 24 08:20:24 castor modem-manager[3223]: <info> Loaded plugin Huawei
Jul 24 08:20:24 castor modem-manager[3223]: <info> Loaded plugin Sierra
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Ericsson MBM
Jul 24 08:20:25 castor dbus[2666]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
Jul 24 08:20:25 castor NetworkManager[3150]: <info> wpa_supplicant started
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin MotoC
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin SimTech
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin ZTE
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Wavecom
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin X22X
Jul 24 08:20:25 castor NetworkManager[3150]: <info> (wlan0): supplicant interface state: starting -> ready
Jul 24 08:20:25 castor NetworkManager[3150]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Jul 24 08:20:25 castor NetworkManager[3150]: <info> (wlan0): supplicant interface state: ready -> inactive
Jul 24 08:20:25 castor NetworkManager[3150]: <warn> Trying to remove a non-existant call id.
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin AnyData
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Option High-Speed
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Gobi
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Novatel
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Longcheer
Jul 24 08:20:25 castor modem-manager[3223]: <info> Loaded plugin Nokia
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Auto-activating connection 'RAPTOR'.
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) starting connection 'RAPTOR'
Jul 24 08:20:26 castor NetworkManager[3150]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jul 24 08:20:26 castor NetworkManager[3150]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0/wireless): connection 'RAPTOR' has security, and secrets exist. No new secrets needed.
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Config: added 'ssid' value 'RAPTOR'
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Config: added 'scan_ssid' value '1'
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Config: added 'auth_alg' value 'OPEN'
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Config: added 'psk' value '<omitted>'
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Jul 24 08:20:26 castor NetworkManager[3150]: <info> Config: set interface ap_scan to 1
Jul 24 08:20:26 castor NetworkManager[3150]: <info> (wlan0): supplicant interface state: inactive -> scanning
Jul 24 08:20:27 castor wpa_supplicant[3233]: wlan0: SME: Trying to authenticate with 00:24:c4:d2:f3:91 (SSID='RAPTOR' freq=2462 MHz)
##########
Notice how this
time there's no
problem authenticating.
##########
Jul 24 08:20:27 castor kernel: [ 40.099720] wlan0: direct probe to AP 00:24:c4:d2:f3:91 (try 1)
Jul 24 08:20:27 castor kernel: [ 40.103866] wlan0: direct probe responded
Jul 24 08:20:27 castor kernel: [ 40.103880] wlan0: authenticate with AP 00:24:c4:d2:f3:91 (try 1)
Jul 24 08:20:27 castor NetworkManager[3150]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jul 24 08:20:27 castor kernel: [ 40.108243] wlan0: authenticated
Jul 24 08:20:27 castor wpa_supplicant[3233]: wlan0: Trying to associate with 00:24:c4:d2:f3:91 (SSID='RAPTOR' freq=2462 MHz)
Jul 24 08:20:27 castor kernel: [ 40.114904] wlan0: associate with AP 00:24:c4:d2:f3:91 (try 1)
Jul 24 08:20:27 castor kernel: [ 40.120440] wlan0: RX AssocResp from 00:24:c4:d2:f3:91 (capab=0x431 status=0 aid=85)
Jul 24 08:20:27 castor kernel: [ 40.120451] wlan0: associated
Jul 24 08:20:27 castor kernel: [ 40.131202] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Jul 24 08:20:27 castor kernel: [ 40.131995] cfg80211: Calling CRDA for country: US
Jul 24 08:20:27 castor wpa_supplicant[3233]: wlan0: Associated with 00:24:c4:d2:f3:91
Jul 24 08:20:27 castor kernel: [ 40.165711] cfg80211: Current regulatory domain updated by AP to: US
Jul 24 08:20:27 castor kernel: [ 40.165722] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Jul 24 08:20:27 castor kernel: [ 40.165732] (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Jul 24 08:20:27 castor kernel: [ 40.332189] padlock: VIA PadLock not detected.
Jul 24 08:20:27 castor wpa_supplicant[3233]: wlan0: WPA: Key negotiation completed with 00:24:c4:d2:f3:91 [PTK=CCMP GTK=TKIP]
Jul 24 08:20:27 castor wpa_supplicant[3233]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:24:c4:d2:f3:91 completed (auth) [id=0 id_str=]
Jul 24 08:20:27 castor NetworkManager[3150]: <info> (wlan0): supplicant interface state: authenticating -> completed
Jul 24 08:20:27 castor NetworkManager[3150]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'RAPTOR'.
--- End Message ---