On 2014-07-09 07:53, Cristian Rodríguez wrote:
El 08/07/14 21:38, Carlos E. R. escribió:
Prior to this event, at 20:18, I suspended the machine (to ram), without this "Dazed" thing. And at "19:17" I hibernated it, not "Dazed" either. And another uneventful one at 4 AM. If you want the full log...
It is "dazed and confused" by laptop-mode scripts that almost certainly do something cray with power saving features.
laptop-mode scripts are active all the time, with messages on every hibernation cycle, for several years, without any kernel message about being "Dazed". And they run after that point in time. Example, on the same day, prior to the other hibernation cycle posted before: (the arrows mark the place where the "dazed" thing usually happens, but this time did not happen: <3.4> 2014-05-25 19:17:37 minas-tirith pm-utils - - - Hibernating (95)... ... <0.6> 2014-05-25 20:04:47 minas-tirith kernel - - - [152607.772272] video LNXVIDEO:00: Restoring backlight state <======= <3.6> 2014-05-25 20:04:49 minas-tirith systemd 1 - - Time has been changed <======= <3.5> 2014-05-25 20:04:51 minas-tirith dbus 1342 - - [system] Activating service name='org.freedesktop.PackageKit' (using servicehelper) <3.4> 2014-05-25 20:04:51 minas-tirith pm-utils - - - Thawing (95)... ... <3.6> 2014-05-25 20:04:52 minas-tirith laptop-mode - - - Warning: Configuration file /etc/laptop-mode/conf.d/board-specific/* is not readable, skipping. <3.6> 2014-05-25 20:04:52 minas-tirith laptop-mode - - - Warning: Configuration file /etc/laptop-mode/conf.d/board-specific/* is not readable, skipping. <3.6> 2014-05-25 20:04:54 minas-tirith laptop-mode - - - Laptop mode <3.6> 2014-05-25 20:04:54 minas-tirith laptop-mode - - - enabled, active <3.4> 2014-05-25 20:04:56 minas-tirith pm-utils - - - Thawing the system now (04)... <3.6> 2014-05-25 20:04:57 minas-tirith laptop-mode - - - Warning: Configuration file /etc/laptop-mode/conf.d/board-specific/* is not readable, skipping. <3.6> 2014-05-25 20:04:57 minas-tirith laptop-mode - - - Laptop mode <3.6> 2014-05-25 20:04:57 minas-tirith laptop-mode - - - enabled, active <3.6> 2014-05-25 20:04:57 minas-tirith laptop-mode - - - Warning: Configuration file /etc/laptop-mode/conf.d/board-specific/* is not readable, skipping. <3.5> 2014-05-25 20:04:58 minas-tirith dbus 1342 - - [system] Successfully activated service 'org.freedesktop.PackageKit' <4.5> 2014-05-25 20:04:59 minas-tirith su - - - (to root) cer on (null) <10.6> 2014-05-25 20:05:00 minas-tirith unix2_chkpwd - - - gkr-pam: unlocked login keyring <0.6> 2014-05-25 20:05:01 minas-tirith kernel - - - [152621.890852] EXT4-fs (sda7): re-mounted. Opts: data=ordered,commit=600 <0.6> 2014-05-25 20:05:02 minas-tirith kernel - - - [152622.904691] EXT4-fs (sda6): re-mounted. Opts: (null) <0.6> 2014-05-25 20:05:02 minas-tirith kernel - - - [152623.006350] EXT4-fs (sda9): re-mounted. Opts: data=ordered,commit=600 <3.5> 2014-05-25 20:05:04 minas-tirith dbus 1342 - - [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' <0.7> 2014-05-25 20:05:05 minas-tirith kernel - - - [152625.375141] ehci-pci 0000:00:1a.7: setting latency timer to 64 <3.6> 2014-05-25 20:05:05 minas-tirith systemd 1 - - Starting Network Manager Script Dispatcher Service... On another event, there is an interrupt storm just before: <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.100011] Enabling non-boot CPUs ... <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.100011] smpboot: Booting Node 0 Processor 1 APIC 0x1 <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.111234] microcode: CPU1 sig=0x1067a, pf=0x80, revision=0xa0b <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.111417] CPU1 is up <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.112413] ACPI: Waking up from system sleep state S4 <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.137823] PM: noirq restore of devices complete after 11.612 msecs <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.138001] PM: early restore of devices complete after 0.150 msecs <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.249782] i915 0000:00:02.0: setting latency timer to 64 <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.251595] uhci_hcd 0000:00:1a.0: setting latency timer to 64 <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.251620] usb usb3: root hub lost power or was reset <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.251633] uhci_hcd 0000:00:1a.1: setting latency timer to 64 <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.251655] usb usb4: root hub lost power or was reset <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.251669] ehci-pci 0000:00:1a.7: setting latency timer to 64 <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.251719] usb usb1: root hub lost power or was reset <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255594] ehci-pci 0000:00:1a.7: cache line size of 64 is not supported <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255867] snd_hda_intel 0000:00:1b.0: irq 45 for MSI/MSI-X <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255947] uhci_hcd 0000:00:1d.0: setting latency timer to 64 <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255953] uhci_hcd 0000:00:1d.1: setting latency timer to 64 <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255975] usb usb5: root hub lost power or was reset <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255979] usb usb6: root hub lost power or was reset <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255988] uhci_hcd 0000:00:1d.2: setting latency timer to 64 <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.255991] uhci_hcd 0000:00:1d.3: setting latency timer to 64 <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.256013] usb usb7: root hub lost power or was reset <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.256015] usb usb8: root hub lost power or was reset <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.256042] ehci-pci 0000:00:1d.7: setting latency timer to 64 <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.256043] pci 0000:00:1e.0: setting latency timer to 64 <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.256053] usb usb2: root hub lost power or was reset <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.256058] ahci 0000:00:1f.2: setting latency timer to 64 <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.257127] ath: phy0: ASPM enabled: 0x43 <0.7> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.259930] ehci-pci 0000:00:1d.7: cache line size of 64 is not supported <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.265101] r8169 0000:03:00.0 eth0: link down <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.278211] i8042 kbd 00:07: System wakeup disabled by ACPI <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.322049] [drm] HPD interrupt storm detected on connector DP-2: switching from hotplug detection to polling <======= <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.566055] usb 2-4: reset high-speed USB device number 2 using ehci-pci <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.569057] ata5: SATA link down (SStatus 0 SControl 300) <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.571063] ata6: SATA link down (SStatus 0 SControl 300) <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.719062] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.722061] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.755939] ata2.00: configured for UDMA/100 <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.770564] ata1.00: configured for UDMA/133 <0.5> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.785058] sd 0:0:0:0: [sda] Starting disk <0.0> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.839698] Uhhuh. NMI received for unknown reason 3c on CPU 0. <0.0> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.839698] Do you have a strange power saving mode enabled? <0.0> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.839698] Dazed and confused, but trying to continue <======= <0.6> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.844516] PM: restore of devices complete after 594.802 msecs <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.860598] Restarting kernel threads ... done. <0.4> 2014-05-24 22:57:35 minas-tirith kernel - - - [132002.860888] Restarting tasks ... done. <0.7> 2014-05-24 22:57:36 minas-tirith kernel - - - [132002.886227] PM: Basic memory bitmaps freed <0.6> 2014-05-24 22:57:36 minas-tirith kernel - - - [132002.886239] video LNXVIDEO:00: Restoring backlight state <3.6> 2014-05-24 22:57:37 minas-tirith systemd 1 - - Time has been changed <0.6> 2014-05-24 22:57:38 minas-tirith kernel - - - [132005.667167] wlan0: authenticate with f8:1a:67:91:f4:22 <0.6> 2014-05-24 22:57:38 minas-tirith kernel - - - [132005.683913] wlan0: send auth to f8:1a:67:91:f4:22 (try 1/3) <0.6> 2014-05-24 22:57:38 minas-tirith kernel - - - [132005.688887] wlan0: authenticated <0.6> 2014-05-24 22:57:38 minas-tirith kernel - - - [132005.690049] wlan0: associate with f8:1a:67:91:f4:22 (try 1/3) <0.6> 2014-05-24 22:57:38 minas-tirith kernel - - - [132005.696130] wlan0: RX AssocResp from f8:1a:67:91:f4:22 (capab=0x431 status=0 aid=1) <0.6> 2014-05-24 22:57:38 minas-tirith kernel - - - [132005.696198] wlan0: associated <3.6> 2014-05-24 22:57:42 minas-tirith laptop-mode - - - Warning: Configuration file /etc/laptop-mode/conf.d/board-specific/* is not readable, skipping. And this, is the log of boots, halts, and hibernation cycles for that period: 2014-05-21 13:14:51+02:00 - Booting the system now ================================================================================ Linux minas-tirith 3.11.10-11-desktop #1 SMP PREEMPT Mon May 12 13:37:06 UTC 2014 (3d22b5f) x86_64 x86_64 x86_64 GNU/Linux 2014-05-21 17:40:52+02:00 - Hibernating the system now (04)... 2014-05-22 03:02:51+02:00 - Thawing the system now (04)... 2014-05-22 04:01:12+02:00 - Hibernating the system now (04)... 2014-05-22 12:40:49+02:00 - Thawing the system now (04)... 2014-05-22 14:39:01+02:00 - Hibernating the system now (04)... 2014-05-22 16:10:00+02:00 - Thawing the system now (04)... 2014-05-22 17:03:58+02:00 - Hibernating the system now (04)... 2014-05-22 21:20:31+02:00 - Thawing the system now (04)... 2014-05-23 03:41:00+02:00 - Hibernating the system now (04)... 2014-05-23 10:25:44+02:00 - Thawing the system now (04)... 2014-05-24 04:25:32+02:00 - Hibernating the system now (04)... 2014-05-24 12:58:32+02:00 - Thawing the system now (04)... 2014-05-24 16:05:55+02:00 - Hibernating the system now (04)... 2014-05-24 21:12:15+02:00 - Thawing the system now (04)... 2014-05-24 22:04:15+02:00 - Hibernating the system now (04)... 2014-05-24 22:57:48+02:00 - Thawing the system now (04)... Dazed event 2014-05-25 04:09:12+02:00 - Hibernating the system now (04)... 2014-05-25 18:46:11+02:00 - Thawing the system now (04)... 2014-05-25 19:17:37+02:00 - Hibernating the system now (04)... 2014-05-25 20:04:56+02:00 - Thawing the system now (04)... 2014-05-25 20:18:48+02:00 - Suspending the system now (04)... 2014-05-25 20:20:33+02:00 - Resuming the system now (04)... 2014-05-25 21:25:04+02:00 - Hibernating the system now (04)... 2014-05-26 22:48:41+02:00 - Thawing the system now (04)... Dazed event 2014-05-27 00:07:38+02:00 - Hibernating the system now (04)... 2014-05-27 01:47:30+02:00 - Thawing the system now (04)... 2014-05-27 02:00:34+02:00 - Hibernating the system now (04)... 2014-05-27 13:18:57+02:00 - Thawing the system now (04)... 2014-05-27 13:27:23+02:00 - Hibernating the system now (04)... 2014-05-30 13:15:12+02:00 - Thawing the system now (04)... 2014-05-30 13:16:31+02:00 - Halting the system now =========================================== uptime: 13:16pm up 9 days 0:02, 0 users, load average: 6.79, 2.08, 0.81 As you can see, many cycles, and only two "dazed" events, of a total of 11 since Feb 11. openSUSE 13.1 was installed 2014-01-07, and kernel 3.11.10-7-desktop was installed on 2014-02-07. On 2014-02-11 11:55:51 I get the first Dazed event. Coincidence? I think not. -- Cheers / Saludos, Carlos E. R. (from 13.1 x86_64 "Bottle" at Telcontar)