Attachment 605297 Details for Bug 849309 – var/log/messages of wifi issues showing changing frequencies and kills.

var/log/messages of wifi issues showing changing frequencies and kills.

fed-nm-atheros-v-log-messages.txt (text/plain), 16.07 KB, created by Dave Ramsey on 2012-08-18 04:33:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Dave Ramsey

Created: 2012-08-18 04:33:37 UTC

Size: 16.07 KB

patch

obsolete

>Aug 17 20:15:48 localhost NetworkManager[624]:    ifcfg-rh:     read connection 'BSSID'
>Aug 17 20:15:49 localhost NetworkManager[624]:    ifcfg-rh: updating /etc/sysconfig/network-scripts/ifcfg-BSSID
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) starting connection 'BSSID'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0/wireless): connection 'BSSID' has security, and secrets exist.  No new secrets needed.
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: added 'ssid' value 'BSSID'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: added 'scan_ssid' value '1'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: added 'key_mgmt' value 'NONE'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: added 'auth_alg' value 'OPEN'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: added 'wpa_key0' value '<omitted>'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: added 'wpa_tx_keyidx' value '0'
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> Config: set interface ap_scan to 1
>Aug 17 20:21:38 localhost NetworkManager[624]: <info> (wlan0): supplicant interface state: inactive -> scanning
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> (wlan0): supplicant interface state: scanning -> authenticating
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> (wlan0): supplicant interface state: authenticating -> associating
>Aug 17 20:21:39 localhost kernel: [  701.461238] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
>Aug 17 20:21:39 localhost kernel: [  701.461503] cfg80211: Calling CRDA for country: US
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> (wlan0): supplicant interface state: associating -> completed
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'BSSID'.
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
>Aug 17 20:21:39 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
>Aug 17 20:21:39 localhost avahi-daemon[626]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3.
>Aug 17 20:21:39 localhost avahi-daemon[626]: New relevant interface wlan0.IPv4 for mDNS.
>Aug 17 20:21:39 localhost avahi-daemon[626]: Registering new address record for 192.168.1.3 on wlan0.IPv4.
>Aug 17 20:21:39 localhost kernel: [  701.482469] cfg80211: Regulatory domain changed to country: US
>Aug 17 20:21:39 localhost kernel: [  701.482477] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>Aug 17 20:21:39 localhost kernel: [  701.482484] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
>Aug 17 20:21:39 localhost kernel: [  701.482490] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
>Aug 17 20:21:39 localhost kernel: [  701.482495] cfg80211:   (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Aug 17 20:21:39 localhost kernel: [  701.482501] cfg80211:   (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Aug 17 20:21:39 localhost kernel: [  701.482506] cfg80211:   (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Aug 17 20:21:39 localhost kernel: [  701.482511] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
>Aug 17 20:21:40 localhost NetworkManager[624]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
>Aug 17 20:21:40 localhost NetworkManager[624]: <info> Policy set 'BSSID' (wlan0) as default for IPv4 routing and DNS.
>Aug 17 20:21:40 localhost NetworkManager[624]: <info> Activation (wlan0) successful, device activated.
>Aug 17 20:21:40 localhost NetworkManager[624]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
>
>Aug 17 21:33:05 localhost kernel: [ 4987.454562] cfg80211: Calling CRDA to update world regulatory domain
>Aug 17 21:33:05 localhost kernel: [ 4987.464742] ath: Failed to wakeup in 500us
>Aug 17 21:33:05 localhost avahi-daemon[626]: Interface wlan0.IPv4 no longer relevant for mDNS.
>Aug 17 21:33:05 localhost avahi-daemon[626]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3.
>Aug 17 21:33:05 localhost avahi-daemon[626]: Withdrawing address record for xxx::xx:xxxx:xxxx:xxxx on wlan0.
>Aug 17 21:33:05 localhost avahi-daemon[626]: Withdrawing address record for 192.168.1.3 on wlan0.
>Aug 17 21:33:05 localhost avahi-daemon[626]: Withdrawing workstation service for wlan0.
>Aug 17 21:33:05 localhost NetworkManager[624]: <info> radio killswitch /sys/devices/pci0000:00/0000:00:02.1/usb1/1-3/1-3:1.0/ieee80211/phy0/rfkill0 disappeared
>Aug 17 21:33:05 localhost kernel: [ 4987.690552] usb 1-3: ath9k_htc: USB layer deinitialized
>Aug 17 21:33:05 localhost kernel: [ 4987.896046] usb 1-3: new high-speed USB device number 4 using ehci_hcd
>Aug 17 21:33:10 localhost NetworkManager[624]: <info> (wlan0): roamed from BSSID xx:xx:xx:xx:xx:xx (BSSID) to (none) ((none))
>Aug 17 21:33:10 localhost kernel: [ 4993.030920] usb 1-3: New USB device found, idVendor=0xff, idProduct=1234
>Aug 17 21:33:10 localhost kernel: [ 4993.030930] usb 1-3: New USB device strings: Mfr=07, Product=21, SerialNumber=13
>Aug 17 21:33:10 localhost kernel: [ 4993.030936] usb 1-3: Product: USB2.0 WLAN
>Aug 17 21:33:10 localhost kernel: [ 4993.030941] usb 1-3: Manufacturer: ATHEROS
>Aug 17 21:33:10 localhost kernel: [ 4993.030945] usb 1-3: SerialNumber: 12345
>Aug 17 21:33:11 localhost NetworkManager[624]: <info> (wlan0): now unmanaged
>Aug 17 21:33:11 localhost NetworkManager[624]: <info> (wlan0): device state change: activated -> unmanaged (reason 'removed') [100 10 36]
>Aug 17 21:33:11 localhost NetworkManager[624]: <info> (wlan0): deactivating device (reason 'removed') [36]
>Aug 17 21:33:11 localhost NetworkManager[624]: <warn> (3) failed to find interface name for index
>Aug 17 21:33:11 localhost NetworkManager[624]: nm_system_iface_flush_routes: assertion `iface != NULL' failed
>Aug 17 21:33:11 localhost NetworkManager[624]: <warn> (3) failed to find interface name for index
>Aug 17 21:33:11 localhost kernel: [ 4993.834804] cfg80211: World regulatory domain updated:
>Aug 17 21:33:11 localhost kernel: [ 4993.834814] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>Aug 17 21:33:11 localhost kernel: [ 4993.834821] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Aug 17 21:33:11 localhost kernel: [ 4993.834827] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>Aug 17 21:33:11 localhost kernel: [ 4993.834832] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>Aug 17 21:33:11 localhost kernel: [ 4993.834838] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Aug 17 21:33:11 localhost kernel: [ 4993.834843] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Aug 17 21:33:11 localhost NetworkManager[624]: <info> (wlan0): cleaning up...
>Aug 17 21:33:11 localhost NetworkManager[624]: <warn> (3) failed to find interface name for index
>Aug 17 21:33:11 localhost NetworkManager[624]: (nm-system.c:685):nm_system_iface_get_flags: runtime check failed: (iface != NULL)
>Aug 17 21:33:11 localhost NetworkManager[624]: <error> [1345253591.806549] [nm-system.c:687] nm_system_iface_get_flags(): (unknown): failed to get interface link object
>Aug 17 21:33:11 localhost dbus-daemon[704]: dbus[704]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
>Aug 17 21:33:11 localhost dbus[704]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
>Aug 17 21:33:12 localhost dbus-daemon[704]: dbus[704]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Aug 17 21:33:12 localhost dbus[704]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Aug 17 21:33:13 localhost systemd[1]: PID file /run/sendmail.pid not readable (yet?) after start.
>Aug 17 21:33:14 localhost systemd[1]: PID 1585 read from file /run/sm-client.pid does not exist.
>Aug 17 21:33:41 localhost udevd[409]: worker [1071] timeout, kill it
>Aug 17 21:33:41 localhost udevd[409]: seq 1993 '/devices/pci0000:00/0000:00:02.1/usb1/1-3' killed
>Aug 17 21:33:41 localhost udevd[409]: worker [1899] timeout, kill it
>Aug 17 21:33:41 localhost udevd[409]: seq 1994 '/devices/pci0000:00/0000:00:02.1/usb1/1-3' killed
>Aug 17 21:33:41 localhost kernel: [ 5024.041925] usb 1-3: ath9k_htc: Firmware - htc_9271.fw download failed
>Aug 17 21:33:41 localhost kernel: [ 5024.041963] ath9k_htc: probe of 1-3:1.0 failed with error -22
>Aug 17 21:33:41 localhost kernel: [ 5024.042256] usb 1-3: USB disconnect, device number 4
>Aug 17 21:33:41 localhost udevd[409]: worker [1071] terminated by signal 9 (Killed)
>Aug 17 21:33:41 localhost udevd[409]: worker [1899] terminated by signal 9 (Killed)
>Aug 17 21:33:42 localhost kernel: [ 5024.249051] usb 1-3: new high-speed USB device number 5 using ehci_hcd
>Aug 17 21:33:42 localhost kernel: [ 5024.383923] usb 1-3: New USB device found, idVendor=0cf3, idProduct=9271
>Aug 17 21:33:42 localhost kernel: [ 5024.383933] usb 1-3: New USB device strings: Mfr=16, Product=32, SerialNumber=48
>Aug 17 21:33:42 localhost kernel: [ 5024.383940] usb 1-3: Product: USB2.0 WLAN
>Aug 17 21:33:42 localhost kernel: [ 5024.383944] usb 1-3: Manufacturer: ATHEROS
>Aug 17 21:33:42 localhost kernel: [ 5024.383948] usb 1-3: SerialNumber: 12345
>Aug 17 21:34:12 localhost udevd[409]: worker [505] timeout, kill it
>Aug 17 21:34:12 localhost udevd[409]: seq 1999 '/devices/pci0000:00/0000:00:02.1/usb1/1-3' killed
>Aug 17 21:34:12 localhost udevd[409]: worker [1900] timeout, kill it
>Aug 17 21:34:12 localhost udevd[409]: seq 2000 '/devices/pci0000:00/0000:00:02.1/usb1/1-3' killed
>Aug 17 21:34:12 localhost kernel: [ 5054.509054] usb 1-3: ath9k_htc: Firmware - htc_9271.fw download failed
>Aug 17 21:34:12 localhost kernel: [ 5054.509097] ath9k_htc: probe of 1-3:1.0 failed with error -22
>Aug 17 21:34:12 localhost kernel: [ 5054.509365] usb 1-3: USB disconnect, device number 5
>Aug 17 21:34:12 localhost udevd[409]: worker [1900] terminated by signal 9 (Killed)
>Aug 17 21:34:12 localhost udevd[409]: worker [505] terminated by signal 9 (Killed)
>Aug 17 21:34:12 localhost kernel: [ 5054.716063] usb 1-3: new high-speed USB device number 6 using ehci_hcd
>Aug 17 21:34:12 localhost kernel: [ 5054.850915] usb 1-3: New USB device found, idVendor=0cf3, idProduct=9271
>Aug 17 21:34:12 localhost kernel: [ 5054.850923] usb 1-3: New USB device strings: Mfr=16, Product=32, SerialNumber=48
>Aug 17 21:34:12 localhost kernel: [ 5054.850930] usb 1-3: Product: USB2.0 WLAN
>Aug 17 21:34:12 localhost kernel: [ 5054.850935] usb 1-3: Manufacturer: ATHEROS
>Aug 17 21:34:12 localhost kernel: [ 5054.850939] usb 1-3: SerialNumber: 12345
>Aug 17 21:34:42 localhost udevd[409]: worker [1901] timeout, kill it
>Aug 17 21:34:42 localhost udevd[409]: seq 2006 '/devices/pci0000:00/0000:00:02.1/usb1/1-3' killed
>Aug 17 21:34:42 localhost kernel: [ 5084.892182] usb 1-3: ath9k_htc: Firmware - htc_9271.fw download failed
>Aug 17 21:34:42 localhost kernel: [ 5084.892224] ath9k_htc: probe of 1-3:1.0 failed with error -22
>Aug 17 21:34:42 localhost udevd[409]: worker [1901] terminated by signal 9 (Killed)
>Aug 17 21:34:43 localhost kernel: [ 5085.098057] usb 1-2: new high-speed USB device number 7 using ehci_hcd
>Aug 17 21:34:43 localhost kernel: [ 5085.232921] usb 1-2: New USB device found, idVendor=0cf3, idProduct=9271
>Aug 17 21:34:43 localhost kernel: [ 5085.232931] usb 1-2: New USB device strings: Mfr=16, Product=32, SerialNumber=48
>Aug 17 21:34:43 localhost kernel: [ 5085.232937] usb 1-2: Product: USB2.0 WLAN
>Aug 17 21:34:43 localhost kernel: [ 5085.232941] usb 1-2: Manufacturer: ATHEROS
>Aug 17 21:34:43 localhost kernel: [ 5085.232945] usb 1-2: SerialNumber: 12345
>Aug 17 21:35:13 localhost udevd[409]: worker [1987] timeout, kill it
>Aug 17 21:35:13 localhost udevd[409]: seq 2010 '/devices/pci0000:00/0000:00:02.1/usb1/1-2' killed
>Aug 17 21:35:13 localhost kernel: [ 5115.554923] usb 1-2: ath9k_htc: Transferred FW: htc_9271.fw, size: 51272
>Aug 17 21:35:13 localhost kernel: [ 5115.790047] ath9k_htc 1-2:1.0: ath9k_htc: HTC initialized with 33 credits
>Aug 17 21:35:13 localhost kernel: [ 5115.987298] ath9k_htc 1-2:1.0: ath9k_htc: FW Version: 1.3
>Aug 17 21:35:13 localhost kernel: [ 5115.989122] ieee80211 phy1: Atheros AR9271 Rev:1
>Aug 17 21:35:13 localhost kernel: [ 5115.989134] cfg80211: Calling CRDA for country: CN
>Aug 17 21:35:13 localhost kernel: [ 5115.994039] usb 1-2: ath9k_htc: USB layer initialized
>Aug 17 21:35:13 localhost kernel: [ 5115.994300] usb 1-3: USB disconnect, device number 6
>Aug 17 21:35:13 localhost udevd[409]: worker [1987] terminated by signal 9 (Killed)
>Aug 17 21:35:13 localhost kernel: [ 5116.020938] cfg80211: Regulatory domain changed to country: CN
>Aug 17 21:35:13 localhost kernel: [ 5116.020947] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>Aug 17 21:35:13 localhost kernel: [ 5116.020954] cfg80211:   (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm)
>Aug 17 21:35:13 localhost kernel: [ 5116.020959] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (N/A, 3000 mBm)
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): using nl80211 for WiFi device control
>Aug 17 21:35:14 localhost NetworkManager[624]: <warn> (wlan0): driver supports Access Point (AP) mode
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): new 802.11 WiFi device (driver: 'ath9k_htc' ifindex: 5)
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/2
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): now managed
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): bringing up device.
>Aug 17 21:35:14 localhost kernel: [ 5116.420300] ADDRCONF(NETDEV_UP): wlan0: link is not ready
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): preparing device.
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): deactivating device (reason 'managed') [2]
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> found WiFi radio killswitch rfkill1 (at /sys/devices/pci0000:00/0000:00:02.1/usb1/1-2/1-2:1.0/ieee80211/phy1/rfkill1) (driver (unknown))
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): supplicant interface state: starting -> ready
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
>Aug 17 21:35:14 localhost NetworkManager[624]: <info> (wlan0): supplicant interface state: ready -> inactive
>Aug 17 21:35:14 localhost NetworkManager[624]: <warn> Trying to remove a non-existant call id.

Actions: View

Attachments on bug 849309: 605297