Attachment 676743 Details for Bug 893580 – /Var/log/messages after resume

/Var/log/messages after resume

messages (text/plain), 17.89 KB, created by Milan Bouchet-Valat on 2013-01-11 09:47:36 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Milan Bouchet-Valat

Created: 2013-01-11 09:47:36 UTC

Size: 17.89 KB

patch

obsolete

>Jan 11 10:35:06 milan systemd-sleep[2834]: Suspending system...
>Jan 11 10:35:07 milan kernel: [  516.880711] PM: Syncing filesystems ... done.
>Jan 11 10:35:26 milan kernel: [  517.498307] Freezing user space processes ... (elapsed 0.01 seconds) done.
>Jan 11 10:35:26 milan kernel: [  517.509248] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>Jan 11 10:35:26 milan kernel: [  517.520445] Suspending console(s) (use no_console_suspend to debug)
>Jan 11 10:35:26 milan kernel: [  517.522795] sd 0:0:0:0: [sda] Synchronizing SCSI cache
>Jan 11 10:35:26 milan kernel: [  517.523062] sd 0:0:0:0: [sda] Stopping disk
>Jan 11 10:35:26 milan kernel: [  518.117254] PM: suspend of devices complete after 597.650 msecs
>Jan 11 10:35:26 milan kernel: [  518.117381] PM: late suspend of devices complete after 0.124 msecs
>Jan 11 10:35:26 milan kernel: [  518.136985] r8169 0000:01:00.0: wake-up capability enabled by ACPI
>Jan 11 10:35:26 milan kernel: [  518.148138] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
>Jan 11 10:35:26 milan kernel: [  518.159071] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
>Jan 11 10:35:26 milan kernel: [  518.180963] PM: noirq suspend of devices complete after 63.688 msecs
>Jan 11 10:35:26 milan kernel: [  518.181137] ACPI: Preparing to enter system sleep state S3
>Jan 11 10:35:26 milan kernel: [  518.228162] PM: Saving platform NVS memory
>Jan 11 10:35:26 milan kernel: [  518.234798] Disabling non-boot CPUs ...
>Jan 11 10:35:26 milan kernel: [  518.236598] smpboot: CPU 1 is now offline
>Jan 11 10:35:26 milan kernel: [  518.237033] CPU 1 offline: Remove Rx thread
>Jan 11 10:35:26 milan kernel: [  518.238815] smpboot: CPU 2 is now offline
>Jan 11 10:35:26 milan kernel: [  518.239225] CPU 2 offline: Remove Rx thread
>Jan 11 10:35:26 milan kernel: [  518.240743] smpboot: CPU 3 is now offline
>Jan 11 10:35:26 milan kernel: [  518.241093] CPU 3 offline: Remove Rx thread
>Jan 11 10:35:26 milan kernel: [  518.241218] Extended CMOS year: 2000
>Jan 11 10:35:26 milan kernel: [  518.241887] ACPI: Low-level resume complete
>Jan 11 10:35:26 milan kernel: [  518.241941] PM: Restoring platform NVS memory
>Jan 11 10:35:26 milan kernel: [  518.242493] Extended CMOS year: 2000
>Jan 11 10:35:26 milan kernel: [  518.242727] microcode: CPU0 updated to revision 0x3, date = 2011-09-01
>Jan 11 10:35:26 milan kernel: [  518.242752] Enabling non-boot CPUs ...
>Jan 11 10:35:26 milan kernel: [  518.242826] smpboot: Booting Node 0 Processor 1 APIC 0x1
>Jan 11 10:35:26 milan kernel: [  518.256368] ACPI Error: [NPSS] Namespace lookup failure, AE_NOT_FOUND (20120913/psargs-359)
>Jan 11 10:35:26 milan kernel: [  518.256372] ACPI Error: Method parse/execution failed [\_PR_.CPU0.PPC_] (Node ffff880151e268e8), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.256375] ACPI Error: Method parse/execution failed [\_PR_.CPU0._PPC] (Node ffff880151e267d0), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.256378] ACPI Error: Method parse/execution failed [\_PR_.CPU1._PPC] (Node ffff880151e268c0), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.256581] microcode: CPU1 updated to revision 0x3, date = 2011-09-01
>Jan 11 10:35:26 milan kernel: [  518.256587] bnx2i: CPU 1 online: Create Rx thread
>Jan 11 10:35:26 milan kernel: [  518.256630] CPU1 is up
>Jan 11 10:35:26 milan kernel: [  518.256662] smpboot: Booting Node 0 Processor 2 APIC 0x4
>Jan 11 10:35:26 milan kernel: [  518.270156] ACPI Error: [NPSS] Namespace lookup failure, AE_NOT_FOUND (20120913/psargs-359)
>Jan 11 10:35:26 milan kernel: [  518.270160] ACPI Error: Method parse/execution failed [\_PR_.CPU0.PPC_] (Node ffff880151e268e8), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.270163] ACPI Error: Method parse/execution failed [\_PR_.CPU0._PPC] (Node ffff880151e267d0), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.270166] ACPI Error: Method parse/execution failed [\_PR_.CPU2._PPC] (Node ffff880151e26b18), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.270358] microcode: CPU2 updated to revision 0x3, date = 2011-09-01
>Jan 11 10:35:26 milan kernel: [  518.270362] bnx2i: CPU 2 online: Create Rx thread
>Jan 11 10:35:26 milan kernel: [  518.270399] CPU2 is up
>Jan 11 10:35:26 milan kernel: [  518.270431] smpboot: Booting Node 0 Processor 3 APIC 0x5
>Jan 11 10:35:26 milan kernel: [  518.283987] ACPI Error: [NPSS] Namespace lookup failure, AE_NOT_FOUND (20120913/psargs-359)
>Jan 11 10:35:26 milan kernel: [  518.283992] ACPI Error: Method parse/execution failed [\_PR_.CPU0.PPC_] (Node ffff880151e268e8), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.283996] ACPI Error: Method parse/execution failed [\_PR_.CPU0._PPC] (Node ffff880151e267d0), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.283999] ACPI Error: Method parse/execution failed [\_PR_.CPU3._PPC] (Node ffff880151e26a78), AE_NOT_FOUND (20120913/psparse-536)
>Jan 11 10:35:26 milan kernel: [  518.284206] microcode: CPU3 updated to revision 0x3, date = 2011-09-01
>Jan 11 10:35:26 milan kernel: [  518.284214] bnx2i: CPU 3 online: Create Rx thread
>Jan 11 10:35:26 milan kernel: [  518.284267] CPU3 is up
>Jan 11 10:35:26 milan kernel: [  518.287948] ACPI: Waking up from system sleep state S3
>Jan 11 10:35:26 milan kernel: [  518.403856] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
>Jan 11 10:35:26 milan kernel: [  518.425811] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
>Jan 11 10:35:26 milan kernel: [  518.458955] PM: noirq resume of devices complete after 87.535 msecs
>Jan 11 10:35:26 milan kernel: [  518.459077] PM: early resume of devices complete after 0.082 msecs
>Jan 11 10:35:26 milan kernel: [  518.460179] mei 0000:00:16.0: wd: failed to find the client
>Jan 11 10:35:26 milan kernel: [  518.472708] r8169 0000:01:00.0: wake-up capability disabled by ACPI
>Jan 11 10:35:26 milan kernel: [  518.533901] r8169 0000:01:00.0 em1: link down
>Jan 11 10:35:26 milan kernel: [  518.690636] usb 1-1.4: reset full-speed USB device number 4 using ehci_hcd
>Jan 11 10:35:26 milan kernel: [  518.770192] ata5: SATA link down (SStatus 0 SControl 300)
>Jan 11 10:35:26 milan kernel: [  518.772255] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
>Jan 11 10:35:26 milan kernel: [  518.830163] ata2.00: configured for UDMA/100
>Jan 11 10:35:26 milan kernel: [  518.848231] usb 2-1.6: reset high-speed USB device number 4 using ehci_hcd
>Jan 11 10:35:26 milan kernel: [  518.997102] usb 2-1.3: reset full-speed USB device number 3 using ehci_hcd
>Jan 11 10:35:26 milan kernel: [  519.083822] btusb 2-1.3:1.0: no reset_resume for driver btusb?
>Jan 11 10:35:26 milan kernel: [  519.083824] btusb 2-1.3:1.1: no reset_resume for driver btusb?
>Jan 11 10:35:26 milan kernel: [  520.702912] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>Jan 11 10:35:26 milan kernel: [  520.705400] ata1.00: configured for UDMA/100
>Jan 11 10:35:26 milan kernel: [  520.705519] sd 0:0:0:0: [sda] Starting disk
>Jan 11 10:35:26 milan kernel: [  520.712582] Extended CMOS year: 2000
>Jan 11 10:35:26 milan kernel: [  521.418506] ieee80211 phy0: brcms_ops_config: change power-save mode: false (implement)
>Jan 11 10:35:26 milan kernel: [  521.418660] ieee80211 phy0: brcmsmac: brcms_ops_bss_info_changed: associated
>Jan 11 10:35:26 milan kernel: [  521.418664] ieee80211 phy0: changing basic rates failed: -22
>Jan 11 10:35:26 milan kernel: [  521.418667] ieee80211 phy0: brcms_ops_bss_info_changed: cqm change: threshold 0, hys 0  (implement)
>Jan 11 10:35:26 milan kernel: [  521.418670] ieee80211 phy0: brcms_ops_bss_info_changed: arp filtering: enabled true, count 1 (implement)
>Jan 11 10:35:26 milan kernel: [  521.418672] ieee80211 phy0: brcms_ops_bss_info_changed: qos enabled: true (implement)
>Jan 11 10:35:26 milan kernel: [  521.418931] PM: resume of devices complete after 2964.993 msecs
>Jan 11 10:35:26 milan kernel: [  521.442272] Restarting tasks ... done.
>Jan 11 10:35:26 milan kernel: [  521.445310] video LNXVIDEO:00: Restoring backlight state
>Jan 11 10:35:26 milan bluetoothd[684]: bluetoothd[684]: Adapter /org/bluez/684/hci0 has been disabled
>Jan 11 10:35:26 milan kernel: [  521.449673] ieee80211 phy0: brcmsmac: brcms_ops_bss_info_changed: disassociated
>Jan 11 10:35:26 milan kernel: [  521.449688] ieee80211 phy0: brcms_ops_bss_info_changed: arp filtering: enabled false, count 1 (implement)
>Jan 11 10:35:26 milan kernel: [  521.449694] ieee80211 phy0: brcms_ops_bss_info_changed: qos enabled: false (implement)
>Jan 11 10:35:26 milan bluetoothd[684]: bluetoothd[684]: Unregister path: /org/bluez/684/hci0
>Jan 11 10:35:26 milan bluetoothd[684]: bluetoothd[684]: Endpoint unregistered: sender=:1.69 path=/MediaEndpoint/A2DPSource
>Jan 11 10:35:26 milan bluetoothd[684]: bluetoothd[684]: Endpoint unregistered: sender=:1.69 path=/MediaEndpoint/HFPAG
>Jan 11 10:35:26 milan kernel: [  521.462063] cfg80211: Calling CRDA to update world regulatory domain
>Jan 11 10:35:26 milan bluetoothd[684]: bluetoothd[684]: Endpoint unregistered: sender=:1.69 path=/MediaEndpoint/HFPHS
>Jan 11 10:35:26 milan bluetoothd[684]: Adapter /org/bluez/684/hci0 has been disabled
>Jan 11 10:35:26 milan bluetoothd[684]: Unregister path: /org/bluez/684/hci0
>Jan 11 10:35:26 milan bluetoothd[684]: Endpoint unregistered: sender=:1.69 path=/MediaEndpoint/A2DPSource
>Jan 11 10:35:26 milan bluetoothd[684]: Endpoint unregistered: sender=:1.69 path=/MediaEndpoint/HFPAG
>Jan 11 10:35:26 milan bluetoothd[684]: Endpoint unregistered: sender=:1.69 path=/MediaEndpoint/HFPHS
>Jan 11 10:35:26 milan systemd-sleep[2834]: System resumed.
>Jan 11 10:35:26 milan systemd[1]: Service bluetooth.target is not needed anymore. Stopping.
>Jan 11 10:35:26 milan systemd[1]: Stopping Bluetooth.
>Jan 11 10:35:26 milan systemd[1]: Stopped target Bluetooth.
>Jan 11 10:35:26 milan dbus-daemon[712]: dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=1000 pid=1600 comm="/usr/bin/pulseaudio --start ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=1000 pid=1600 comm="/usr/bin/pulseaudio --start ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=1000 pid=1600 comm="/usr/bin/pulseaudio --start ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=1000 pid=1600 comm="/usr/bin/pulseaudio --start ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan dbus-daemon[712]: dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=1000 pid=1600 comm="/usr/bin/pulseaudio --start ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan dbus-daemon[712]: dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.69" (uid=1000 pid=1600 comm="/usr/bin/pulseaudio --start ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan systemd[1]: Starting Bluetooth.
>Jan 11 10:35:26 milan systemd[1]: Reached target Bluetooth.
>Jan 11 10:35:26 milan kernel: [  521.476986] cfg80211: World regulatory domain updated:
>Jan 11 10:35:26 milan kernel: [  521.476990] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
>Jan 11 10:35:26 milan kernel: [  521.476992] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 11 10:35:26 milan kernel: [  521.476994] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>Jan 11 10:35:26 milan kernel: [  521.476996] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
>Jan 11 10:35:26 milan kernel: [  521.476998] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 11 10:35:26 milan kernel: [  521.476999] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
>Jan 11 10:35:26 milan NetworkManager[755]: <info> (wlan0): supplicant interface state: completed -> disconnected
>Jan 11 10:35:26 milan systemd[1]: Started Suspend.
>Jan 11 10:35:26 milan systemd[1]: Service sleep.target is not needed anymore. Stopping.
>Jan 11 10:35:26 milan systemd[1]: Stopping Sleep.
>Jan 11 10:35:26 milan systemd[1]: Stopped target Sleep.
>Jan 11 10:35:26 milan systemd[1]: Starting Suspend.
>Jan 11 10:35:26 milan systemd[1]: Reached target Suspend.
>Jan 11 10:35:26 milan dbus-daemon[712]: dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.81" (uid=1000 pid=1624 comm="/usr/bin/gnome-shell ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan dbus[712]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.81" (uid=1000 pid=1624 comm="/usr/bin/gnome-shell ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.2" (uid=0 pid=684 comm="/usr/sbin/bluetoothd -n ")
>Jan 11 10:35:26 milan NetworkManager[755]: <info> (wlan0): supplicant interface state: disconnected -> scanning
>Jan 11 10:35:27 milan kernel: [  522.436789] Bluetooth: hci0 command 0x1005 tx timeout
>Jan 11 10:35:27 milan bluetoothd[684]: bluetoothd[684]: Parsing /etc/bluetooth/serial.conf failed: No such file or directory
>Jan 11 10:35:27 milan bluetoothd[684]: Parsing /etc/bluetooth/serial.conf failed: No such file or directory
>Jan 11 10:35:27 milan bluetoothd[684]: bluetoothd[684]: Adapter /org/bluez/684/hci0 has been enabled
>Jan 11 10:35:27 milan bluetoothd[684]: bluetoothd[684]: hci0: Set Discoverable (0x0006) failed: Busy (0x0a)
>Jan 11 10:35:27 milan bluetoothd[684]: Adapter /org/bluez/684/hci0 has been enabled
>Jan 11 10:35:27 milan bluetoothd[684]: hci0: Set Discoverable (0x0006) failed: Busy (0x0a)
>Jan 11 10:35:27 milan bluetoothd[684]: bluetoothd[684]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/HFPAG
>Jan 11 10:35:27 milan bluetoothd[684]: bluetoothd[684]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/HFPHS
>Jan 11 10:35:27 milan bluetoothd[684]: bluetoothd[684]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/A2DPSource
>Jan 11 10:35:27 milan bluetoothd[684]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/HFPAG
>Jan 11 10:35:27 milan bluetoothd[684]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/HFPHS
>Jan 11 10:35:27 milan bluetoothd[684]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/A2DPSource
>Jan 11 10:35:42 milan NetworkManager[755]: <warn> (wlan0): link timed out.
>Jan 11 10:35:42 milan NetworkManager[755]: <info> (wlan0): device state change: activated -> failed (reason 'SSID not found') [100 120 53]
>Jan 11 10:35:42 milan NetworkManager[755]: <warn> Activation (wlan0) failed for connection 'Auto NUMERICABLE-760E'
>Jan 11 10:35:42 milan NetworkManager[755]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
>Jan 11 10:35:42 milan NetworkManager[755]: <info> (wlan0): deactivating device (reason 'none') [0]
>Jan 11 10:35:42 milan dbus-daemon[712]: dbus[712]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
>Jan 11 10:35:42 milan dbus[712]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
>Jan 11 10:35:42 milan dbus-daemon[712]: dbus[712]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Jan 11 10:35:42 milan dbus[712]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>Jan 11 10:35:42 milan systemd[1]: Stopping Sendmail Mail Transport Client...
>Jan 11 10:35:42 milan systemd[1]: Stopping Sendmail Mail Transport Agent...
>Jan 11 10:35:42 milan systemd[1]: Starting Sendmail Mail Transport Agent...
>Jan 11 10:35:42 milan NetworkManager[755]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 2752
>Jan 11 10:35:42 milan avahi-daemon[709]: Withdrawing address record for 192.168.0.14 on wlan0.
>Jan 11 10:35:42 milan avahi-daemon[709]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.14.
>Jan 11 10:35:42 milan avahi-daemon[709]: Interface wlan0.IPv4 no longer relevant for mDNS.
>Jan 11 10:35:42 milan NetworkManager[755]: <info> (wlan0): supplicant interface state: scanning -> disconnected
>Jan 11 10:35:46 milan nm-dispatcher.action: Script '/etc/NetworkManager/dispatcher.d/10-sendmail' took too long; killing it.
>Jan 11 10:35:46 milan chronyd[1774]: Source 88.190.227.30 offline
>Jan 11 10:35:46 milan chronyd[1774]: Source 193.106.144.7 offline
>Jan 11 10:35:46 milan chronyd[1774]: Source 88.191.80.53 offline
>Jan 11 10:35:46 milan chronyd[1774]: Source 188.165.45.139 offline
>Jan 11 10:35:46 milan dbus-daemon[712]: Failed to issue method call: Unit winbind.service is not loaded.
>Jan 11 10:35:46 milan NetworkManager[755]: <warn> Dispatcher script timed out: Script '/etc/NetworkManager/dispatcher.d/10-sendmail' timed out.
>Jan 11 10:35:47 milan systemd[1]: PID file /run/sendmail.pid not readable (yet?) after start.
>Jan 11 10:35:47 milan systemd[1]: Started Sendmail Mail Transport Agent.
>Jan 11 10:35:47 milan systemd[1]: Starting Sendmail Mail Transport Client...
>Jan 11 10:35:47 milan systemd[1]: Started Sendmail Mail Transport Client.

Actions: View

Attachments on bug 893580: 676743 | 676744 | 676745 | 695984