Attachment 857957 Details for Bug 1060321 – system log from closing lid until reconnect

system log from closing lid until reconnect

system-log-suspend (text/plain), 28.88 KB, created by froggy on 2014-01-31 19:09:33 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: froggy

Created: 2014-01-31 19:09:33 UTC

Size: 28.88 KB

patch

obsolete

>
>Jan 31 20:01:07 localhost systemd-logind: Lid closed.
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> sleep requested (sleeping: no  enabled: yes)
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> sleeping or disabling...
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> (p4p1): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> (p4p1): taking down device.
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> NetworkManager state is now ASLEEP
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> (wlp12s0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
>Jan 31 20:01:07 localhost NetworkManager[637]: <info> (wlp12s0): deactivating device (reason 'sleeping') [37]
>Jan 31 20:01:08 localhost NetworkManager[637]: <info> (wlp12s0): canceled DHCP transaction, DHCP client pid 4229
>Jan 31 20:01:08 localhost avahi-daemon[636]: Withdrawing address record for 192.168.1.104 on wlp12s0.
>Jan 31 20:01:08 localhost kernel: [ 5931.418042] wlp12s0: deauthenticating from a0:f3:c1:5f:df:d6 by local choice (reason=3)
>Jan 31 20:01:08 localhost avahi-daemon[636]: Leaving mDNS multicast group on interface wlp12s0.IPv4 with address 192.168.1.104.
>Jan 31 20:01:08 localhost avahi-daemon[636]: Interface wlp12s0.IPv4 no longer relevant for mDNS.
>Jan 31 20:01:08 localhost kernel: [ 5931.440693] cfg80211: Calling CRDA to update world regulatory domain
>Jan 31 20:01:08 localhost NetworkManager[637]: <info> (wlp12s0): taking down device.
>Jan 31 20:01:08 localhost avahi-daemon[636]: Withdrawing address record for fe80::21f:3cff:fe6f:e0a8 on wlp12s0.
>Jan 31 20:01:08 localhost kernel: [ 5931.463212] cfg80211: World regulatory domain updated:
>Jan 31 20:01:08 localhost kernel: [ 5931.463220] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>Jan 31 20:01:08 localhost kernel: [ 5931.463226] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:01:08 localhost kernel: [ 5931.463231] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:01:08 localhost kernel: [ 5931.463235] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:01:08 localhost kernel: [ 5931.463239] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:01:08 localhost kernel: [ 5931.463244] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:01:08 localhost dbus-daemon: dbus[651]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
>Jan 31 20:01:08 localhost dbus[651]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
>Jan 31 20:01:08 localhost systemd: Starting Network Manager Script Dispatcher Service...
>Jan 31 20:01:08 localhost dbus-daemon: dbus[651]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Jan 31 20:01:08 localhost dbus[651]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Jan 31 20:01:08 localhost systemd: Started Network Manager Script Dispatcher Service.
>Jan 31 20:01:08 localhost chronyd[632]: Source 79.132.237.6 offline
>Jan 31 20:01:08 localhost chronyd[632]: Source 85.234.197.3 offline
>Jan 31 20:01:08 localhost chronyd[632]: Source 194.50.97.12 offline
>Jan 31 20:01:08 localhost chronyd[632]: Source 193.190.147.153 offline
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> wake requested (sleeping: yes  enabled: yes)
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> waking up and re-enabling...
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (p4p1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (p4p1): bringing up device.
>Jan 31 20:01:10 localhost systemd: Starting Sleep.
>Jan 31 20:01:10 localhost systemd: Reached target Sleep.
>Jan 31 20:01:10 localhost systemd: Starting Suspend...
>Jan 31 20:01:10 localhost kernel: [ 5933.738635] IPv6: ADDRCONF(NETDEV_UP): p4p1: link is not ready
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> NetworkManager state is now DISCONNECTED
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0): bringing up device.
>Jan 31 20:01:10 localhost systemd-sleep: Suspending system...
>Jan 31 20:01:10 localhost kernel: [ 5933.817182] IPv6: ADDRCONF(NETDEV_UP): wlp12s0: link is not ready
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0) supports 4 scan SSIDs
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: starting -> ready
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: ready -> disconnected
>Jan 31 20:01:10 localhost NetworkManager[637]: <info> (wlp12s0) supports 4 scan SSIDs
>Jan 31 20:01:10 localhost kernel: [ 5933.754986] PM: Syncing filesystems ... done.
>Jan 31 20:01:10 localhost kernel: [ 5933.881993] mmc0: card 0001 removed
>Jan 31 20:03:02 localhost kernel: [ 5933.895218] Freezing user space processes ... (elapsed 0.003 seconds) done.
>Jan 31 20:03:02 localhost kernel: [ 5933.898420] Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done.
>Jan 31 20:03:02 localhost kernel: [ 5933.899427] Suspending console(s) (use no_console_suspend to debug)
>Jan 31 20:03:02 localhost kernel: [ 5933.909804] sd 0:0:0:0: [sda] Synchronizing SCSI cache
>Jan 31 20:03:02 localhost kernel: [ 5933.913491] sd 0:0:0:0: [sda] Stopping disk
>Jan 31 20:03:02 localhost kernel: [ 5934.148244] PM: suspend of devices complete after 248.379 msecs
>Jan 31 20:03:02 localhost kernel: [ 5934.148819] PM: late suspend of devices complete after 0.567 msecs
>Jan 31 20:03:02 localhost kernel: [ 5934.148996] pci 0000:00:1e.0: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.160117] pci 0000:00:1e.0: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.171110] pci 0000:00:1e.0: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.182558] tg3 0000:09:00.0: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.207103] ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.220949] uhci_hcd 0000:00:1d.3: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.223789] uhci_hcd 0000:00:1d.2: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.226031] uhci_hcd 0000:00:1d.1: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.228118] uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.228361] PM: noirq suspend of devices complete after 79.537 msecs
>Jan 31 20:03:02 localhost kernel: [ 5934.228675] ACPI: Preparing to enter system sleep state S3
>Jan 31 20:03:02 localhost kernel: [ 5934.232269] PM: Saving platform NVS memory
>Jan 31 20:03:02 localhost kernel: [ 5934.232272] Disabling non-boot CPUs ...
>Jan 31 20:03:02 localhost kernel: [ 5934.234657] smpboot: CPU 1 is now offline
>Jan 31 20:03:02 localhost kernel: [ 5934.235028] ACPI: Low-level resume complete
>Jan 31 20:03:02 localhost kernel: [ 5934.235028] PM: Restoring platform NVS memory
>Jan 31 20:03:02 localhost kernel: [ 5934.235028] microcode: CPU0 sig=0x6fd, pf=0x20, revision=0xa4
>Jan 31 20:03:02 localhost kernel: [ 5934.235028] Enabling non-boot CPUs ...
>Jan 31 20:03:02 localhost kernel: [ 5934.235028] smpboot: Booting Node 0 Processor 1 APIC 0x1
>Jan 31 20:03:02 localhost kernel: [ 5934.247404] microcode: CPU1 sig=0x6fd, pf=0x20, revision=0xa3
>Jan 31 20:03:02 localhost kernel: [ 5934.247719] microcode: CPU1 updated to revision 0xa4, date = 2010-10-02
>Jan 31 20:03:02 localhost kernel: [ 5934.247760] CPU1 is up
>Jan 31 20:03:02 localhost kernel: [ 5934.249471] ACPI: Waking up from system sleep state S3
>Jan 31 20:03:02 localhost kernel: [ 5934.304133] uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.305983] uhci_hcd 0000:00:1d.1: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.307842] uhci_hcd 0000:00:1d.2: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.309646] uhci_hcd 0000:00:1d.3: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5934.322661] ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5935.080121] pci 0000:00:1e.0: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5935.091118] pci 0000:00:1e.0: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5935.102125] pci 0000:00:1e.0: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5935.102157] PM: noirq resume of devices complete after 823.183 msecs
>Jan 31 20:03:02 localhost kernel: [ 5935.102502] PM: early resume of devices complete after 0.287 msecs
>Jan 31 20:03:02 localhost kernel: [ 5935.134961] usb usb2: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.135218] usb usb3: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.135453] usb usb4: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.135687] usb usb5: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.136189] tg3 0000:09:00.0: System wakeup disabled by ACPI
>Jan 31 20:03:02 localhost kernel: [ 5935.173154] ata1.00: _GTF evaluation failed (AE 0x1001)
>Jan 31 20:03:02 localhost kernel: [ 5935.255072] usb usb6: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.283926] usb usb7: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.283964] usb usb8: root hub lost power or was reset
>Jan 31 20:03:02 localhost kernel: [ 5935.333575] ata1.00: configured for UDMA/100
>Jan 31 20:03:02 localhost kernel: [ 5935.333698] sd 0:0:0:0: [sda] Starting disk
>Jan 31 20:03:02 localhost kernel: [ 5935.850599] firewire_core 0000:02:01.1: rediscovered device fw0
>Jan 31 20:03:02 localhost kernel: [ 5935.901255] usb 2-2: reset full-speed USB device number 2 using uhci_hcd
>Jan 31 20:03:02 localhost kernel: [ 5936.319708] usb 2-2.3: reset full-speed USB device number 3 using uhci_hcd
>Jan 31 20:03:02 localhost kernel: [ 5936.704198] usb 2-2.3.2: reset full-speed USB device number 4 using uhci_hcd
>Jan 31 20:03:02 localhost kernel: [ 5936.797714] PM: resume of devices complete after 1695.199 msecs
>Jan 31 20:03:02 localhost kernel: [ 5936.798068] ohci-pci 0000:03:00.0: remove, state 1
>Jan 31 20:03:02 localhost kernel: [ 5936.798082] usb usb6: USB disconnect, device number 1
>Jan 31 20:03:02 localhost kernel: [ 5936.798806] ohci-pci 0000:03:00.0: USB bus 6 deregistered
>Jan 31 20:03:02 localhost kernel: [ 5936.799444] ohci-pci 0000:03:00.1: remove, state 1
>Jan 31 20:03:02 localhost kernel: [ 5936.799454] usb usb7: USB disconnect, device number 1
>Jan 31 20:03:02 localhost kernel: [ 5936.799918] ohci-pci 0000:03:00.1: USB bus 7 deregistered
>Jan 31 20:03:02 localhost kernel: [ 5936.800442] ehci-pci 0000:03:00.2: remove, state 1
>Jan 31 20:03:02 localhost kernel: [ 5936.800452] usb usb8: USB disconnect, device number 1
>Jan 31 20:03:02 localhost kernel: [ 5936.801120] ehci-pci 0000:03:00.2: USB bus 8 deregistered
>Jan 31 20:03:02 localhost kernel: [ 5936.802702] pci 0000:03:00.0: BAR 0: assigned [mem 0x8c000000-0x8c000fff]
>Jan 31 20:03:02 localhost kernel: [ 5936.802714] pci 0000:03:00.1: BAR 0: assigned [mem 0x8c001000-0x8c001fff]
>Jan 31 20:03:02 localhost kernel: [ 5936.802726] pci 0000:03:00.2: BAR 0: assigned [mem 0x8c002000-0x8c0020ff]
>Jan 31 20:03:02 localhost kernel: [ 5936.803324] ohci-pci 0000:03:00.0: OHCI PCI host controller
>Jan 31 20:03:02 localhost kernel: [ 5936.803850] ohci-pci 0000:03:00.0: new USB bus registered, assigned bus number 6
>Jan 31 20:03:02 localhost kernel: [ 5936.803887] ohci-pci 0000:03:00.0: irq 19, io mem 0x8c000000
>Jan 31 20:03:02 localhost kernel: [ 5936.884222] usb usb6: New USB device found, idVendor=1d6b, idProduct=0001
>Jan 31 20:03:02 localhost kernel: [ 5936.884227] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1
>Jan 31 20:03:02 localhost kernel: [ 5936.884232] usb usb6: Product: OHCI PCI host controller
>Jan 31 20:03:02 localhost kernel: [ 5936.884236] usb usb6: Manufacturer: Linux 3.12.8-300.fc20.x86_64 ohci_hcd
>Jan 31 20:03:02 localhost kernel: [ 5936.884240] usb usb6: SerialNumber: 0000:03:00.0
>Jan 31 20:03:02 localhost kernel: [ 5936.884759] hub 6-0:1.0: USB hub found
>Jan 31 20:03:02 localhost kernel: [ 5936.884781] hub 6-0:1.0: 3 ports detected
>Jan 31 20:03:02 localhost kernel: [ 5936.885607] ohci-pci 0000:03:00.1: OHCI PCI host controller
>Jan 31 20:03:02 localhost kernel: [ 5936.885811] ohci-pci 0000:03:00.1: new USB bus registered, assigned bus number 7
>Jan 31 20:03:02 localhost kernel: [ 5936.885843] ohci-pci 0000:03:00.1: irq 19, io mem 0x8c001000
>Jan 31 20:03:02 localhost kernel: [ 5936.966362] usb usb7: New USB device found, idVendor=1d6b, idProduct=0001
>Jan 31 20:03:02 localhost kernel: [ 5936.966367] usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1
>Jan 31 20:03:02 localhost kernel: [ 5936.966370] usb usb7: Product: OHCI PCI host controller
>Jan 31 20:03:02 localhost kernel: [ 5936.966374] usb usb7: Manufacturer: Linux 3.12.8-300.fc20.x86_64 ohci_hcd
>Jan 31 20:03:02 localhost kernel: [ 5936.966377] usb usb7: SerialNumber: 0000:03:00.1
>Jan 31 20:03:02 localhost kernel: [ 5936.966781] hub 7-0:1.0: USB hub found
>Jan 31 20:03:02 localhost kernel: [ 5936.966798] hub 7-0:1.0: 2 ports detected
>Jan 31 20:03:02 localhost kernel: [ 5936.967839] ehci-pci 0000:03:00.2: EHCI Host Controller
>Jan 31 20:03:02 localhost kernel: [ 5936.968002] ehci-pci 0000:03:00.2: new USB bus registered, assigned bus number 8
>Jan 31 20:03:02 localhost kernel: [ 5936.968114] ehci-pci 0000:03:00.2: irq 19, io mem 0x8c002000
>Jan 31 20:03:02 localhost kernel: [ 5936.974149] ehci-pci 0000:03:00.2: USB 2.0 started, EHCI 1.00
>Jan 31 20:03:02 localhost kernel: [ 5936.974230] usb usb8: New USB device found, idVendor=1d6b, idProduct=0002
>Jan 31 20:03:02 localhost kernel: [ 5936.974234] usb usb8: New USB device strings: Mfr=3, Product=2, SerialNumber=1
>Jan 31 20:03:02 localhost kernel: [ 5936.974237] usb usb8: Product: EHCI Host Controller
>Jan 31 20:03:02 localhost kernel: [ 5936.974241] usb usb8: Manufacturer: Linux 3.12.8-300.fc20.x86_64 ehci_hcd
>Jan 31 20:03:02 localhost kernel: [ 5936.974244] usb usb8: SerialNumber: 0000:03:00.2
>Jan 31 20:03:02 localhost kernel: [ 5936.974560] hub 8-0:1.0: USB hub found
>Jan 31 20:03:02 localhost kernel: [ 5936.974574] hub 8-0:1.0: 5 ports detected
>Jan 31 20:03:02 localhost kernel: [ 5937.023393] hub 6-0:1.0: USB hub found
>Jan 31 20:03:02 localhost kernel: [ 5937.023422] hub 6-0:1.0: 3 ports detected
>Jan 31 20:03:02 localhost kernel: [ 5937.072384] hub 7-0:1.0: USB hub found
>Jan 31 20:03:02 localhost kernel: [ 5937.072414] hub 7-0:1.0: 2 ports detected
>Jan 31 20:03:02 localhost systemd-logind: Lid opened.
>Jan 31 20:03:02 localhost kernel: [ 5937.073455] Restarting tasks ... done.
>Jan 31 20:03:02 localhost systemd: Time has been changed
>Jan 31 20:03:02 localhost systemd: Time has been changed
>Jan 31 20:03:02 localhost systemd: Time has been changed
>Jan 31 20:03:02 localhost systemd: Time has been changed
>Jan 31 20:03:02 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: disconnected -> inactive
>Jan 31 20:03:02 localhost udisksd[1076]: Cleaning up mount point /run/media/wout/backup-sd (device 179:1 no longer exist)
>Jan 31 20:03:02 localhost kernel: [ 5937.168247] video LNXVIDEO:00: Restoring backlight state
>Jan 31 20:03:02 localhost systemd-sleep: System resumed.
>Jan 31 20:03:03 localhost systemd: Started Suspend.
>Jan 31 20:03:03 localhost systemd: Requested transaction contradicts existing jobs: File exists
>Jan 31 20:03:03 localhost systemd: Service sleep.target is not needed anymore. Stopping.
>Jan 31 20:03:03 localhost systemd: Stopping Sleep.
>Jan 31 20:03:03 localhost systemd: Stopped target Sleep.
>Jan 31 20:03:03 localhost systemd: Reached target Suspend.
>Jan 31 20:03:03 localhost systemd-logind: Operation finished.
>Jan 31 20:03:03 localhost kernel: [ 5937.323207] mmc0: new high speed SDHC card at address 0001
>Jan 31 20:03:03 localhost kernel: [ 5937.326749] mmcblk0: mmc0:0001 00000 29.2 GiB 
>Jan 31 20:03:03 localhost kernel: [ 5937.331090]  mmcblk0: p1
>Jan 31 20:03:03 localhost kernel: [ 5937.979656] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
>Jan 31 20:03:03 localhost udisksd[1076]: Mounted /dev/mmcblk0p1 at /run/media/wout/backup-sd on behalf of uid 1000
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Auto-activating connection 'REDCAT/bluecat'.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) starting connection 'REDCAT/bluecat'
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> (wlp12s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> NetworkManager state is now CONNECTING
>Jan 31 20:03:26 localhost NetworkManager[637]: <warn> (wlp12s0): add_pending_action (2): 'autoactivate' already added
>Jan 31 20:03:26 localhost NetworkManager[637]: file devices/nm-device.c: line 6928 (nm_device_add_pending_action): should not be reached
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 1 of 5 (Device Prepare) scheduled...
>Jan 31 20:03:26 localhost NetworkManager[637]: <warn> (wlp12s0): remove_pending_action (1): 'autoactivate' never added
>Jan 31 20:03:26 localhost NetworkManager[637]: file devices/nm-device.c: line 6979 (nm_device_remove_pending_action): should not be reached
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 1 of 5 (Device Prepare) started...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 2 of 5 (Device Configure) scheduled...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 1 of 5 (Device Prepare) complete.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 2 of 5 (Device Configure) starting...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> (wlp12s0): device state change: prepare -> config (reason 'none') [40 50 0]
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0/wireless): access point 'REDCAT/bluecat' has security, but secrets are required.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> (wlp12s0): device state change: config -> need-auth (reason 'none') [50 60 0]
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 2 of 5 (Device Configure) complete.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 1 of 5 (Device Prepare) scheduled...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 1 of 5 (Device Prepare) started...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> (wlp12s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 2 of 5 (Device Configure) scheduled...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 1 of 5 (Device Prepare) complete.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 2 of 5 (Device Configure) starting...
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> (wlp12s0): device state change: prepare -> config (reason 'none') [40 50 0]
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0/wireless): connection 'REDCAT/bluecat' has security, and secrets exist.  No new secrets needed.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Config: added 'ssid' value 'REDCAT/bluecat'
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Config: added 'scan_ssid' value '1'
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Config: added 'psk' value '<omitted>'
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Config: added 'proto' value 'WPA RSN'
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 2 of 5 (Device Configure) complete.
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> Config: set interface ap_scan to 1
>Jan 31 20:03:26 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: inactive -> scanning
>Jan 31 20:03:29 localhost kernel: [ 5963.659504] wlp12s0: authenticate with a0:f3:c1:5f:df:d6
>Jan 31 20:03:29 localhost kernel: [ 5963.662740] wlp12s0: send auth to a0:f3:c1:5f:df:d6 (try 1/3)
>Jan 31 20:03:29 localhost kernel: [ 5963.665152] wlp12s0: authenticated
>Jan 31 20:03:29 localhost kernel: [ 5963.666459] wlp12s0: associate with a0:f3:c1:5f:df:d6 (try 1/3)
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: scanning -> authenticating
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: authenticating -> associating
>Jan 31 20:03:29 localhost kernel: [ 5963.678047] wlp12s0: RX AssocResp from a0:f3:c1:5f:df:d6 (capab=0x411 status=0 aid=3)
>Jan 31 20:03:29 localhost kernel: [ 5963.679619] wlp12s0: associated
>Jan 31 20:03:29 localhost kernel: [ 5963.679677] IPv6: ADDRCONF(NETDEV_CHANGE): wlp12s0: link becomes ready
>Jan 31 20:03:29 localhost kernel: [ 5963.679778] cfg80211: Calling CRDA for country: US
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: associating -> associated
>Jan 31 20:03:29 localhost kernel: [ 5963.689613] cfg80211: Regulatory domain changed to country: US
>Jan 31 20:03:29 localhost kernel: [ 5963.689620] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>Jan 31 20:03:29 localhost kernel: [ 5963.689626] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
>Jan 31 20:03:29 localhost kernel: [ 5963.689630] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
>Jan 31 20:03:29 localhost kernel: [ 5963.689634] cfg80211:   (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:03:29 localhost kernel: [ 5963.689639] cfg80211:   (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:03:29 localhost kernel: [ 5963.689643] cfg80211:   (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 31 20:03:29 localhost kernel: [ 5963.689647] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
>Jan 31 20:03:29 localhost kernel: [ 5963.689651] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm)
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: associated -> 4-way handshake
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): supplicant interface state: 4-way handshake -> completed
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'REDCAT/bluecat'.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 3 of 5 (IP Configure Start) scheduled.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 3 of 5 (IP Configure Start) started...
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): device state change: config -> ip-config (reason 'none') [50 70 0]
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Beginning DHCPv4 transaction (timeout in 45 seconds)
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> dhclient started with pid 4480
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 3 of 5 (IP Configure Start) complete.
>Jan 31 20:03:29 localhost NetworkManager[637]: <error> [1391195009.692969] [rdisc/nm-lndp-rdisc.c:221] send_rs(): (wlp12s0): cannot send router solicitation: -99.
>Jan 31 20:03:29 localhost dhclient[4480]: Internet Systems Consortium DHCP Client 4.2.5
>Jan 31 20:03:29 localhost dhclient[4480]: Copyright 2004-2013 Internet Systems Consortium.
>Jan 31 20:03:29 localhost dhclient[4480]: All rights reserved.
>Jan 31 20:03:29 localhost dhclient[4480]: For info, please visit https://www.isc.org/software/dhcp/
>Jan 31 20:03:29 localhost dhclient[4480]: 
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): DHCPv4 state changed nbi -> preinit
>Jan 31 20:03:29 localhost NetworkManager: Internet Systems Consortium DHCP Client 4.2.5
>Jan 31 20:03:29 localhost NetworkManager: Copyright 2004-2013 Internet Systems Consortium.
>Jan 31 20:03:29 localhost NetworkManager: All rights reserved.
>Jan 31 20:03:29 localhost NetworkManager: For info, please visit https://www.isc.org/software/dhcp/
>Jan 31 20:03:29 localhost dhclient[4480]: Listening on LPF/wlp12s0/00:1f:3c:6f:e0:a8
>Jan 31 20:03:29 localhost dhclient[4480]: Sending on   LPF/wlp12s0/00:1f:3c:6f:e0:a8
>Jan 31 20:03:29 localhost dhclient[4480]: Sending on   Socket/fallback
>Jan 31 20:03:29 localhost dhclient[4480]: DHCPREQUEST on wlp12s0 to 255.255.255.255 port 67 (xid=0x63959aac)
>Jan 31 20:03:29 localhost NetworkManager: Listening on LPF/wlp12s0/00:1f:3c:6f:e0:a8
>Jan 31 20:03:29 localhost NetworkManager: Sending on   LPF/wlp12s0/00:1f:3c:6f:e0:a8
>Jan 31 20:03:29 localhost NetworkManager: Sending on   Socket/fallback
>Jan 31 20:03:29 localhost NetworkManager: DHCPREQUEST on wlp12s0 to 255.255.255.255 port 67 (xid=0x63959aac)
>Jan 31 20:03:29 localhost dhclient[4480]: DHCPACK from 192.168.1.1 (xid=0x63959aac)
>Jan 31 20:03:29 localhost NetworkManager: DHCPACK from 192.168.1.1 (xid=0x63959aac)
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): DHCPv4 state changed preinit -> reboot
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   address 192.168.1.104
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   plen 24 (255.255.255.0)
>Jan 31 20:03:29 localhost dhclient[4480]: bound to 192.168.1.104 -- renewal in 108090 seconds.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   gateway 192.168.1.1
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   server identifier 192.168.1.1
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   lease time 259200
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   hostname 'dhcppc4'
>Jan 31 20:03:29 localhost NetworkManager[637]: <info>   nameserver '192.168.1.1'
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 5 of 5 (IPv4 Commit) started...
>Jan 31 20:03:29 localhost avahi-daemon[636]: Joining mDNS multicast group on interface wlp12s0.IPv4 with address 192.168.1.104.
>Jan 31 20:03:29 localhost avahi-daemon[636]: New relevant interface wlp12s0.IPv4 for mDNS.
>Jan 31 20:03:29 localhost avahi-daemon[636]: Registering new address record for 192.168.1.104 on wlp12s0.IPv4.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) Stage 5 of 5 (IPv4 Commit) complete.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> (wlp12s0): device state change: secondaries -> activated (reason 'none') [90 100 0]
>Jan 31 20:03:29 localhost NetworkManager: bound to 192.168.1.104 -- renewal in 108090 seconds.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> NetworkManager state is now CONNECTED_GLOBAL
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Policy set 'REDCAT/bluecat' (wlp12s0) as default for IPv4 routing and DNS.
>Jan 31 20:03:29 localhost NetworkManager[637]: <info> Activation (wlp12s0) successful, device activated.
>Jan 31 20:03:29 localhost dbus[651]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
>Jan 31 20:03:29 localhost dbus-daemon: dbus[651]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
>Jan 31 20:03:29 localhost systemd: Starting Network Manager Script Dispatcher Service...
>Jan 31 20:03:29 localhost dbus-daemon: dbus[651]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Jan 31 20:03:29 localhost dbus[651]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Jan 31 20:03:29 localhost systemd: Started Network Manager Script Dispatcher Service.
>Jan 31 20:03:30 localhost chronyd[632]: Source 79.132.237.6 online
>Jan 31 20:03:30 localhost chronyd[632]: Source 193.190.147.153 online
>Jan 31 20:03:30 localhost chronyd[632]: Source 85.234.197.3 online
>Jan 31 20:03:30 localhost chronyd[632]: Source 194.50.97.12 online
>Jan 31 20:03:30 localhost chronyd[632]: System clock wrong by 0.518845 seconds, adjustment started
>Jan 31 20:03:30 localhost avahi-daemon[636]: Registering new address record for fe80::21f:3cff:fe6f:e0a8 on wlp12s0.*.

Actions: View

Attachments on bug 1060321: 857957 | 992411