[opensuse-support] How can I find what when wrong: Dependency failed for Hibernate
Hi, after recovery from hibernate (which crashes the machine on the second or third hibernation), I get on log: <3.4> 2019-02-28 11:43:18 Telcontar systemd 1 - - Dependency failed for Hibernate. And: Telcontar:~ # systemctl status hibernate.target ● hibernate.target - Hibernate Loaded: loaded (/usr/lib/systemd/system/hibernate.target; static; vendor preset: disabled) Active: inactive (dead) Docs: man:systemd.special(7) Feb 28 11:43:18 Telcontar systemd[1]: hibernate.target: Bound to unit systemd-hibernate.service, but unit isn't active. Feb 28 11:43:18 Telcontar systemd[1]: Dependency failed for Hibernate. Feb 28 11:43:18 Telcontar systemd[1]: hibernate.target: Job hibernate.target/start failed with result 'dependency'. Telcontar:~ # What dependendency? Is there a command that will tell me exactly what dependency failed, and then why? Because as far as I can see, it succeeded. <4.5> 2019-02-28 03:44:41 Telcontar su - - - (to root) cer on pts/19 <10.6> 2019-02-28 03:44:41 Telcontar su - - - pam_unix(su-l:session): session opened for user root by (uid=1000) <10.6> 2019-02-28 03:45:04 Telcontar su - - - pam_unix(su-l:session): session closed for user root <10.3> 2019-02-28 03:52:50 Telcontar su - - - The gnome keyring socket is not owned with the same credentials as the user login: /run/user/1000/keyring/control <10.3> 2019-02-28 03:52:50 Telcontar su - - - gkr-pam: couldn't unlock the login keyring. <4.5> 2019-02-28 03:52:50 Telcontar su - - - (to root) cer on pts/33 <10.6> 2019-02-28 03:52:50 Telcontar su - - - pam_unix(su-l:session): session opened for user root by (uid=1000) <4.4> 2019-02-28 03:52:59 Telcontar systemd-logind 1664 - - /etc/systemd/sleep.conf:1: Assignment outside of section. Ignoring. <3.6> 2019-02-28 03:52:59 Telcontar systemd 1 - - Reached target Sleep. <3.6> 2019-02-28 03:52:59 Telcontar systemd 1 - - Starting Hibernate... <3.4> 2019-02-28 03:52:59 Telcontar systemd-sleep 23288 - - /etc/systemd/sleep.conf:1: Assignment outside of section. Ignoring. <0.7> 2019-02-28 03:52:59 Telcontar kernel - - - [55681.307503] PM: Hibernation mode set to 'platform' <3.6> 2019-02-28 03:52:59 Telcontar systemd-sleep 23288 - - INFO: running /usr/lib/systemd/system-sleep/grub2.sleep for hibernate <3.6> 2019-02-28 03:52:59 Telcontar systemd-sleep 23288 - - INFO: Running prepare-grub .. <3.6> 2019-02-28 03:52:59 Telcontar systemd-sleep 23288 - - 2019-02-28 03:52:59+01:00 - Hibernating the system now... <3.4> 2019-02-28 03:52:59 Telcontar systemd-sh - - - Hibernating the system now... <3.6> 2019-02-28 03:52:59 Telcontar systemd-sleep 23288 - - service: no such service upsd.service <3.6> 2019-02-28 03:53:00 Telcontar systemd-sleep 23288 - - running kernel is grub menu entry Main_openSUSE (vmlinuz-4.12.14-lp150.12.48-default) <3.6> 2019-02-28 03:53:00 Telcontar systemd-sleep 23288 - - preparing boot-loader: selecting entry Main_openSUSE, kernel /boot/4.12.14-lp150.12.48-default <3.6> 2019-02-28 03:53:02 Telcontar systemd-sleep 23288 - - running /usr/sbin/grub2-once "Main_openSUSE" <3.6> 2019-02-28 03:53:02 Telcontar systemd-sleep 23288 - - time needed for sync: 1.9 seconds, time needed for grub: 0.2 seconds. <3.6> 2019-02-28 03:53:02 Telcontar systemd-sleep 23288 - - INFO: Done. <3.6> 2019-02-28 03:53:04 Telcontar systemd-sleep 23288 - - Suspending system... <0.6> 2019-02-28 03:53:04 Telcontar kernel - - - [55686.369949] PM: Syncing filesystems ... <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.648814] PM: done. <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.648816] Freezing user space processes ... (elapsed 0.001 seconds) done. <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.650775] OOM killer disabled. <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.650888] PM: Marking nosave pages: [mem 0x00000000-0x00000fff] <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.650889] PM: Marking nosave pages: [mem 0x0009f000-0x000fffff] <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.650892] PM: Marking nosave pages: [mem 0xbff90000-0xffffffff] <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.651492] PM: Basic memory bitmaps created <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55686.651518] PM: Preallocating image memory... <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55697.732216] usb 6-2: USB disconnect, device number 4 <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.971713] done (allocated 786298 pages) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.971718] PM: Allocated 3145192 kbytes in 15.31 seconds (205.43 MB/s) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.971719] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.973662] Suspending console(s) (use no_console_suspend to debug) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.974639] serial 00:02: disabled <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.976724] pciehp 0000:00:1c.3:pcie004: Slot(0-2): Card present <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.976732] pciehp 0000:00:1c.2:pcie004: Slot(0-1): Card present <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55701.976778] pciehp 0000:00:1c.0:pcie004: Slot(0): Card present <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.180402] PM: freeze of devices complete after 206.728 msecs <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.180403] PM: secret key is invalid <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.180858] PM: late freeze of devices complete after 0.453 msecs <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.181840] PM: noirq freeze of devices complete after 0.980 msecs <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.182409] ACPI: Preparing to enter system sleep state S4 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.182778] PM: Saving platform NVS memory <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.183006] Disabling non-boot CPUs ... <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.184470] smpboot: CPU 1 is now offline <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.185141] Broke affinity for irq 19 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.186172] smpboot: CPU 2 is now offline <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.186962] Broke affinity for irq 19 <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.186972] Broke affinity for irq 26 <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.186974] Broke affinity for irq 27 <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.186977] Broke affinity for irq 30 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.187985] smpboot: CPU 3 is now offline <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.188206] PM: Creating hibernation image: <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.313962] PM: Need to copy 785103 pages <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.313965] PM: Normal pages needed: 785103 + 1024, available pages: 1311673 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.189515] PM: Restoring platform NVS memory <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.189853] Suspended for 28103.374 seconds <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.189901] Enabling non-boot CPUs ... <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.190004] x86: Booting SMP configuration: <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.190005] smpboot: Booting Node 0 Processor 1 APIC 0x1 <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.192750] cache: parent cpu1 should not be sleeping <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.193023] CPU1 is up <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.193116] smpboot: Booting Node 0 Processor 2 APIC 0x2 <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.195898] cache: parent cpu2 should not be sleeping <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.196209] CPU2 is up <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.196265] smpboot: Booting Node 0 Processor 3 APIC 0x3 <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.199034] cache: parent cpu3 should not be sleeping <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.199446] CPU3 is up <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.204614] ACPI: Waking up from system sleep state S4 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.226137] PM: noirq restore of devices complete after 21.290 msecs <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.226585] PM: early restore of devices complete after 0.423 msecs <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.329888] usb usb3: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.329893] usb usb4: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.329920] usb usb5: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.329950] usb usb1: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.329984] usb usb6: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.329987] usb usb7: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.330021] usb usb8: root hub lost power or was reset <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.330078] usb usb2: root hub lost power or was reset <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.333832] ehci-pci 0000:00:1a.7: cache line size of 32 is not supported <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.333953] ehci-pci 0000:00:1d.7: cache line size of 32 is not supported <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.334052] pciehp 0000:00:1c.0:pcie004: Timeout on hotplug command 0x1038 (issued 55698572 msec ago) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.334053] pciehp 0000:00:1c.5:pcie004: Timeout on hotplug command 0x1038 (issued 55698572 msec ago) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.334065] pciehp 0000:00:1c.4:pcie004: Timeout on hotplug command 0x1038 (issued 55698572 msec ago) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.334069] pciehp 0000:00:1c.2:pcie004: Timeout on hotplug command 0x1038 (issued 55698572 msec ago) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.334072] pciehp 0000:00:1c.3:pcie004: Timeout on hotplug command 0x1038 (issued 55698572 msec ago) <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.340451] sd 8:0:0:0: [sda] Starting disk <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.340477] sd 8:0:1:0: [sdb] Starting disk <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.340506] sd 9:0:0:0: [sdc] Starting disk <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.340529] sd 9:0:1:0: [sdd] Starting disk <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.340551] sd 10:0:0:0: [sde] Starting disk <0.3> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.450492] snd_hda_intel 0000:03:00.0: CORB reset timeout#1, CORBRP = 0 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.464673] rtc_cmos 00:01: System wakeup disabled by ACPI <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.466135] serial 00:02: activated <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.768226] ata6: SATA link down (SStatus 0 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.768536] ata5: SATA link down (SStatus 0 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.776259] ata7: SATA link down (SStatus 0 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.776297] ata8: SATA link down (SStatus 0 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.810100] ata11: SATA link up 3.0 Gbps (SStatus 123 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.810235] ata12: SATA link up 1.5 Gbps (SStatus 113 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812580] ata11.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812582] ata11.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812629] ata12.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812631] ata12.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812633] ata12.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812707] ata11.00: ACPI cmd c6/00:01:00:00:00:a0 (SET MULTIPLE MODE) succeeded <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.812709] ata11.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.815290] ata11.00: supports DRM functions and may not be fully accessible <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.817453] ata12.00: configured for UDMA/133 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.818084] usb 2-5: reset high-speed USB device number 4 using ehci-pci <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.818085] usb 1-2: reset high-speed USB device number 3 using ehci-pci <0.4> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.820008] ata11.00: supports DRM functions and may not be fully accessible <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.821874] ata11.00: configured for UDMA/133 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.847909] r8169 0000:07:00.0 eth1: link down <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.848193] r8169 0000:06:00.0 eth0: link down <0.5> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.906094] firewire_core 0000:08:02.0: rediscovered device fw0 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.954083] usb 3-1: reset low-speed USB device number 2 using uhci_hcd <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.958082] usb 8-2: reset low-speed USB device number 2 using uhci_hcd <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55702.958089] usb 7-2: reset low-speed USB device number 2 using uhci_hcd <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.126123] ata9.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.126134] ata9.01: SATA link up 3.0 Gbps (SStatus 123 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.128415] ata9.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.128418] ata9.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.128652] ata9.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.128655] ata9.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.130129] ata10.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.130141] ata10.01: SATA link up 3.0 Gbps (SStatus 123 SControl 300) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.132428] ata10.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.132430] ata10.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.132632] ata10.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.132635] ata10.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.133408] ata9.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.133411] ata9.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.133627] ata9.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.133629] ata9.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.137381] ata10.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.137383] ata10.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.137598] ata10.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.137600] ata10.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.144828] ata10.00: configured for UDMA/133 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.158416] ata9.00: configured for UDMA/133 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.163090] ata9.01: configured for UDMA/133 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.168585] ata10.01: configured for UDMA/133 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55703.270084] usb 2-5.4: reset high-speed USB device number 6 using ehci-pci <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55704.716929] r8169 0000:06:00.0 eth0: link up <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266203] <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266204] floppy driver state <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266204] ------------------- <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266211] now=4308818304 last interrupt=4294893801 diff=13924503 last called handler=reset_interrupt [floppy] <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266212] timeout_message=lock fdc <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266213] last output bytes: <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266214] 8 90 4294893785 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266215] 8 90 4294893785 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266216] 8 80 4294893785 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266216] 8 80 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266217] 8 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266218] 8 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266219] 8 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266220] e 80 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266221] 13 80 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266221] 0 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266222] 1a 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266223] 0 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266224] 12 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266225] 0 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266226] 14 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266227] 18 90 4294893797 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266227] 8 80 4294893801 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266228] 8 80 4294893801 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266229] 8 80 429489380 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266230] 8 80 4294893801 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266231] last result at 4294893801 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266231] last redo_fd_request at 4294893801 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266236] status=0 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266237] fdc_busy=1 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266240] do_floppy=reset_interrupt [floppy] <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266241] cont=ffffffffa0141420 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266242] current_req= (null) <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266243] command_status=-1 <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266243] <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.266246] floppy0: floppy timeout called <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.422500] PM: restore of devices complete after 4092.536 msecs <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.422855] PM: Image restored successfully. <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.422857] PM: Trampoline freed <0.7> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.422880] PM: Basic memory bitmaps freed <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.422881] OOM killer enabled. <0.6> 2019-02-28 11:41:48 Telcontar kernel - - - [55706.422881] Restarting tasks ... done. <3.6> 2019-02-28 11:41:49 Telcontar systemd-sleep 23288 - - System resumed. <3.4> 2019-02-28 11:41:49 Telcontar rtkit-daemon 9872 - - The canary thread is apparently starving. Taking action. <3.6> 2019-02-28 11:41:49 Telcontar rtkit-daemon 9872 - - Demoting known real-time threads. <3.5> 2019-02-28 11:41:49 Telcontar rtkit-daemon 9872 - - Successfully demoted thread 9876 of process 9870 (/usr/bin/pulseaudio). <3.5> 2019-02-28 11:41:49 Telcontar rtkit-daemon 9872 - - Successfully demoted thread 9875 of process 9870 (/usr/bin/pulseaudio). <3.5> 2019-02-28 11:41:49 Telcontar rtkit-daemon 9872 - - Successfully demoted thread 9870 of process 9870 (/usr/bin/pulseaudio). <3.5> 2019-02-28 11:41:49 Telcontar rtkit-daemon 9872 - - Demoted 3 threads. <3.6> 2019-02-28 11:41:49 Telcontar systemd-sleep 23288 - - INFO: running /usr/lib/systemd/system-sleep/grub2.sleep for hibernate <3.6> 2019-02-28 11:41:49 Telcontar systemd-sleep 23288 - - INFO: Running grub-once-restore .. <3.6> 2019-02-28 11:41:49 Telcontar systemd-sleep 23288 - - 2019-02-28 11:41:49+01:00 - Thawing the system now)... <3.4> 2019-02-28 11:41:49 Telcontar systemd-sh - - - Thawing the system now... <3.6> 2019-02-28 11:41:49 Telcontar systemd-sleep 23288 - - INFO: Done. <3.6> 2019-02-28 11:41:50 Telcontar systemd 1 - - Started Timeline of Snapper Snapshots. <3.6> 2019-02-28 11:41:50 Telcontar systemd-sleep 23288 - - service: no such service upsd.service <3.4> 2019-02-28 11:41:50 Telcontar systemd-sleep 23718 - - /usr/lib/systemd/system-sleep/nut.sh failed with error code 1. <3.6> 2019-02-28 11:41:50 Telcontar dbus-daemon 1543 - - [system] Activating service name='org.opensuse.Snapper' requested by ':1.772' (uid=0 pid=23755 comm="/usr/lib/snapper/systemd-helper --timeline ") (using servicehelper) <3.6> 2019-02-28 11:41:50 Telcontar dbus-daemon 1543 - - [system] Successfully activated service 'org.opensuse.Snapper' <3.6> 2019-02-28 11:41:50 Telcontar systemd-sleep 23288 - - tput: No value for $TERM and no -T specified <3.4> 2019-02-28 11:41:51 Telcontar wickedd 1708 - - route ipv4 0.0.0.0/0 via 192.168.1.1 dev eth0#2 type unicast table main scope universe protocol boot covered by a ipv4:static lease <1.5> 2019-02-28 11:41:51 Telcontar dns-resolver - - - ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched... <1.5> 2019-02-28 11:41:51 Telcontar dns-resolver - - - You can find my version in /etc/resolv.conf.netconfig <3.3> 2019-02-28 11:41:51 Telcontar wickedd 1708 - - ni_process_reap: process 23765 has not exited yet; now doing a blocking waitpid() <1.7> 2019-02-28 11:41:51 Telcontar konversation 9946 - - Connection broken with state 5 and error: "The remote host closed the connection" <1.5> 2019-02-28 11:41:51 Telcontar dns-resolver - - - ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched... <1.5> 2019-02-28 11:41:51 Telcontar dns-resolver - - - You can find my version in /etc/resolv.conf.netconfig <3.3> 2019-02-28 11:41:51 Telcontar wickedd 1708 - - ni_process_reap: process 23891 has not exited yet; now doing a blocking waitpid() <3.6> 2019-02-28 11:42:18 Telcontar systemd 1 - - Started Leafnode NNTP server (127.0.0.1:40882). <3.6> 2019-02-28 11:42:18 Telcontar systemd 1 - - Started Leafnode NNTP server (127.0.0.1:40880). <3.4> 2019-02-28 11:43:18 Telcontar systemd-sleep 23288 - - system-sleep terminated by signal ALRM. <3.3> 2019-02-28 11:43:18 Telcontar systemd-sleep 23288 - - Execution failed: Protocol error <3.6> 2019-02-28 11:43:18 Telcontar systemd 1 - - Started Hibernate. <3.6> 2019-02-28 11:43:18 Telcontar systemd 1 - - sleep.target: Unit not needed anymore. Stopping. <3.6> 2019-02-28 11:43:18 Telcontar systemd 1 - - Stopped target Sleep. <3.5> 2019-02-28 11:43:18 Telcontar systemd 1 - - hibernate.target: Bound to unit systemd-hibernate.service, but unit isn't active. <3.4> 2019-02-28 11:43:18 Telcontar systemd 1 - - Dependency failed for Hibernate. <4.6> 2019-02-28 11:43:18 Telcontar systemd-logind 1664 - - Operation 'sleep' finished. <3.5> 2019-02-28 11:43:18 Telcontar systemd 1 - - hibernate.target: Job hibernate.target/start failed with result 'dependency'. Maybe this? <3.6> 2019-02-28 03:52:59 Telcontar systemd-sleep 23288 - - service: no such service upsd.service or this? <3.4> 2019-02-28 11:41:50 Telcontar systemd-sleep 23718 - - /usr/lib/systemd/system-sleep/nut.sh failed with error code 1. I also notice this: <4.4> 2019-02-28 03:52:59 Telcontar systemd-logind 1664 - - /etc/systemd/sleep.conf:1: Assignment outside of section. Ignoring. cer@Telcontar:~> cat /etc/systemd/sleep.conf HibernateMode=shutdown cer@Telcontar:~> Changing to: [sleep] HibernateMode=shutdown and will see. -- Cheers / Saludos, Carlos E. R. (from 15.0 x86_64 at Telcontar)
On Thu, Feb 28, 2019 at 2:33 PM Carlos E. R. <robin.listas@telefonica.net> wrote:
Hi,
after recovery from hibernate (which crashes the machine on the second or third hibernation), I get on log:
<3.4> 2019-02-28 11:43:18 Telcontar systemd 1 - - Dependency failed for Hibernate.
And:
Telcontar:~ # systemctl status hibernate.target ● hibernate.target - Hibernate Loaded: loaded (/usr/lib/systemd/system/hibernate.target; static; vendor preset: disabled) Active: inactive (dead) Docs: man:systemd.special(7)
Feb 28 11:43:18 Telcontar systemd[1]: hibernate.target: Bound to unit systemd-hibernate.service, but unit isn't active. Feb 28 11:43:18 Telcontar systemd[1]: Dependency failed for Hibernate. Feb 28 11:43:18 Telcontar systemd[1]: hibernate.target: Job hibernate.target/start failed with result 'dependency'. Telcontar:~ #
It is not really an error, just the way to stop hibertnate.target after resume. This has been changed upstream to use different method for quite some time. -- To unsubscribe, e-mail: opensuse-support+unsubscribe@opensuse.org To contact the owner, e-mail: opensuse-support+owner@opensuse.org
On 28/02/2019 13.40, Andrei Borzenkov wrote:
On Thu, Feb 28, 2019 at 2:33 PM Carlos E. R. <robin.listas@telefonica.net> wrote:
Hi,
after recovery from hibernate (which crashes the machine on the second or third hibernation), I get on log:
<3.4> 2019-02-28 11:43:18 Telcontar systemd 1 - - Dependency failed for Hibernate.
And:
Telcontar:~ # systemctl status hibernate.target ● hibernate.target - Hibernate Loaded: loaded (/usr/lib/systemd/system/hibernate.target; static; vendor preset: disabled) Active: inactive (dead) Docs: man:systemd.special(7)
Feb 28 11:43:18 Telcontar systemd[1]: hibernate.target: Bound to unit systemd-hibernate.service, but unit isn't active. Feb 28 11:43:18 Telcontar systemd[1]: Dependency failed for Hibernate. Feb 28 11:43:18 Telcontar systemd[1]: hibernate.target: Job hibernate.target/start failed with result 'dependency'. Telcontar:~ #
It is not really an error, just the way to stop hibertnate.target after resume. This has been changed upstream to use different method for quite some time.
Oh! :-o It is confusing. -- Cheers / Saludos, Carlos E. R. (from 15.0 x86_64 at Telcontar)
participants (2)
-
Andrei Borzenkov
-
Carlos E. R.