Attachment 197331 Details for Bug 293111 – /var/log/messages output from the switch from wired to wireless to the failure

/var/log/messages output from the switch from wired to wireless to the failure

messages_wireless_switch.txt (text/plain), 15.48 KB, created by David Hislop on 2007-09-17 12:31:59 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: David Hislop

Created: 2007-09-17 12:31:59 UTC

Size: 15.48 KB

patch

obsolete

>Sep 17 21:59:24 slate NetworkManager: <info>  User Switch: /org/freedesktop/NetworkManager/Devices/eth1 / myAP_1 
>Sep 17 21:59:24 slate NetworkManager: <info>  Deactivating device eth1. 
>Sep 17 21:59:24 slate dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth1 for sub-path eth1.dbus.get.reason
>Sep 17 21:59:24 slate NetworkManager: <info>  Device eth1 activation scheduled... 
>Sep 17 21:59:24 slate NetworkManager: <info>  Deactivating device eth0. 
>Sep 17 21:59:24 slate dhclient: DHCPRELEASE on eth0 to 192.168.1.1 port 67
>Sep 17 21:59:25 slate avahi-daemon[2036]: Withdrawing address record for 192.168.1.102 on eth0.
>Sep 17 21:59:25 slate avahi-daemon[2036]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.102.
>Sep 17 21:59:25 slate avahi-daemon[2036]: Interface eth0.IPv4 no longer relevant for mDNS.
>Sep 17 21:59:26 slate avahi-daemon[2036]: Withdrawing address record for fe80::208:74ff:fe4c:7bf4 on eth0.
>Sep 17 21:59:26 slate avahi-daemon[2036]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::208:74ff:fe4c:7bf4.
>Sep 17 21:59:26 slate avahi-daemon[2036]: Interface eth0.IPv6 no longer relevant for mDNS.
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) started... 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled... 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started... 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled... 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete. 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting... 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1/wireless): access point 'myAP_1' is encrypted, but NO valid key exists.  New key needed. 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) New wireless user key requested for network 'myAP_1'. 
>Sep 17 21:59:26 slate NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete. 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1) New wireless user key for network 'myAP_1' received. 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled... 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started... 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled... 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete. 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting... 
>Sep 17 21:59:46 slate NetworkManager: <info>  Activation (eth1/wireless): access point 'myAP_1' is encrypted, and a key exists.  No new key needed. 
>Sep 17 21:59:47 slate NetworkManager: <info>  SUP: sending command 'INTERFACE_ADD eth1		wext	/var/run/wpa_supplicant	' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'AP_SCAN 1' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'ADD_NETWORK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was '0' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 ssid KEY_VALUE' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 proto WPA' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 key_mgmt WPA-PSK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 psk <key>' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: sending command 'ENABLE_NETWORK 0' 
>Sep 17 21:59:48 slate NetworkManager: <info>  SUP: response was 'OK' 
>Sep 17 21:59:48 slate NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete. 
>Sep 17 21:59:59 slate nmbd[1957]: [2007/09/17 21:59:59, 0] libsmb/nmblib.c:send_udp(791) 
>Sep 17 21:59:59 slate nmbd[1957]:   Packet send failed to 192.168.1.255(138) ERRNO=Invalid argument 
>Sep 17 22:00:17 slate kernel: eth1: timeout waiting for mgmt response
>Sep 17 22:00:19 slate last message repeated 2 times
>Sep 17 22:00:20 slate NetworkManager: <WARN>  nm_device_802_11_wireless_get_essid(): error getting ESSID for device eth1: Input/output error 
>Sep 17 22:00:20 slate NetworkManager: <WARN>  nm_device_802_11_wireless_get_essid(): error getting ESSID for device eth1: Input/output error 
>Sep 17 22:00:20 slate kernel: eth1: timeout waiting for mgmt response
>Sep 17 22:00:20 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:20 slate last message repeated 13 times
>Sep 17 22:00:22 slate NetworkManager: <WARN>  nm_device_802_11_wireless_get_essid(): error getting ESSID for device eth1: Input/output error 
>Sep 17 22:00:22 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:23 slate last message repeated 10 times
>Sep 17 22:00:24 slate NetworkManager: <WARN>  nm_device_802_11_wireless_get_essid(): error getting ESSID for device eth1: Input/output error 
>Sep 17 22:00:24 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:24 slate last message repeated 8 times
>Sep 17 22:00:26 slate NetworkManager: <WARN>  nm_device_802_11_wireless_get_essid(): error getting ESSID for device eth1: Input/output error 
>Sep 17 22:00:26 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:27 slate last message repeated 12 times
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth1/wireless): association took too long (>40s), failing activation. 
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth1) failure scheduled... 
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth1) failed for access point (myAP_1) 
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth1) failed. 
>Sep 17 22:00:28 slate NetworkManager: <info>  Deactivating device eth1. 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate last message repeated 7 times
>Sep 17 22:00:28 slate NetworkManager: <WARN>  nm_device_802_11_wireless_set_essid(): error setting ESSID to '' for device eth1: Input/output error 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <WARN>  nm_device_802_11_wireless_set_wep_enc_key(): error setting key for device eth1: Input/output error 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <WARN>  nm_device_802_11_wireless_set_mode(): error setting card eth1 to Infrastructure mode: Input/output error 
>Sep 17 22:00:28 slate NetworkManager: <info>  SWITCH: no current connection, found better connection 'eth0'. 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate last message repeated 6 times
>Sep 17 22:00:28 slate NetworkManager: <info>  Will activate connection 'eth0'. 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <info>  Device eth0 activation scheduled... 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) started... 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=ff020008 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=ff020003 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=10000000 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=17000007 err=-12
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled... 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started... 
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=19000001 err=-12
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled... 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete. 
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=10000002 err=-12
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting... 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) successful. 
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=19000004 err=-12
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled. 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete. 
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000000 err=-12
>Sep 17 22:00:28 slate NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) started... 
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000001 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000002 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000004 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000005 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000006 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000007 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=12000003 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=150007e0 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=ff02000c err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_commit_list: failure. oid=ff020003 err=-12
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:28 slate kernel: eth1: mgt_update_addr: failure
>Sep 17 22:00:28 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:29 slate kernel: eth1: mgmt tx queue is still full
>Sep 17 22:00:29 slate kernel: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
>Sep 17 22:00:29 slate NetworkManager: <info>  nm-netlink-monitor.c - nm_netlink_monitor_event_handler (724) netlink reports device eth1 link now 1 
>Sep 17 22:00:29 slate NetworkManager: <info>  nm-netlink-monitor.c - nm_netlink_monitor_event_handler (724) netlink reports device eth1 link now 0 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction. 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) complete. 
>Sep 17 22:00:29 slate NetworkManager: <info>  DHCP daemon state is now 12 (successfully started) for interface eth0 
>Sep 17 22:00:29 slate NetworkManager: <info>  DHCP daemon state is now 1 (starting) for interface eth0 
>Sep 17 22:00:29 slate dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7
>Sep 17 22:00:29 slate dhclient: DHCPOFFER from 192.168.1.1
>Sep 17 22:00:29 slate dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
>Sep 17 22:00:29 slate dhclient: DHCPACK from 192.168.1.1
>Sep 17 22:00:29 slate NetworkManager: <info>  DHCP daemon state is now 2 (bound) for interface eth0 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled... 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) started... 
>Sep 17 22:00:29 slate dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.host_name
>Sep 17 22:00:29 slate dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_domain
>Sep 17 22:00:29 slate dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth0 for sub-path eth0.dbus.get.nis_servers
>Sep 17 22:00:29 slate NetworkManager: <info>  Retrieved the following IP4 configuration from the DHCP daemon: 
>Sep 17 22:00:29 slate NetworkManager: <info>    address 192.168.1.102 
>Sep 17 22:00:29 slate NetworkManager: <info>    netmask 255.255.255.0 
>Sep 17 22:00:29 slate NetworkManager: <info>    broadcast 192.168.1.255 
>Sep 17 22:00:29 slate NetworkManager: <info>    gateway 192.168.1.1 
>Sep 17 22:00:29 slate NetworkManager: <info>    nameserver a.b.c.d 
>Sep 17 22:00:29 slate NetworkManager: <info>    nameserver e.f.g.h 
>Sep 17 22:00:29 slate NetworkManager: <info>    domain name 'aaa.bbb.ccc.ddd' 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled... 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) complete. 
>Sep 17 22:00:29 slate NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) started... 
>Sep 17 22:00:29 slate dhclient: bound to 192.168.1.102 -- renewal in 35581 seconds.
>Sep 17 22:00:29 slate avahi-daemon[2036]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.102.
>Sep 17 22:00:29 slate avahi-daemon[2036]: New relevant interface eth0.IPv4 for mDNS.
>Sep 17 22:00:29 slate avahi-daemon[2036]: Registering new address record for 192.168.1.102 on eth0.IPv4.
>Sep 17 22:00:30 slate NetworkManager: <info>  Activation (eth0) Finish handler scheduled. 
>Sep 17 22:00:30 slate NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete. 
>Sep 17 22:00:30 slate NetworkManager: <info>  Activation (eth0) successful, device activated. 
>Sep 17 22:00:31 slate avahi-daemon[2036]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::208:74ff:fe4c:7bf4.
>Sep 17 22:00:31 slate avahi-daemon[2036]: New relevant interface eth0.IPv6 for mDNS.
>Sep 17 22:00:31 slate avahi-daemon[2036]: Registering new address record for fe80::208:74ff:fe4c:7bf4 on eth0.*.
>Sep 17 22:00:48 slate NetworkManager: <info>  nm-netlink-monitor.c - nm_netlink_monitor_event_handler (724) netlink reports device eth1 link now 1 
>Sep 17 22:00:50 slate avahi-daemon[2036]: Joining mDNS multicast group on interface eth1.IPv6 with address fe80::209:5bff:fe65:23b7.
>Sep 17 22:00:50 slate avahi-daemon[2036]: New relevant interface eth1.IPv6 for mDNS.
>Sep 17 22:00:50 slate avahi-daemon[2036]: Registering new address record for fe80::209:5bff:fe65:23b7 on eth1.*.

Actions: View

Attachments on bug 293111: 197331 | 197341 | 197351 | 305832 | 305833 | 305923 | 305924 | 306388