You are not logged in.

#76 2012-08-03 16:09:31

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Another piece of news and info. I just noticed that if I connect a 3GS iPhone to my computer, sometimes upowerd segfaults:

Aug  3 18:23:54 localhost kernel: [  280.471777] upowerd[3078]: segfault at 0 ip 00007f0fa4cbe5b0 sp 00007fff80ddf538 error 4 in libc-2.16.so[7f0fa4bc8000+19b000]

(This is even worse if I boot with the phone connected).

But that's not important. What is important is that when upowerd is not running, the computer will not suspend by closing the lid but will still suspend with the pm-suspend command. I really think we should be looking into upower, too!

I will downgrade upower and report back!

Last edited by yourself (2012-08-03 16:14:43)

Offline

#77 2012-08-04 21:57:03

Essl
Member
Registered: 2012-07-15
Posts: 27

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

hmmm, I do have an ipod touch that I occasionally (but very rarely) charge on the laptop. This would explain why this happens to me so much less than to the other people in this thread!

Offline

#78 2012-08-04 22:36:38

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Unfortunately, I downgraded upower to 0.9.10 and the same thing happened again after 25 hours of uptime :-(

I am really out of ideas. The only thing I can think of next is to install a *new* system from scratch on the same laptop and see what happens. If it happens on the new install, bummer. If it doesn't, start converting the new system to the configuration of this one. But this will take time, something I don't have. Anyone willing to try this kind of thing on his/her machine?

Offline

#79 2012-08-06 13:24:21

Essl
Member
Registered: 2012-07-15
Posts: 27

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

I think what I'm going to try is just buying a plug charger for my ipod, and see if that fixes it. I tried plugging it in again to see if it would trigger the thing, and it didn't, but it did happen again the day after that. So to me it seems like it is indeed the idevices at fault. Maybe installing libimobiledevices could help as well.

Offline

#80 2012-08-06 14:02:35

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Essl wrote:

I think what I'm going to try is just buying a plug charger for my ipod, and see if that fixes it. I tried plugging it in again to see if it would trigger the thing, and it didn't, but it did happen again the day after that. So to me it seems like it is indeed the idevices at fault. Maybe installing libimobiledevices could help as well.

No, no. At most, an idevice will crash upowerd and then your laptop won't suspend by closing the lid....

Offline

#81 2012-08-06 18:49:05

Essl
Member
Registered: 2012-07-15
Posts: 27

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

yeah, but I figured that may have been the reason. Well looks like I jinxed it anyway, it happened again to me today.

Offline

#82 2012-08-07 11:10:56

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

And it happened again while on Gnome 3.4. The desktop environment does not have anything to do with it. Something is happening on closing the lid. This is what we should track.

Last edited by yourself (2012-08-07 11:11:28)

Offline

#83 2012-08-07 12:22:31

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Ok, I've tried something else. Let's see if this helps.

I've disabled suspending on lid-close from XFCE power manager (and tested it, nothing happened). Then installed acpid and edited /etc/acpi/handler.sh to execute pm-suspend in lid close event. Also, I've set SUSPEND_MODULES to

SUSPEND_MODULES="iwlwifi uhci_hcd ehci_hcd button sdhci sdhci_pci mmc_block mmc_core"

Fingers crossed.

Offline

#84 2012-08-07 15:02:59

Declan
Member
Registered: 2009-02-06
Posts: 91

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Hi, I'm not sure if we have exactly the same problem, but it could be. I'm also using a Lenovo (Z575) and am having trouble waking up from suspend:

https://bbs.archlinux.org/viewtopic.php?id=146282

Offline

#85 2012-08-08 09:34:06

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Declan wrote:

Hi, I'm not sure if we have exactly the same problem, but it could be. I'm also using a Lenovo (Z575) and am having trouble waking up from suspend:

https://bbs.archlinux.org/viewtopic.php?id=146282

Unfortunately, it doesn't look like we're having the same issue....

Offline

#86 2012-08-08 09:59:42

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Ok, it happened again... No matter what I try, the problem persists. But I can confirm that no weird ACPI events get sent since I was logging them and only saw this:

Wed Aug  8 11:57:40 EEST 2012: button/lid LID close
Wed Aug  8 12:00:25 EEST 2012: button/lid LID open
Wed Aug  8 12:02:50 EEST 2012: button/volumedown VOLDN 00000080 00000000 K
Wed Aug  8 12:13:48 EEST 2012: button/lid LID close

And after that, no more ACPI events get registered.

Compare the /var/log/everything.log

Aug  8 11:57:40 localhost yourself: LID closed
Aug  8 11:57:41 localhost kernel: [17768.608348] EXT4-fs (sda1): re-mounted. Opts: discard,barrier=0,commit=0
Aug  8 11:57:41 localhost kernel: [17768.736783] wlan0: deauthenticating from 00:13:33:a1:23:46 by local choice (reason=3)
Aug  8 11:57:41 localhost kernel: [17768.746213] cfg80211: Calling CRDA to update world regulatory domain
Aug  8 11:57:41 localhost avahi-daemon[29988]: Interface wlan0.IPv4 no longer relevant for mDNS.
Aug  8 11:57:41 localhost avahi-daemon[29988]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.6.
Aug  8 11:57:41 localhost avahi-daemon[29988]: Withdrawing address record for fe80::8ea9:82ff:fe91:7742 on wlan0.
Aug  8 11:57:41 localhost avahi-daemon[29988]: Withdrawing address record for 192.168.1.6 on wlan0.
Aug  8 11:57:41 localhost avahi-daemon[29988]: Withdrawing workstation service for wlan0.
Aug  8 11:57:41 localhost NetworkManager[778]: <info> (wlan0): now unmanaged
Aug  8 11:57:41 localhost NetworkManager[778]: <info> (wlan0): device state change: activated -> unmanaged (reason 'removed') [100 10 36]
Aug  8 11:57:41 localhost NetworkManager[778]: <info> (wlan0): deactivating device (reason 'removed') [36]
Aug  8 11:57:41 localhost dhcpcd[31226]: received SIGTERM, stopping
Aug  8 11:57:41 localhost dhcpcd[31226]: wlan0: removing interface
Aug  8 11:57:41 localhost dhcpcd[31226]: wlan0: del_route: No such device
Aug  8 11:57:41 localhost dhcpcd[31226]: del_address: No such device
Aug  8 11:57:41 localhost kernel: [17768.860608] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.871960] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.872085] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.872095] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.872118] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Aug  8 11:57:41 localhost kernel: [17768.885244] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Aug  8 11:57:41 localhost kernel: [17768.885270] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.898563] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.898689] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.898699] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.898723] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Aug  8 11:57:41 localhost kernel: [17768.898749] ehci_hcd 0000:00:1d.0: remove, state 4
Aug  8 11:57:41 localhost kernel: [17768.898766] usb usb2: USB disconnect, device number 1
Aug  8 11:57:41 localhost kernel: [17768.898771] usb 2-1: USB disconnect, device number 2
Aug  8 11:57:41 localhost kernel: [17768.904231] ehci_hcd 0000:00:1d.0: USB bus 2 deregistered
Aug  8 11:57:41 localhost kernel: [17768.904328] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.915144] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.915280] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.915289] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.915312] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Aug  8 11:57:41 localhost kernel: [17768.928455] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Aug  8 11:57:41 localhost kernel: [17768.928479] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.941813] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.941942] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.941952] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 11:57:41 localhost kernel: [17768.941976] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Aug  8 11:57:41 localhost kernel: [17768.942001] ehci_hcd 0000:00:1a.0: remove, state 4
Aug  8 11:57:41 localhost kernel: [17768.942019] usb usb1: USB disconnect, device number 1
Aug  8 11:57:41 localhost kernel: [17768.942024] usb 1-1: USB disconnect, device number 2
Aug  8 11:57:41 localhost kernel: [17768.942029] usb 1-1.6: USB disconnect, device number 3
Aug  8 11:57:41 localhost NetworkManager[778]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 31226
Aug  8 11:57:41 localhost NetworkManager[778]: <warn> (19) failed to find interface name for index
Aug  8 11:57:41 localhost NetworkManager[778]: nm_system_iface_flush_routes: assertion `iface != NULL' failed
Aug  8 11:57:41 localhost NetworkManager[778]: <warn> (19) failed to find interface name for index
Aug  8 11:57:41 localhost NetworkManager[778]: <info> (wlan0): cleaning up...
Aug  8 11:57:41 localhost NetworkManager[778]: <warn> (19) failed to find interface name for index
Aug  8 11:57:41 localhost NetworkManager[778]: (nm-system.c:685):nm_system_iface_get_flags: runtime check failed: (iface != NULL)
Aug  8 11:57:41 localhost NetworkManager[778]: <error> [1344416261.582550] [nm-system.c:687] nm_system_iface_get_flags(): (unknown): failed to get interface link object
Aug  8 11:57:41 localhost NetworkManager[778]: <info> radio killswitch /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/ieee80211/phy0/rfkill16 disappeared
Aug  8 11:57:41 localhost dbus[418]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Aug  8 11:57:41 localhost NetworkManager[778]: <warn> (pid 31226) unhandled DHCP event for interface wlan0
Aug  8 11:57:41 localhost kernel: [17768.989538] ehci_hcd 0000:00:1a.0: USB bus 1 deregistered
Aug  8 11:57:41 localhost dbus[418]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug  8 11:57:41 localhost kernel: [17769.211272] PM: Syncing filesystems ... done.
Aug  8 11:57:41 localhost kernel: [17769.222630] PM: Preparing system for mem sleep
Aug  8 12:00:24 localhost kernel: [17769.397967] Freezing user space processes ... (elapsed 0.01 seconds) done.
Aug  8 12:00:24 localhost kernel: [17769.411209] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Aug  8 12:00:24 localhost kernel: [17769.424542] PM: Entering mem sleep
Aug  8 12:00:24 localhost kernel: [17769.424595] Suspending console(s) (use no_console_suspend to debug)
Aug  8 12:00:24 localhost kernel: [17769.437897] e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Aug  8 12:00:24 localhost kernel: [17769.885527] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Aug  8 12:00:24 localhost kernel: [17769.885626] sd 0:0:0:0: [sda] Stopping disk
Aug  8 12:00:24 localhost kernel: [17769.950335] i915 0000:00:02.0: power state changed by ACPI to D3
Aug  8 12:00:24 localhost kernel: [17770.105149] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
Aug  8 12:00:24 localhost kernel: [17770.216668] PM: suspend of devices complete after 497.840 msecs
Aug  8 12:00:24 localhost kernel: [17770.216792] PM: late suspend of devices complete after 0.121 msecs
Aug  8 12:00:24 localhost kernel: [17770.217530] PM: noirq suspend of devices complete after 0.737 msecs
Aug  8 12:00:24 localhost kernel: [17770.217715] ACPI: Preparing to enter system sleep state S3
Aug  8 12:00:24 localhost kernel: [17770.359758] PM: Saving platform NVS memory
Aug  8 12:00:24 localhost kernel: [17770.363139] Disabling non-boot CPUs ...

with the one about 15 minutes later

Aug  8 12:13:48 localhost yourself: LID closed
Aug  8 12:13:48 localhost kernel: [18574.359296] EXT4-fs (sda1): re-mounted. Opts: discard,barrier=0,commit=0
Aug  8 12:13:49 localhost kernel: [18574.526595] wlan0: deauthenticating from 00:13:33:a1:23:46 by local choice (reason=3)
Aug  8 12:13:49 localhost kernel: [18574.536732] cfg80211: Calling CRDA to update world regulatory domain
Aug  8 12:13:49 localhost avahi-daemon[29988]: Interface wlan0.IPv4 no longer relevant for mDNS.
Aug  8 12:13:49 localhost avahi-daemon[29988]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.6.
Aug  8 12:13:49 localhost avahi-daemon[29988]: Withdrawing address record for fe80::8ea9:82ff:fe91:7742 on wlan0.
Aug  8 12:13:49 localhost avahi-daemon[29988]: Withdrawing address record for 192.168.1.6 on wlan0.
Aug  8 12:13:49 localhost avahi-daemon[29988]: Withdrawing workstation service for wlan0.
Aug  8 12:13:49 localhost NetworkManager[778]: <info> (wlan0): now unmanaged
Aug  8 12:13:49 localhost NetworkManager[778]: <info> (wlan0): device state change: activated -> unmanaged (reason 'removed') [100 10 36]
Aug  8 12:13:49 localhost NetworkManager[778]: <info> (wlan0): deactivating device (reason 'removed') [36]
Aug  8 12:13:49 localhost dhcpcd[1247]: received SIGTERM, stopping
Aug  8 12:13:49 localhost dhcpcd[1247]: wlan0: removing interface
Aug  8 12:13:49 localhost dhcpcd[1247]: wlan0: del_route: No such device
Aug  8 12:13:49 localhost kernel: [18574.649473] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.662761] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.662890] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.662900] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.662923] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Aug  8 12:13:49 localhost kernel: [18574.676119] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Aug  8 12:13:49 localhost kernel: [18574.676145] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.689352] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.689475] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.689485] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.689508] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Aug  8 12:13:49 localhost kernel: [18574.689534] ehci_hcd 0000:00:1d.0: remove, state 4
Aug  8 12:13:49 localhost kernel: [18574.689551] usb usb2: USB disconnect, device number 1
Aug  8 12:13:49 localhost kernel: [18574.689556] usb 2-1: USB disconnect, device number 2
Aug  8 12:13:49 localhost kernel: [18574.694746] ehci_hcd 0000:00:1d.0: USB bus 2 deregistered
Aug  8 12:13:49 localhost kernel: [18574.694865] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.705997] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.706150] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.706163] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.706189] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Aug  8 12:13:49 localhost kernel: [18574.719335] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Aug  8 12:13:49 localhost kernel: [18574.719366] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.732714] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.732843] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.732853] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Aug  8 12:13:49 localhost kernel: [18574.732876] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Aug  8 12:13:49 localhost kernel: [18574.732902] ehci_hcd 0000:00:1a.0: remove, state 4
Aug  8 12:13:49 localhost kernel: [18574.732920] usb usb1: USB disconnect, device number 1
Aug  8 12:13:49 localhost kernel: [18574.732925] usb 1-1: USB disconnect, device number 2
Aug  8 12:13:49 localhost kernel: [18574.732930] usb 1-1.6: USB disconnect, device number 3
Aug  8 12:13:49 localhost kernel: [18574.750062] ehci_hcd 0000:00:1a.0: USB bus 1 deregistered
Aug  8 12:13:49 localhost NetworkManager[778]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 1247
Aug  8 12:13:49 localhost NetworkManager[778]: <warn> (20) failed to find interface name for index
Aug  8 12:13:49 localhost NetworkManager[778]: nm_system_iface_flush_routes: assertion `iface != NULL' failed
Aug  8 12:13:49 localhost NetworkManager[778]: <warn> (20) failed to find interface name for index
Aug  8 12:13:49 localhost NetworkManager[778]: <info> (wlan0): cleaning up...
Aug  8 12:13:49 localhost NetworkManager[778]: <warn> (20) failed to find interface name for index
Aug  8 12:13:49 localhost NetworkManager[778]: (nm-system.c:685):nm_system_iface_get_flags: runtime check failed: (iface != NULL)
Aug  8 12:13:49 localhost NetworkManager[778]: <error> [1344417229.314421] [nm-system.c:687] nm_system_iface_get_flags(): (unknown): failed to get interface link object
Aug  8 12:13:49 localhost NetworkManager[778]: <info> radio killswitch /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/ieee80211/phy0/rfkill17 disappeared
Aug  8 12:13:49 localhost NetworkManager[778]: <warn> (pid 1247) unhandled DHCP event for interface wlan0
Aug  8 12:13:49 localhost dbus[418]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Aug  8 12:13:49 localhost dbus[418]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug  8 12:13:49 localhost kernel: [18574.981105] PM: Syncing filesystems ... done.
Aug  8 12:13:49 localhost kernel: [18574.990237] PM: Preparing system for mem sleep
Aug  8 12:13:49 localhost kdm[14167]: X server for display :0 terminated unexpectedly
Aug  8 12:13:49 localhost NetworkManager[778]: <warn> error requesting auth for org.freedesktop.NetworkManager.wifi.share.open: (3) GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner: Could not get UID of name ':1.166': no such name
Aug  8 12:13:50 localhost kdm_greet[1748]: Cannot load /usr/share/apps/kdm/faces/.default.face: No such file or directory
Aug  8 09:14:11 localhost rtkit-daemon[1756]: Successfully made thread 1868 of process 1868 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Aug  8 09:14:11 localhost rtkit-daemon[1756]: Supervising 1 threads of 1 processes of 1 users.
Aug  8 12:14:11 localhost pulseaudio[1868]: [pulseaudio] pid.c: Stale PID file, overwriting.
Aug  8 12:14:12 localhost dbus[418]: [system] Activating service name='org.bluez' (using servicehelper)
Aug  8 12:14:12 localhost dbus[418]: [system] Activated service 'org.bluez' failed: Launch helper exited with unknown return code 1
Aug  8 12:14:12 localhost pulseaudio[1868]: [pulseaudio] bluetooth-util.c: org.bluez.Manager.ListAdapters() failed: org.freedesktop.DBus.Error.Spawn.ChildExited: Launch helper exited with unknown return code 1
Aug  8 09:14:12 localhost rtkit-daemon[1756]: Successfully made thread 1952 of process 1952 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Aug  8 09:14:12 localhost rtkit-daemon[1756]: Supervising 2 threads of 2 processes of 1 users.
Aug  8 12:14:12 localhost pulseaudio[1952]: [pulseaudio] pid.c: Daemon already running.

As you can see, up to these:

Aug  8 12:13:49 localhost kernel: [18574.981105] PM: Syncing filesystems ... done.
Aug  8 12:13:49 localhost kernel: [18574.990237] PM: Preparing system for mem sleep

they are completely identical. After that, we see that on the failed suspend, X crashes:

Aug  8 12:13:49 localhost kdm[14167]: X server for display :0 terminated unexpectedly

and the kernel never states

Aug  8 12:00:24 localhost kernel: [17769.397967] Freezing user space processes ... (elapsed 0.01 seconds) done.
Aug  8 12:00:24 localhost kernel: [17769.411209] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Aug  8 12:00:24 localhost kernel: [17769.424542] PM: Entering mem sleep

as it always does on a successful suspend.

On a side note there is this rtkit-daemon stating the wrong time, but this normal behaviour as it runs in its own chroot environment and has no access to localtime. (At least that's what they say).

So after all this tampering and fiddling, I am back to where I started. The kernel just *STOPS* the suspend process in the middle, no errors reported apart from X crashing and I'm left in the dark. I weep.

Offline

#87 2012-08-08 12:44:27

niklaas
Member
From: Vienna
Registered: 2012-07-24
Posts: 26

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

yourself wrote:

Another piece of news and info. I just noticed that if I connect a 3GS iPhone to my computer, sometimes upowerd segfaults:

Aug  3 18:23:54 localhost kernel: [  280.471777] upowerd[3078]: segfault at 0 ip 00007f0fa4cbe5b0 sp 00007fff80ddf538 error 4 in libc-2.16.so[7f0fa4bc8000+19b000]

It's maybe a bit off-topic but: Have you found out how to prevent this? I get the same error and because of this I am unable to copy music to my iPod touch.

Offline

#88 2012-08-08 14:29:24

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

niklaas wrote:
yourself wrote:

Another piece of news and info. I just noticed that if I connect a 3GS iPhone to my computer, sometimes upowerd segfaults:

Aug  3 18:23:54 localhost kernel: [  280.471777] upowerd[3078]: segfault at 0 ip 00007f0fa4cbe5b0 sp 00007fff80ddf538 error 4 in libc-2.16.so[7f0fa4bc8000+19b000]

It's maybe a bit off-topic but: Have you found out how to prevent this? I get the same error and because of this I am unable to copy music to my iPod touch.

No, I haven't :-( ...  I transfer files to and from my jailbroken iPhone using SSH...

On our problem, I've tried booting with i915.semaphores=1 kernel parameter and see what happens. I've got the impression that the only thing I haven't tried is downgrade X and/or intel KMS driver so it may be related to that.....

By the way, are we all using the i915 kernel driver?

Offline

#89 2012-08-08 18:23:01

Essl
Member
Registered: 2012-07-15
Posts: 27

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

oh yeah, I should mention that this doesn't happen to me with my ipod touch. upowerd has never segfaulted either according to my kernel.log. I don't have libimobiledevices installed though.

Offline

#90 2012-08-08 21:38:15

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

No deal, i915.semaphores and it happened again. Nothing suspicious in acpi events, /var/log/everything.log contains exactly the same sequence! The suspend procedure stops at "preparing system for mem sleep" and X crashing....

Aug  9 00:25:06 localhost kernel: [14861.452137] PM: Syncing filesystems ... done.
Aug  9 00:25:06 localhost kernel: [14861.456038] PM: Preparing system for mem sleep
Aug  9 00:25:06 localhost kdm[1746]: X server for display :0 terminated unexpectedly

WTF is going on!?!?!?!?!?

Offline

#91 2012-08-10 19:43:56

joshua.finkbeiner
Member
Registered: 2010-05-29
Posts: 5

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Have you tried setting SUSPEND_MODULES="thinkpad_acpi" in /etc/pm/config.d/modules? I had a similar problem on my W520 with occasional hangs when resuming, and this change seems to have alleviated it for me (or, at least I haven't experienced the problem in a couple weeks...).

There was some information at the Thinkpad wiki at http://www.thinkwiki.org/wiki/Thinkpad-acpi, although their server appears to be down right now.

Offline

#92 2012-08-11 08:28:19

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

joshua.finkbeiner wrote:

Have you tried setting SUSPEND_MODULES="thinkpad_acpi" in /etc/pm/config.d/modules? I had a similar problem on my W520 with occasional hangs when resuming, and this change seems to have alleviated it for me (or, at least I haven't experienced the problem in a couple weeks...).

There was some information at the Thinkpad wiki at http://www.thinkwiki.org/wiki/Thinkpad-acpi, although their server appears to be down right now.

Yes, I have, and it made no difference... :-(

Offline

#93 2012-08-11 12:45:48

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

I am now at 29 hours of uptime and it hasn't happened. There are 3 things that have happened.

-> The xf86-video-intel driver has been updated about 12 hours ago
-> I killed the X server to reload the new driver (by running init 3 and then again init 5 as root)
-> I am using acpid only and having it run a test pm-suspend before the actual one:
(excerpt from /etc/acpi/handler.sh)

logger 'LID closed'
LOG=/var/log/suspend-debug.log
echo "     " >> $LOG
echo " ----- SUSPEND: `date` ----- " >> $LOG
echo "     " >> $LOG
dmesg -C
echo "--- Testing Core ---" >> $LOG
echo "core" > /sys/power/pm_test
pm-suspend
dmesg >> $LOG
dmesg -C
echo "--- Actual suspend ---" >> $LOG
echo "none" > /sys/power/pm_test
pm-suspend
dmesg >> $LOG
dmesg -C
;;

I have not reached 29 hours of uptime with suspend/resume cycles before. This tells me that something of those 3 affected the issue. It could be that it was xf86-video-intel's bug and now it's fixed (unlikely, but then again, why not?), it could be that this was (and still is) an X issue which happens after continuous use of X (and by killing it, I managed to reset the "bug counter") or somehow setting "core" to pm_test, trying a test suspend and then "none" and actually suspend bypassed the issue somehow.

If it is fixed in xf86-video-intel, I should be able to go on without any problems.
If it is an X issue and the bug counter was reset, I should have the problem appear at about 36 hours.
If it is the test suspend, I should be able to go on without problems.

OR, it could be that nothing has affected the issue and it is a coincidence. We'll soon find out, one way or the other.

Offline

#94 2012-08-11 15:23:15

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Ha. So it happened again just now. 34 hours of uptime.

This leads me to believe that it is directly connected to X. Further, this seems to be connected to X's uptime.

But a new version of X just came out, maybe this is fixed?

Fingers crossed (again!)

Offline

#95 2012-08-11 15:29:22

der_joachim
Member
From: the Netherlands
Registered: 2008-12-17
Posts: 136
Website

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

joshua.finkbeiner wrote:

Have you tried setting SUSPEND_MODULES="thinkpad_acpi" in /etc/pm/config.d/modules? I had a similar problem on my W520 with occasional hangs when resuming, and this change seems to have alleviated it for me (or, at least I haven't experienced the problem in a couple weeks...).

There was some information at the Thinkpad wiki at http://www.thinkwiki.org/wiki/Thinkpad-acpi, although their server appears to be down right now.

[semi-offtopic]: Thinkwiki.org is happliy up and running, but apparently the wiki page has moved or something.


Geek, runner, motorcyclist and professional know-it-all

Offline

#96 2012-08-12 13:27:48

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

The same happened again with the newer 1.12.3.901-1 xorg-server.

I'll try to confirm by killing the x server every now and then, see if I can get some uptime this way.

Offline

#97 2012-08-13 08:02:53

btorb
Member
Registered: 2012-08-06
Posts: 28

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

It is one hell of an annoying bug. It appears on a lot of systems (read about it on gentoo, debian, ubuntu and arch fora). Nobody seems to have a clue.

My summary (as a report of negative results):

On ubuntu 11.10 all worked well for me; I upgraded to 12.04 and the problem emerged.
-I installed arch from scratch (using the July 2012 code) with plain vanilla openbox and it happened again: every now and then after awaking after suspend X crashes for no reason.
- i tried uninstalling acpid and invoking pm-suspend: no effect
- i tried the lts kernel: no effect and the sporadic crashes persist.
- Downgraded to the previous Xserver, no effect.

Currently running a 3.0.4 kernel with thinpad_acpi, hdaps and thinkpad_ec modules: still no effect. X keeps on crashing every now and then after suspending.

I'm kind of desperate because I use my laptop for work and I rather suspend then shutdown-boot every time as i can leave my files open where I finished. Going back to an old ubuntu version is probably not the way forward as i need some "new" software packages.

Anybody any clue?

(btw, shall we try to bundle all forum posts about this issues. There are at least a few other already on the arch forum: here, here)

Offline

#98 2012-08-13 10:00:18

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

I just noticed these two lines on my clean/after-boot dmesg:

[    0.363136] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored
and
[    0.387856] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug

Anyone else has these?

Offline

#99 2012-08-15 17:36:43

Essl
Member
Registered: 2012-07-15
Posts: 27

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Yes, I do have both of these in my dmesg:


[    0.393707] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored


[    0.405755] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug

Offline

#100 2012-08-16 07:56:30

yourself
Member
Registered: 2008-10-23
Posts: 117

Re: [SOLVED]Linux3.4 broke resume/suspend some of the times in Lenovo T420

Well, it certainly is Xorg (at least partially) to blame. I am now at 60 hours of uptime with proper suspend/resume cycles by closing the lid.

The way I have done this is by killing the X server every 10-12 hours. Not a "solution" but at least we can start looking somewhere... I'll file a bug and see where it goes from there...

By the way, is anyone here experiencing the issue NOT using the i915 kernel driver? (i.e. non-intel graphics?)

Last edited by yourself (2012-08-16 07:57:03)

Offline

Board footer

Powered by FluxBB