On Wed, Jan 24, 2018 at 10:43 PM, Ed Greshko <ed.greshko@greshko.com> wrote:
On 01/25/18 12:41, InvalidPath wrote:
> So since my log paste above I have suspended this guy two more times.. for maybe 10
> minutes then almost an hour respectively and both times the bloody thing started up
> as a new boot.  Each time I went to try sifting thru journalctl again but got
> reather sidetracked on log entries that were erronious or did not belong there..
> i.e. Gnome pieces and parts.  So A minute ago I removed all of Gnome just to make
> sure nothing else might have been affecting this suspend issue.


FWIW, not a whole lot is recorded in the journal when a system is suspended. 

I issued the command "systemctl start systemd-suspend.service" as close to 13:28 as
possible.  I waited 1 minute and hit the power button.  This is all that the journal
recorded.

Jan 25 13:28:01 acer.greshko.com systemd[1]: Reached target Sleep.
Jan 25 13:28:01 acer.greshko.com systemd[1]: Starting Suspend...
Jan 25 13:28:01 acer.greshko.com systemd-sleep[8179]: Suspending system...
Jan 25 13:28:01 acer.greshko.com kernel: PM: suspend entry (deep)
Jan 25 13:28:01 acer.greshko.com kernel: PM: Syncing filesystems ... done.


Jan 25 13:29:02 acer.greshko.com kernel: Freezing user space processes ... (elapsed
0.002 seconds) done.
Jan 25 13:29:02 acer.greshko.com kernel: OOM killer disabled.
Jan 25 13:29:02 acer.greshko.com kernel: Freezing remaining freezable tasks ...
(elapsed 0.001 seconds) done.
Jan 25 13:29:02 acer.greshko.com kernel: Suspending console(s) (use
no_console_suspend to debug)
Jan 25 13:29:02 acer.greshko.com kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jan 25 13:29:02 acer.greshko.com kernel: sd 0:0:0:0: [sda] Stopping disk
Jan 25 13:29:02 acer.greshko.com kernel: PM: suspend devices took 0.421 seconds
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: Preparing to enter system sleep state S3
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: EC: event blocked
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: EC: EC stopped
Jan 25 13:29:02 acer.greshko.com kernel: PM: Saving platform NVS memory
Jan 25 13:29:02 acer.greshko.com kernel: Disabling non-boot CPUs ...
Jan 25 13:29:02 acer.greshko.com kernel: IRQ 27: no longer affine to CPU1
Jan 25 13:29:02 acer.greshko.com kernel: IRQ 28: no longer affine to CPU1
Jan 25 13:29:02 acer.greshko.com kernel: smpboot: CPU 1 is now offline
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: Low-level resume complete
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: EC: EC started
Jan 25 13:29:02 acer.greshko.com kernel: PM: Restoring platform NVS memory
Jan 25 13:29:02 acer.greshko.com kernel: Enabling non-boot CPUs ...
Jan 25 13:29:02 acer.greshko.com kernel: x86: Booting SMP configuration:
Jan 25 13:29:02 acer.greshko.com kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Jan 25 13:29:02 acer.greshko.com kernel:  cache: parent cpu1 should not be sleeping
Jan 25 13:29:02 acer.greshko.com kernel: CPU1 is up
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: Waking up from system sleep state S3
Jan 25 13:29:02 acer.greshko.com kernel: firewire_ohci 0000:0a:09.0: proprietary
Ricoh MMC controller disabled (via firewire function)
Jan 25 13:29:02 acer.greshko.com kernel: firewire_ohci 0000:0a:09.0: MMC cards are
now supported by standard SDHCI controller
Jan 25 13:29:02 acer.greshko.com kernel: usb usb3: root hub lost power or was reset
Jan 25 13:29:02 acer.greshko.com kernel: ACPI: EC: event unblocked
Jan 25 13:29:02 acer.greshko.com kernel: usb usb4: root hub lost power or was reset
Jan 25 13:29:03 acer.greshko.com kernel: sdhci-pci 0000:0a:09.1: Will use DMA mode
even though HW doesn't fully claim to support it.
Jan 25 13:29:03 acer.greshko.com kernel: sdhci-pci 0000:0a:09.1: Will use DMA mode
even though HW doesn't fully claim to support it.
Jan 25 13:29:03 acer.greshko.com kernel: usb usb5: root hub lost power or was reset
Jan 25 13:29:03 acer.greshko.com kernel: usb usb6: root hub lost power or was reset
Jan 25 13:29:03 acer.greshko.com kernel: usb usb7: root hub lost power or was reset
Jan 25 13:29:03 acer.greshko.com kernel: sd 0:0:0:0: [sda] Starting disk
Jan 25 13:29:03 acer.greshko.com kernel: iwl3945 0000:06:00.0: Card state received:
HW:Kill SW:On
Jan 25 13:29:03 acer.greshko.com kernel: iwl3945 0000:06:00.0: Card state received:
HW:On SW:On
Jan 25 13:29:03 acer.greshko.com kernel: tg3 0000:08:00.0 enp8s0: Link is down
Jan 25 13:29:03 acer.greshko.com kernel: ata1: SATA link up 3.0 Gbps (SStatus 123
SControl 300)
Jan 25 13:29:03 acer.greshko.com kernel: ata1.00: unexpected _GTF length (8)
Jan 25 13:29:03 acer.greshko.com kernel: ata1.00: supports DRM functions and may not
be fully accessible
Jan 25 13:29:03 acer.greshko.com kernel: usb 1-2: reset high-speed USB device number
2 using ehci-pci
Jan 25 13:29:03 acer.greshko.com kernel: ata1.00: unexpected _GTF length (8)
Jan 25 13:29:03 acer.greshko.com kernel: ata1.00: supports DRM functions and may not
be fully accessible
Jan 25 13:29:03 acer.greshko.com kernel: usb 7-1: reset full-speed USB device number
2 using uhci_hcd
Jan 25 13:29:03 acer.greshko.com kernel: ata1.00: configured for UDMA/133
Jan 25 13:29:03 acer.greshko.com kernel: ata1.00: Enabling discard_zeroes_data
Jan 25 13:29:03 acer.greshko.com kernel: firewire_core 0000:0a:09.0: rediscovered
device fw0
Jan 25 13:29:03 acer.greshko.com kernel: usb 7-1:1.0: rebind failed: -517
Jan 25 13:29:03 acer.greshko.com kernel: usb 7-1:1.1: rebind failed: -517
Jan 25 13:29:03 acer.greshko.com kernel: PM: resume devices took 0.885 seconds
Jan 25 13:29:03 acer.greshko.com kernel: OOM killer enabled.
Jan 25 13:29:03 acer.greshko.com kernel: Restarting tasks ... done.
Jan 25 13:29:03 acer.greshko.com kernel: video LNXVIDEO:00: Restoring backlight state
Jan 25 13:29:03 acer.greshko.com kernel: PM: suspend exit
Jan 25 13:29:03 acer.greshko.com kernel: ata4.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET
FEATURES) filtered out
Jan 25 13:29:03 acer.greshko.com kernel: ata4.00: ACPI cmd ef/03:42:00:00:00:a0 (SET
FEATURES) filtered out
Jan 25 13:29:03 acer.greshko.com kernel: ata4.00: configured for UDMA/33
Jan 25 13:29:02 acer.greshko.com audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-suspend
comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 25 13:29:02 acer.greshko.com audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-suspend
comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 25 13:29:03 acer.greshko.com audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-rfkill
comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 25 13:29:02 acer.greshko.com bluetoothd[719]: Endpoint unregistered:
sender=:1.142 path=/MediaEndpoint/A2DPSource
Jan 25 13:29:02 acer.greshko.com systemd[1]: Starting Load/Save RF Kill Switch Status...
Jan 25 13:29:02 acer.greshko.com bluetoothd[719]: Endpoint unregistered:
sender=:1.142 path=/MediaEndpoint/A2DPSink
Jan 25 13:29:02 acer.greshko.com systemd-sleep[8179]: System resumed.
Jan 25 13:29:02 acer.greshko.com wpa_supplicant[920]: rfkill: WLAN unblocked
Jan 25 13:29:03 acer.greshko.com systemd[1]: Started Suspend.
Jan 25 13:29:02 acer.greshko.com wpa_supplicant[920]: rfkill: WLAN unblocked
Jan 25 13:29:03 acer.greshko.com systemd[1]: sleep.target: Unit not needed anymore.
Stopping.
Jan 25 13:29:02 acer.greshko.com dbus-daemon[722]: [system] Rejected send message, 1
matched rules; type="error", sender=":1.142" (uid=1029 pid=3111
comm="/usr/bin/pulseaudio --start --log-target=syslog "
label="unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023") interface="(unset)"
member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented"
requested_reply="0" destination=":1.2" (uid=0 pid=719
comm="/usr/libexec/bluetooth/bluetoothd " label="system_u:system_r:bluetooth_t:s0")
Jan 25 13:29:03 acer.greshko.com systemd[1]: Stopped target Sleep.
Jan 25 13:29:02 acer.greshko.com dbus-daemon[722]: [system] Rejected send message, 1
matched rules; type="error", sender=":1.142" (uid=1029 pid=3111
comm="/usr/bin/pulseaudio --start --log-target=syslog "
label="unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023") interface="(unset)"
member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented"
requested_reply="0" destination=":1.2" (uid=0 pid=719
comm="/usr/libexec/bluetooth/bluetoothd " label="system_u:system_r:bluetooth_t:s0")
Jan 25 13:29:03 acer.greshko.com systemd[1]: Reached target Bluetooth.
Jan 25 13:29:02 acer.greshko.com dbus-daemon[722]: [system] Rejected send message, 1
matched rules; type="error", sender=":1.142" (uid=1029 pid=3111
comm="/usr/bin/pulseaudio --start --log-target=syslog "
label="unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023") interface="(unset)"
member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented"
requested_reply="0" destination=":1.2" (uid=0 pid=719
comm="/usr/libexec/bluetooth/bluetoothd " label="system_u:system_r:bluetooth_t:s0")
Jan 25 13:29:03 acer.greshko.com systemd-rfkill[8190]: Failed to open device: No such
device
Jan 25 13:29:02 acer.greshko.com dbus-daemon[722]: [system] Rejected send message, 1
matched rules; type="error", sender=":1.142" (uid=1029 pid=3111
comm="/usr/bin/pulseaudio --start --log-target=syslog "
label="unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023") interface="(unset)"
member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented"
requested_reply="0" destination=":1.2" (uid=0 pid=719
comm="/usr/libexec/bluetooth/bluetoothd " label="system_u:system_r:bluetooth_t:s0")
Jan 25 13:29:03 acer.greshko.com systemd[1]: Started Load/Save RF Kill Switch Status.
Jan 25 13:29:03 acer.greshko.com bluetoothd[719]: LEAdvertisingManager skipped, LE
unavailable
Jan 25 13:29:03 acer.greshko.com bluetoothd[719]: Endpoint registered: sender=:1.142
path=/MediaEndpoint/A2DPSource
Jan 25 13:29:03 acer.greshko.com bluetoothd[719]: Endpoint registered: sender=:1.142
path=/MediaEndpoint/A2DPSink
Jan 25 13:29:03 acer.greshko.com kernel: tg3 0000:08:00.0 enp8s0: Link is up at 100
Mbps, full duplex
Jan 25 13:29:03 acer.greshko.com kernel: tg3 0000:08:00.0 enp8s0: Flow control is on
for TX and on for RX
Jan 25 13:29:03 acer.greshko.com NetworkManager[809]: <info>  [1516858143.9307]
device (enp8s0): link connected
Jan 25 13:29:08 acer.greshko.com audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295
ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-rfkill
comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

--
A motto of mine is: When in doubt, try it out


_______________________________________________
users mailing list -- users@lists.fedoraproject.org
To unsubscribe send an email to users-leave@lists.fedoraproject.org


Yeah I noticed the lack of entries myself... I have not looked but would messages nab anything? I wonder if teh suspend.service file itself could be setup to log elsewhere.. possibly a bit more verbosely?