Attachment 872239 Details for Bug 1074195 – Extract from /var/log/messages showing two cycles of failure.

Extract from /var/log/messages showing two cycles of failure.

nflog.txt (text/plain), 16.48 KB, created by David.Feuer on 2014-03-08 20:13:53 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: David.Feuer

Created: 2014-03-08 20:13:53 UTC

Size: 16.48 KB

patch

obsolete

>Mar  8 01:35:59 lemur NetworkManager[799]: <info> NetworkManager state is now DISCONNECTED
>Mar  8 01:35:59 lemur NetworkManager[799]: <warn> Activation (wlp2s0) failed for connection '9V4M1'
>Mar  8 01:35:59 lemur NetworkManager[799]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
>Mar  8 01:35:59 lemur NetworkManager[799]: <info> (wlp2s0): device state change: failed -> disconnected (reason 'none') [120 30 0]
>Mar  8 01:35:59 lemur NetworkManager[799]: <info> (wlp2s0): deactivating device (reason 'none') [0]
>Mar  8 01:35:59 lemur NetworkManager[799]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
>Mar  8 01:35:59 lemur firewalld: 2014-03-08 01:35:59 ERROR: UNKNOWN_INTERFACE: wlp2s0
>Mar  8 01:35:59 lemur NetworkManager[799]: <warn> (wlp2s0) firewall zone remove failed: (32) UNKNOWN_INTERFACE: wlp2s0
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: scanning -> inactive
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Auto-activating connection '9V4M1'.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) starting connection '9V4M1'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> NetworkManager state is now CONNECTING
>Mar  8 01:36:02 lemur NetworkManager[799]: <warn> (wlp2s0): add_pending_action (2): 'autoactivate' already added
>Mar  8 01:36:02 lemur NetworkManager[799]: file devices/nm-device.c: line 7185 (nm_device_add_pending_action): should not be reached
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) scheduled...
>Mar  8 01:36:02 lemur NetworkManager[799]: <warn> (wlp2s0): remove_pending_action (1): 'autoactivate' never added
>Mar  8 01:36:02 lemur NetworkManager[799]: file devices/nm-device.c: line 7236 (nm_device_remove_pending_action): should not be reached
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) started...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) scheduled...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) complete.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) starting...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): device state change: prepare -> config (reason 'none') [40 50 0]
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0/wireless): access point '9V4M1' has security, but secrets are required.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): device state change: config -> need-auth (reason 'none') [50 60 0]
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) complete.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) scheduled...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) started...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) scheduled...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) complete.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) starting...
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): device state change: prepare -> config (reason 'none') [40 50 0]
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0/wireless): connection '9V4M1' has security, and secrets exist.  No new secrets needed.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: added 'ssid' value '9V4M1'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: added 'scan_ssid' value '1'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: added 'key_mgmt' value 'NONE'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: added 'auth_alg' value 'SHARED'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: added 'wep_key0' value '<omitted>'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: added 'wep_tx_keyidx' value '0'
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) complete.
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> Config: set interface ap_scan to 1
>Mar  8 01:36:02 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: inactive -> scanning
>Mar  8 01:36:06 lemur kernel: [73220.781299] wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:06 lemur kernel: wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:06 lemur kernel: [73220.783675] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:06 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:06 lemur kernel: [73220.786128] wlp2s0: 00:18:01:e9:a7:b3 denied authentication (status 13)
>Mar  8 01:36:06 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: scanning -> authenticating
>Mar  8 01:36:06 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 denied authentication (status 13)
>Mar  8 01:36:06 lemur kernel: [73220.794881] wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:06 lemur kernel: wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:06 lemur kernel: [73220.796879] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:06 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:06 lemur kernel: [73220.799330] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:06 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:06 lemur kernel: [73220.899905] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:06 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:06 lemur kernel: [73220.901883] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:06 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:06 lemur kernel: [73221.002745] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:06 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:06 lemur kernel: [73221.005068] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:06 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:06 lemur kernel: [73221.105605] wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:06 lemur kernel: wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:06 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: authenticating -> disconnected
>Mar  8 01:36:16 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: disconnected -> scanning
>Mar  8 01:36:19 lemur kernel: [73234.305325] wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:19 lemur kernel: wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:19 lemur kernel: [73234.307452] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:19 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:19 lemur kernel: [73234.309780] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:19 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:19 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: scanning -> authenticating
>Mar  8 01:36:19 lemur kernel: [73234.409788] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:19 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:19 lemur kernel: [73234.411852] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:19 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:19 lemur kernel: [73234.512651] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:19 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:19 lemur kernel: [73234.514601] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:19 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:20 lemur kernel: [73234.615529] wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:20 lemur kernel: wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:20 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: authenticating -> disconnected
>Mar  8 01:36:23 lemur NetworkManager[799]: <info> (wlp2s0): device state change: config -> disconnected (reason 'user-requested') [50 30 39]
>Mar  8 01:36:23 lemur NetworkManager[799]: <info> (wlp2s0): deactivating device (reason 'user-requested') [39]
>Mar  8 01:36:23 lemur NetworkManager[799]: <info> NetworkManager state is now DISCONNECTED
>Mar  8 01:36:23 lemur firewalld: 2014-03-08 01:36:23 ERROR: UNKNOWN_INTERFACE: wlp2s0
>Mar  8 01:36:23 lemur NetworkManager[799]: <warn> (wlp2s0) firewall zone remove failed: (32) UNKNOWN_INTERFACE: wlp2s0
>Mar  8 01:36:26 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: disconnected -> inactive
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) starting connection '9V4M1'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> (wlp2s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> NetworkManager state is now CONNECTING
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) scheduled...
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) started...
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) scheduled...
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) complete.
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) starting...
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> (wlp2s0): device state change: prepare -> config (reason 'none') [40 50 0]
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0/wireless): connection '9V4M1' has security, and secrets exist.  No new secrets needed.
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: added 'ssid' value '9V4M1'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: added 'scan_ssid' value '1'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: added 'key_mgmt' value 'NONE'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: added 'auth_alg' value 'SHARED'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: added 'wep_key0' value '<omitted>'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: added 'wep_tx_keyidx' value '0'
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) complete.
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> Config: set interface ap_scan to 1
>Mar  8 01:36:36 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: inactive -> scanning
>Mar  8 01:36:39 lemur kernel: [73254.456519] wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:39 lemur kernel: wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:39 lemur kernel: [73254.458090] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:39 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:39 lemur kernel: [73254.460763] wlp2s0: 00:18:01:e9:a7:b3 denied authentication (status 13)
>Mar  8 01:36:39 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 denied authentication (status 13)
>Mar  8 01:36:39 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: scanning -> authenticating
>Mar  8 01:36:39 lemur kernel: [73254.470118] wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:39 lemur kernel: wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:39 lemur kernel: [73254.472418] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:39 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:39 lemur kernel: [73254.474815] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:39 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:40 lemur kernel: [73254.575256] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:40 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:40 lemur kernel: [73254.577248] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:40 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:40 lemur kernel: [73254.678127] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:40 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:40 lemur kernel: [73254.679949] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:40 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:40 lemur kernel: [73254.779983] wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:40 lemur kernel: wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:40 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: authenticating -> disconnected
>Mar  8 01:36:50 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: disconnected -> scanning
>Mar  8 01:36:53 lemur kernel: [73267.984525] wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:53 lemur kernel: wlp2s0: authenticate with 00:18:01:e9:a7:b3
>Mar  8 01:36:53 lemur kernel: [73267.986856] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:53 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 1/3)
>Mar  8 01:36:53 lemur kernel: [73267.989405] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:53 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: scanning -> authenticating
>Mar  8 01:36:53 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:53 lemur kernel: [73268.090086] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:53 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 2/3)
>Mar  8 01:36:53 lemur kernel: [73268.092144] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:53 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:53 lemur kernel: [73268.193036] wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:53 lemur kernel: wlp2s0: send auth to 00:18:01:e9:a7:b3 (try 3/3)
>Mar  8 01:36:53 lemur kernel: [73268.195108] wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:53 lemur kernel: wlp2s0: 00:18:01:e9:a7:b3 unexpected authentication state: alg 1 (expected 128) transact 2 (expected 2)
>Mar  8 01:36:53 lemur kernel: [73268.295789] wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:53 lemur kernel: wlp2s0: authentication with 00:18:01:e9:a7:b3 timed out
>Mar  8 01:36:53 lemur NetworkManager[799]: <info> (wlp2s0): supplicant interface state: authenticating -> disconnected
>Mar  8 01:37:01 lemur NetworkManager[799]: <warn> Activation (wlp2s0/wireless): association took too long, failing activation.
>Mar  8 01:37:01 lemur NetworkManager[799]: <info> (wlp2s0): device state change: config -> failed (reason 'SSID not found') [50 120 53]
>Mar  8 01:37:01 lemur NetworkManager[799]: <info> NetworkManager state is now DISCONNECTED

Actions: View

Attachments on bug 1074195: 872239 | 872240