can't figure this one out...high idle temps after resume

cris9288 · 2013-05-11 04:35:42

As the title states, I have had no luck in figuring out what is causing this bug. Sometimes, when I wake from suspend my cpu temp (displayed by conky) will begin to slowly climb, finally idling at about 55 C. This is right after I resume and before I have a chance to do anything, but once I start doing things my temp skyrockets up to 75+ and my fans go crazy. The only solution is to reboot or suspend and resume again. I haven't been able to discern a specific pattern yet, but I think it might be triggered after a certain amount of suspend/resumes. Another guess is that if my machine has been in sleep mode for a while then the bug is triggered. This is merely speculation though - as far as can tell, its just random.

This happened exactly on the change from kernel 3.7 to 3.8. I've tried everything I can think of, which is not much because there is nothing in dmesg or journalctl that indicates something could be going wrong. Htop, top, and powertop don't show anything out of the ordinary and cpu usage is completely normal. I honestly have no idea where I can look. I tried acpi_listen, udevadm monitor, following serveral logs in /var/log during temperature increases, but i've come up completely empty. Does anybody have any ideas of other places I can look?

FYI, my laptop is an HP dv4 with an i3-2330M, 4G ram

Dheart · 2013-05-11 11:09:51

Do you have anything eating up your CPU after resume? Run htop to check?

Could it be that your CPU was switched to the performance governor? Check with cpupower frequency-info .
If that is the case just manually set the governor to ondemand.

teateawhy · 2013-05-11 13:06:57

Sometimes the gpu is hung after resuming from suspend. This happens with sandy brigde gpu, such as your i3-2330M has integrated.
Search for this output in the journal. (Just searching ERROR should do the trick.)
[drm:i915_hangcheck_hung] *ERROR* Hangcheck timer elapsed... GPU hung
Taken from this thread:
https://bbs.archlinux.org/viewtopic.php?id=150743

cris9288 · 2013-05-11 16:39:59

hey guys thanks for the replies. Like I said, htop, top, etc show nothing eating up my cpu times. I don't think it's the cpu governor. I have acpid run powerdown/powerup and it detects it properly on boot/resume. Next time it happens i'll double check though.

@teateawhy, I didn't find those errors in my dmesg output. I dumped the contents of dmesg during sessions with and without the bug present. I combed them both over and came up empty. Maybe somebody can spot something.

here's the dmesg output with the bug present after a resume:

May 10 19:57:36 christian kernel: PM: Preparing system for mem sleep
May 10 19:57:36 christian kernel: PM: Syncing filesystems ... done.
May 10 19:57:34 christian logger[2619]: LID closed
May 10 19:57:34 christian systemd-sleep[2618]: Suspending system...
May 10 19:57:34 christian systemd[1]: Starting Suspend...
May 10 19:57:34 christian systemd[1]: Reached target Sleep.
May 10 19:57:34 christian systemd[1]: Starting Sleep.
May 10 19:57:34 christian systemd-logind[242]: Suspending...
May 10 19:57:34 christian systemd-logind[242]: Lid closed.
May 10 19:55:38 christian sudo[2595]: pam_unix(sudo:session): session closed for user root
May 10 19:55:09 christian sudo[2595]: pam_unix(sudo:session): session opened for user root by (u
May 10 19:55:09 christian sudo[2595]: christian : TTY=pts/1 ; PWD=/home/christian ; USER=root ; 
May 10 19:55:07 christian sudo[2584]: pam_unix(sudo:session): session closed for user root
May 10 19:54:56 christian sudo[2297]: pam_unix(sudo:session): session closed for user root
May 10 19:54:51 christian sudo[2584]: pam_unix(sudo:session): session opened for user root by (u
May 10 19:54:51 christian sudo[2584]: christian : TTY=pts/1 ; PWD=/home/christian ; USER=root ; 
May 10 19:51:56 christian dhcpcd[515]: eth1: leased 192.168.0.3 for 3600 seconds
May 10 19:51:56 christian dhcpcd[515]: eth1: acknowledged 192.168.0.3 from 192.168.0.1
May 10 19:51:55 christian dhcpcd[515]: eth1: renewing lease of 192.168.0.3
May 10 19:47:14 christian acpid[238]: client 2277[1000:100] has disconnected
May 10 19:47:11 christian sudo[2297]: pam_unix(sudo:session): session opened for user root by (u
May 10 19:47:11 christian sudo[2297]: christian : TTY=pts/2 ; PWD=/home/christian ; USER=root ; 
May 10 19:45:16 christian acpid[238]: 1 client rule loaded
May 10 19:45:16 christian acpid[238]: client connected from 2277[1000:100]
May 10 19:42:42 christian wpa_actiond[301]: Interface 'eth1' reestablished connection to network
May 10 19:42:41 christian systemd[1]: Started power_supply@off.service.
May 10 19:42:41 christian kernel: NET: Unregistered protocol family 31
May 10 19:42:41 christian kernel: EXT4-fs (sda3): re-mounted. Opts: (null)
May 10 19:42:41 christian kernel: EXT4-fs (sda2): re-mounted. Opts: stripe=4
May 10 19:42:41 christian kernel: EXT4-fs (sda1): re-mounted. Opts: (null)
May 10 19:42:40 christian logger[1669]: ACPI group/action undefined: thermal_zone / LNXTHERM:00
May 10 19:42:40 christian logger[1661]: ACPI action undefined: ACPI0003:00
May 10 19:42:40 christian systemd-logind[242]: Operation finished.
May 10 19:42:40 christian systemd[1]: Starting power_supply@off.service...
May 10 19:42:40 christian systemd[1]: Reached target Suspend.
May 10 19:42:40 christian systemd[1]: Starting Suspend.
May 10 19:42:40 christian systemd[1]: Stopped target Sleep.
May 10 19:42:40 christian systemd[1]: Stopping Sleep.
May 10 19:42:40 christian systemd[1]: Service sleep.target is not needed anymore. Stopping.
May 10 19:42:40 christian systemd[1]: Started Suspend.
May 10 19:42:40 christian logger[1658]: LID opened
May 10 19:42:40 christian kernel: video LNXVIDEO:00: Restoring backlight state
May 10 19:42:40 christian kernel: Restarting tasks ... done.
May 10 19:42:40 christian kernel: PM: Finishing wakeup.
May 10 19:42:40 christian kernel: PM: resume of devices complete after 3080.564 msecs
May 10 19:42:40 christian kernel: sd 0:0:0:0: [sda] Starting disk
May 10 19:42:40 christian kernel: ata1.00: configured for UDMA/133
May 10 19:42:40 christian kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
May 10 19:42:40 christian kernel: [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
May 10 19:42:40 christian kernel: ata3.00: configured for UDMA/100
May 10 19:42:40 christian kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 10 19:42:40 christian kernel: usb 1-1.1: reset full-speed USB device number 3 using ehci-pci
May 10 19:42:40 christian kernel: usb 2-1.6: reset high-speed USB device number 3 using ehci-pci
May 10 19:42:40 christian kernel: Extended CMOS year: 2000
May 10 19:42:40 christian kernel: ahci 0000:00:1f.2: setting latency timer to 64
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1d.0: setting latency timer to 64
May 10 19:42:40 christian kernel: snd_hda_intel 0000:00:1b.0: irq 42 for MSI/MSI-X
May 10 19:42:40 christian kernel: mei 0000:00:16.0: irq 41 for MSI/MSI-X
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1a.0: setting latency timer to 64
May 10 19:42:40 christian kernel: i915 0000:00:02.0: setting latency timer to 64
May 10 19:42:40 christian kernel: PM: early resume of devices complete after 0.313 msecs
May 10 19:42:40 christian kernel: PM: noirq resume of devices complete after 120.394 msecs
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
May 10 19:42:40 christian kernel: ACPI: Waking up from system sleep state S3
May 10 19:42:40 christian kernel: CPU3 is up
May 10 19:42:40 christian kernel: CPU3: Thermal monitoring handled by SMI
May 10 19:42:40 christian kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
May 10 19:42:40 christian kernel: CPU2 is up
May 10 19:42:40 christian kernel: CPU2: Thermal monitoring handled by SMI
May 10 19:42:40 christian kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
May 10 19:42:40 christian kernel: CPU1 is up
May 10 19:42:40 christian kernel: CPU1: Thermal monitoring handled by SMI
May 10 19:42:40 christian kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
May 10 19:42:40 christian kernel: Enabling non-boot CPUs ...
May 10 19:42:40 christian kernel: CPU0: Thermal monitoring handled by SMI
May 10 19:42:40 christian kernel: Extended CMOS year: 2000
May 10 19:42:40 christian kernel: PM: Restoring platform NVS memory
May 10 19:42:40 christian kernel: ACPI: Low-level resume complete
May 10 19:42:40 christian kernel: Extended CMOS year: 2000
May 10 19:42:40 christian kernel: smpboot: CPU 3 is now offline
May 10 19:42:40 christian kernel: smpboot: CPU 2 is now offline
May 10 19:42:40 christian kernel: smpboot: CPU 1 is now offline
May 10 19:42:40 christian kernel: Disabling non-boot CPUs ...
May 10 19:42:40 christian kernel: PM: Saving platform NVS memory
May 10 19:42:40 christian kernel: ACPI: Preparing to enter system sleep state S3
May 10 19:42:40 christian kernel: PM: noirq suspend of devices complete after 52.839 msecs
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
May 10 19:42:40 christian kernel: PM: late suspend of devices complete after 0.465 msecs
May 10 19:42:40 christian kernel: PM: suspend of devices complete after 632.736 msecs
May 10 19:42:40 christian kernel: cfg80211: Calling CRDA to update world regulatory domain
May 10 19:42:40 christian kernel: sd 0:0:0:0: [sda] Stopping disk
May 10 19:42:40 christian kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1d.0: setting latency timer to 64
May 10 19:42:40 christian kernel: ehci-pci 0000:00:1a.0: setting latency timer to 64
May 10 19:42:40 christian kernel: Suspending console(s) (use no_console_suspend to debug)
May 10 19:42:40 christian systemd-sleep[1583]: System resumed.
May 10 19:42:40 christian kernel: PM: Entering mem sleep
May 10 19:42:40 christian wpa_actiond[301]: Interface 'eth1' lost connection to network 'No Park
May 10 19:42:40 christian kernel: Freezing remaining freezable tasks ... (elapsed 0.01 seconds) 
May 10 19:42:40 christian systemd-logind[242]: Lid opened.
May 10 19:42:40 christian systemd[1]: Time has been changed
May 10 19:42:40 christian kernel: Freezing user space processes ... (elapsed 0.01 seconds) done

and here it is without

May 10 21:04:22 christian sudo[3294]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 10 21:04:22 christian sudo[3294]: christian : TTY=pts/0 ; PWD=/home/christian ; USER=root ; COMMAND=/usr/bin/journalctl -r
May 10 21:04:12 christian sudo[3294]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty=/dev/pts/0 ruser=christian rhost=  user=christian
May 10 21:03:39 christian wpa_actiond[301]: Interface 'eth1' reestablished connection to network 'No Parking'
May 10 21:03:38 christian systemd[1]: Started power_supply@off.service.
May 10 21:03:38 christian kernel: EXT4-fs (sda3): re-mounted. Opts: (null)
May 10 21:03:38 christian kernel: EXT4-fs (sda2): re-mounted. Opts: stripe=4
May 10 21:03:38 christian kernel: EXT4-fs (sda1): re-mounted. Opts: (null)
May 10 21:03:37 christian logger[2706]: ACPI group/action undefined: thermal_zone / LNXTHERM:00
May 10 21:03:37 christian logger[2698]: ACPI action undefined: ACPI0003:00
May 10 21:03:37 christian systemd[1]: Starting power_supply@off.service...
May 10 21:03:37 christian systemd-logind[242]: Operation finished.
May 10 21:03:37 christian systemd[1]: Reached target Suspend.
May 10 21:03:37 christian systemd[1]: Stopped target Sleep.
May 10 21:03:37 christian systemd[1]: Stopping Sleep.
May 10 21:03:37 christian systemd[1]: Service sleep.target is not needed anymore. Stopping.
May 10 21:03:37 christian systemd[1]: Requested transaction contradicts existing jobs: File exists
May 10 21:03:37 christian systemd[1]: Started Suspend.
May 10 21:03:37 christian logger[2695]: LID opened
May 10 21:03:37 christian kernel: video LNXVIDEO:00: Restoring backlight state
May 10 21:03:37 christian systemd-sleep[2618]: System resumed.
May 10 21:03:37 christian kernel: Restarting tasks ... done.
May 10 21:03:37 christian kernel: PM: Finishing wakeup.
May 10 21:03:37 christian kernel: PM: resume of devices complete after 2981.597 msecs
May 10 21:03:37 christian kernel: sd 0:0:0:0: [sda] Starting disk
May 10 21:03:37 christian kernel: ata1.00: configured for UDMA/133
May 10 21:03:37 christian kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
May 10 21:03:37 christian kernel: [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
May 10 21:03:37 christian kernel: usb 2-1.6: reset high-speed USB device number 3 using ehci-pci
May 10 21:03:37 christian kernel: ata3.00: configured for UDMA/100
May 10 21:03:37 christian kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
May 10 21:03:37 christian kernel: Extended CMOS year: 2000
May 10 21:03:37 christian kernel: usb 1-1.1: reset full-speed USB device number 3 using ehci-pci
May 10 21:03:37 christian kernel: mei 0000:00:16.0: irq 42 for MSI/MSI-X
May 10 21:03:37 christian kernel: snd_hda_intel 0000:00:1b.0: irq 41 for MSI/MSI-X
May 10 21:03:37 christian kernel: ahci 0000:00:1f.2: setting latency timer to 64
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1d.0: setting latency timer to 64
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1a.0: setting latency timer to 64
May 10 21:03:37 christian kernel: i915 0000:00:02.0: setting latency timer to 64
May 10 21:03:37 christian kernel: PM: early resume of devices complete after 0.311 msecs
May 10 21:03:37 christian kernel: PM: noirq resume of devices complete after 120.410 msecs
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1a.0: System wakeup disabled by ACPI
May 10 21:03:37 christian kernel: ACPI: Waking up from system sleep state S3
May 10 21:03:37 christian kernel: CPU3 is up
May 10 21:03:37 christian kernel: CPU3: Thermal monitoring handled by SMI
May 10 21:03:37 christian kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
May 10 21:03:37 christian kernel: CPU2 is up
May 10 21:03:37 christian kernel: CPU2: Thermal monitoring handled by SMI
May 10 21:03:37 christian kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
May 10 21:03:37 christian kernel: CPU1 is up
May 10 21:03:37 christian kernel: CPU1: Thermal monitoring handled by SMI
May 10 21:03:37 christian kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
May 10 21:03:37 christian kernel: Enabling non-boot CPUs ...
May 10 21:03:37 christian kernel: CPU0: Thermal monitoring handled by SMI
May 10 21:03:37 christian kernel: Extended CMOS year: 2000
May 10 21:03:37 christian kernel: PM: Restoring platform NVS memory
May 10 21:03:37 christian kernel: ACPI: Low-level resume complete
May 10 21:03:37 christian kernel: Extended CMOS year: 2000
May 10 21:03:37 christian kernel: smpboot: CPU 3 is now offline
May 10 21:03:37 christian kernel: smpboot: CPU 2 is now offline
May 10 21:03:37 christian kernel: smpboot: CPU 1 is now offline
May 10 21:03:37 christian kernel: Disabling non-boot CPUs ...
May 10 21:03:37 christian kernel: PM: Saving platform NVS memory
May 10 21:03:37 christian kernel: ACPI: Preparing to enter system sleep state S3
May 10 21:03:37 christian kernel: PM: noirq suspend of devices complete after 52.932 msecs
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
May 10 21:03:37 christian kernel: smpboot: CPU 2 is now offline
May 10 21:03:37 christian kernel: smpboot: CPU 1 is now offline
May 10 21:03:37 christian kernel: Disabling non-boot CPUs ...
May 10 21:03:37 christian kernel: PM: Saving platform NVS memory
May 10 21:03:37 christian kernel: ACPI: Preparing to enter system sleep state S3
May 10 21:03:37 christian kernel: PM: noirq suspend of devices complete after 52.932 msecs
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
May 10 21:03:37 christian kernel: PM: late suspend of devices complete after 0.406 msecs
May 10 21:03:37 christian kernel: smpboot: CPU 2 is now offline
May 10 21:03:37 christian kernel: smpboot: CPU 1 is now offline
May 10 21:03:37 christian kernel: Disabling non-boot CPUs ...
May 10 21:03:37 christian kernel: PM: Saving platform NVS memory
May 10 21:03:37 christian kernel: ACPI: Preparing to enter system sleep state S3
May 10 21:03:37 christian kernel: PM: noirq suspend of devices complete after 52.932 msecs
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1a.0: System wakeup enabled by ACPI
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
May 10 21:03:37 christian kernel: PM: late suspend of devices complete after 0.406 msecs
May 10 21:03:37 christian kernel: PM: suspend of devices complete after 633.153 msecs
May 10 21:03:37 christian kernel: cfg80211: Calling CRDA to update world regulatory domain
May 10 21:03:37 christian kernel: sd 0:0:0:0: [sda] Stopping disk
May 10 21:03:37 christian kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 10 21:03:37 christian wpa_actiond[301]: Interface 'eth1' lost connection to network 'No Park
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1d.0: setting latency timer to 64
May 10 21:03:37 christian kernel: ehci-pci 0000:00:1a.0: setting latency timer to 64
May 10 21:03:37 christian kernel: Suspending console(s) (use no_console_suspend to debug)
May 10 21:03:37 christian kernel: PM: Entering mem sleep
May 10 21:03:37 christian kernel: Freezing remaining freezable tasks ... (elapsed 0.01 seconds) 
May 10 21:03:37 christian systemd-logind[242]: Lid opened.
May 10 21:03:37 christian systemd[1]: Time has been changed
May 10 21:03:37 christian kernel: Freezing user space processes ... (elapsed 0.01 seconds) done

Arch Linux

#1 2013-05-11 04:35:42

can't figure this one out...high idle temps after resume

#2 2013-05-11 11:09:51

Re: can't figure this one out...high idle temps after resume

#3 2013-05-11 13:06:57

Re: can't figure this one out...high idle temps after resume

#4 2013-05-11 16:39:59

Re: can't figure this one out...high idle temps after resume

Board footer