On Wed, 9 Apr 2008, Michael Biebl wrote: > Sven-Haegar Koch schrieb: > > Package: powersaved > > Version: 0.15.20-2 > > Severity: normal > > > > > > Until the powersaved update of today pressing Fn+F4 on my Thinkpad T60 > > started suspend to ram, which worked without problems. > > > > Now when pressing it I only get some logs from acpid into syslog, nothing > > from powersaved anymore: > > (/etc/powersave/common DEBUG="31") > > > > Apr 9 01:11:06 aurora acpid: received event "ibm/hotkey HKEY 00000080 > > 00001004" > > Apr 9 01:11:06 aurora acpid: notifying client 4028[0:0] > > Apr 9 01:11:06 aurora acpid: notifying client 27786[115:122] > > Apr 9 01:11:06 aurora acpid: notifying client 4596[0:0] > > Apr 9 01:11:06 aurora acpid: notifying client 718[0:0] > > Apr 9 01:11:06 aurora acpid: executing action "/etc/acpi/sleepbtn.sh" > > Apr 9 01:11:06 aurora acpid: action exited with status 0 > > Apr 9 01:11:06 aurora acpid: completed event "ibm/hotkey HKEY 00000080 > > 00001004" > > Is that really all? No other logs of powersaved e.g. when it starts. > I'm wondering if the debug log is working at all.
There are log messages at the start of powesaved: Apr 10 20:59:24 aurora powersaved: Starting powersaved with ACPI support Apr 10 20:59:24 aurora acpid: client connected from 20760[0:0] Apr 10 20:59:24 aurora acpid: 1 client rule loaded > Do you get any messages in the syslog, if you run > powersaved -v31 -f /var/run/acpid.socket This generates way more logging than only having DEBUG="31" in /etc/powersave/common: Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/common line 67, key 'NOTIFY_METHOD' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/common line 78, key 'BOOT_LOADER' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/common line 90, key 'POLLING_INTERVAL' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/common line 101, key 'SCREENSAVER_DPMS_OFF' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/common line 113, key 'SCREENSAVER_BLANKONLY' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/common line 125, key 'POWERBTN_DELAY' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/thermal line 18, key 'ENABLE_THERMAL_MANAGEMENT' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/thermal line 36, key 'THERMAL_POLLING_FREQUENCY' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/cpufreq line 14, key 'CPUFREQ_CONTROL' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave/cpufreq line 22, key 'MAX_CPUS_ONLINE' Apr 10 21:02:36 aurora powersaved[21167]: Debug (PS_Config:36) Init power management interface Apr 10 21:02:36 aurora powersaved[21167]: Debug (SchemeConfig:33) SchemeConfig Constructor Apr 10 21:02:36 aurora powersaved[21167]: Info (initSchemes:212) Scheme scheme_powersave found. Apr 10 21:02:36 aurora powersaved[21167]: Info (initSchemes:184) Default Battery scheme is scheme_performance Apr 10 21:02:36 aurora powersaved[21167]: Info (initSchemes:188) Default AC scheme is scheme_performance Apr 10 21:02:36 aurora powersaved[21167]: Debug (PS_Config:36) Init power management interface Apr 10 21:02:36 aurora powersaved[21167]: Debug (SchemeConfig:33) SchemeConfig Constructor Apr 10 21:02:36 aurora powersaved[21167]: Info (initSchemes:212) Scheme scheme_performance found. Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave//scheme_powersave line 176, key 'MAX_CPUS_ONLINE' Apr 10 21:02:36 aurora powersaved[21167]: Info (readConfigFile:127) Empty value in file /etc/powersave//scheme_performance line 175, key 'MAX_CPUS_ONLINE' Apr 10 21:02:36 aurora powersaved[21167]: Info (openSystemConnection:252) dbus_bus_get(): Success. Apr 10 21:02:36 aurora powersaved[21167]: Info (openSystemConnection:277) Successfully acquired the interface org.freedesktop.Policy.Power Apr 10 21:02:36 aurora powersaved[21167]: Debug (PM_Interface:59) Constructor PM_Interface Apr 10 21:02:36 aurora powersaved[21167]: Info (Brightness:40) No Laptop Panels found Apr 10 21:02:36 aurora powersaved[21167]: Debug (ACPI_Interface:48) Constructor ACPI_Interface Apr 10 21:02:36 aurora powersaved[21167]: Info (setBatteryAlarm:304) set alarms: rem_Perc_all: 33, want alarm at: 12, divisor: 0.000000 Apr 10 21:02:36 aurora powersaved[21167]: DIAG (setBatteryAlarm:338) Battery 0, remaining: 14210, last full: 42780, set alarm to: 5167 Apr 10 21:02:36 aurora powersaved[21167]: Info (setBatteryAlarm_:261) Alarm set to 12% Apr 10 21:02:36 aurora powersaved[21167]: DIAG (ACPI_Interface:53) could not set battery alarm Apr 10 21:02:36 aurora powersaved[21167]: Debug (enableAll:110) Found 2 hotpluggable CPUs in system Apr 10 21:02:36 aurora powersaved[21167]: Info (registerEvent:48) registering event no.'0' Apr 10 21:02:36 aurora powersaved[21167]: Info (executeInternalAction:264) Executing internal action: ignore Apr 10 21:02:36 aurora powersaved[21167]: Info (continueEvent:240) Internal action ignore executed Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:168) SCRIPT returned: Name: 'ignore', Return value: 0, Comment: 'internal action successfull: ignore' Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:187) SCRIPT Event daemon.start finished successfully Apr 10 21:02:36 aurora powersaved[21167]: Info (registerEvent:48) registering event no.'1' Apr 10 21:02:36 aurora powersaved[21167]: Info (executeInternalAction:264) Executing internal action: ignore Apr 10 21:02:36 aurora powersaved[21167]: Info (continueEvent:240) Internal action ignore executed Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:168) SCRIPT returned: Name: 'ignore', Return value: 0, Comment: 'internal action successfull: ignore' Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:187) SCRIPT Event battery.info finished successfully Apr 10 21:02:36 aurora powersaved[21167]: Info (updateBatteryState:107) Get Battery state: 3 Apr 10 21:02:36 aurora powersaved[21167]: Info (start:120) There is at least one battery available. Apr 10 21:02:36 aurora powersaved[21167]: Debug (haveClient:314) We own the interface org.freedesktop.Policy.Power Apr 10 21:02:36 aurora powersaved[21167]: Info (registerEvent:48) registering event no.'2' Apr 10 21:02:36 aurora powersaved[21167]: Info (executeInternalAction:264) Executing internal action: ignore Apr 10 21:02:36 aurora powersaved[21167]: Info (continueEvent:240) Internal action ignore executed Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:168) SCRIPT returned: Name: 'ignore', Return value: 0, Comment: 'internal action successfull: ignore' Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:187) SCRIPT Event processor.dynamic finished successfully Apr 10 21:02:36 aurora powersaved[21167]: Debug (haveClient:314) We own the interface org.freedesktop.Policy.Power Apr 10 21:02:36 aurora powersaved[21167]: DIAG (setThermalTrippoints:327) Set trippoints for device no. 0: 99:-1:95:-1:-1 Apr 10 21:02:36 aurora powersaved[21167]: Debug (setCoolingMode:382) Set cooling mode for device no. 0: 0 (active) Apr 10 21:02:36 aurora powersaved[21167]: DIAG (activateSettings:333) BIOS has no cooling mode support. Apr 10 21:02:36 aurora powersaved[21167]: Info (getDevicesNum:79) Device CPU1 found in system. Apr 10 21:02:36 aurora powersaved[21167]: Info (getDevicesNum:79) Device CPU0 found in system. Apr 10 21:02:36 aurora powersaved[21167]: Info (getThrottlingInfoCPU:142) Throttling state: 8 Apr 10 21:02:36 aurora powersaved[21167]: Info (getThrottlingInfoCPU:148) Current Throttling state: T0 Apr 10 21:02:36 aurora powersaved[21167]: Info (setThrottlingStateCPU:190) cpu 0 set to throttling state 0 Apr 10 21:02:36 aurora powersaved[21167]: Info (getThrottlingInfoCPU:142) Throttling state: 8 Apr 10 21:02:36 aurora powersaved[21167]: Info (getThrottlingInfoCPU:148) Current Throttling state: T0 Apr 10 21:02:36 aurora powersaved[21167]: Info (setThrottlingStateCPU:190) cpu 1 set to throttling state 0 Apr 10 21:02:36 aurora powersaved[21167]: Info (dethrottle:131) CPU(s) dethrottled Apr 10 21:02:36 aurora powersaved[21167]: Info (registerEvent:48) registering event no.'3' Apr 10 21:02:36 aurora powersaved[21167]: Info (executeInternalAction:264) Executing internal action: ignore Apr 10 21:02:36 aurora powersaved[21167]: Info (continueEvent:240) Internal action ignore executed Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:168) SCRIPT returned: Name: 'ignore', Return value: 0, Comment: 'internal action successfull: ignore' Apr 10 21:02:36 aurora powersaved[21167]: Info (checkScriptReturn:187) SCRIPT Event daemon.scheme.change finished successfully Apr 10 21:02:36 aurora powersaved[21167]: DIAG (activateScheme:340) Scheme already set Apr 10 21:02:36 aurora powersaved[21167]: DIAG (activateScheme:340) Scheme already set Apr 10 21:02:36 aurora powersaved[21167]: Debug (checkBatteryStateChanges:147) Check battery state Apr 10 21:02:36 aurora powersaved[21167]: Info (updateBatteryState:107) Get Battery state: 3 Apr 10 21:02:36 aurora powersaved[21167]: Info (checkBatteryStateChanges:174) Battery state unchanged Apr 10 21:02:36 aurora powersaved[21167]: Info (run:99) Starting main loop Apr 10 21:02:36 aurora acpid: client connected from 21167[0:0] Apr 10 21:02:36 aurora acpid: 1 client rule loaded Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:89) Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:147) Received msg: 'NameAcquired' from interface: org.freedesktop.DBus Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:148) Obj.path '/org/freedesktop/DBus' from 'org.freedesktop.DBus' Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:186) Signal from org.freedesktop.DBus with member NameAcquired received Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:233) Message handled Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:89) Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:147) Received msg: 'NameAcquired' from interface: org.freedesktop.DBus Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:148) Obj.path '/org/freedesktop/DBus' from 'org.freedesktop.DBus' Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:186) Signal from org.freedesktop.DBus with member NameAcquired received Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:233) Message handled Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:89) Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:147) Received msg: 'NameAcquired' from interface: org.freedesktop.DBus Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:148) Obj.path '/org/freedesktop/DBus' from 'org.freedesktop.DBus' Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:186) Signal from org.freedesktop.DBus with member NameAcquired received Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:233) Message handled Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:89) Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:124) Received message from invalid interface Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:89) Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:124) Received message from invalid interface Apr 10 21:02:36 aurora powersaved[21167]: Debug (filter_function:89) Apr 10 21:02:36 aurora powersaved[21167]: Info (filter_function:124) Received message from invalid interface And pressing Fn+F4 now logs: Apr 10 21:03:52 aurora acpid: received event "ibm/hotkey HKEY 00000080 00001004" Apr 10 21:03:52 aurora acpid: notifying client 4043[0:0] Apr 10 21:03:52 aurora acpid: notifying client 4344[115:122] Apr 10 21:03:52 aurora acpid: notifying client 4556[0:0] Apr 10 21:03:52 aurora acpid: notifying client 21130[0:0] Apr 10 21:03:52 aurora acpid: client has disconnected Apr 10 21:03:52 aurora acpid: notifying client 21167[0:0] Apr 10 21:03:52 aurora acpid: executing action "/etc/acpi/sleepbtn.sh" Apr 10 21:03:52 aurora powersaved[21167]: DIAG (handleHWEventRequest:158) ACPI Event: 'ibm/hotkey HKEY 00000080 00001004' Apr 10 21:03:52 aurora powersaved[21167]: Debug (handleHWEventRequest:170) type: ibm/hotkey, dev_name: HKEY, port: 00000080, count: 00001004 Apr 10 21:03:52 aurora powersaved[21167]: DIAG (handleHWEventRequest:241) unknown HW event, using [other]. type 'ibm/hotkey' dev_name 'HKEY' port '00000080' count '00001004' Apr 10 21:03:52 aurora powersaved[21167]: Info (registerEvent:48) registering event no.'6' Apr 10 21:03:52 aurora powersaved[21167]: Info (executeInternalAction:264) Executing internal action: ignore Apr 10 21:03:52 aurora powersaved[21167]: Info (continueEvent:240) Internal action ignore executed Apr 10 21:03:52 aurora powersaved[21167]: Info (checkScriptReturn:168) SCRIPT returned: Name: 'ignore', Return value: 0, Comment: 'internal action successfull: ignore' Apr 10 21:03:52 aurora powersaved[21167]: Info (checkScriptReturn:187) SCRIPT Event other finished successfully Apr 10 21:03:53 aurora acpid: action exited with status 0 Apr 10 21:03:53 aurora acpid: completed event "ibm/hotkey HKEY 00000080 00001004" > > I do not use kde or gnome (I use Windowmaker), so no powermanagement tool > > from these is running. > > > > Executing "powersave -u" as root still works, it suspends and locks the > > xscreensaver on resume. > > > > powersaved -u does not react on events itself. It simply sends a > Suspend() or Hibernate() request to hal. But it's good to know, that > that works. > > > > > The NEWS.Debian file tells me that powersaved no longer does suspend-things, > > but installing pm-utils did not fix the problem either, and I was not able > > to find a place to look for further config-changes I might have to do now > > to get my laptop back into a usable state. > > Interesting enough, you should already have pm-utils installed, as hal > depends on it. It's strange that you had to manually install it. I think I already had, I suppose I installed pm-dev, which is a completly different (und useless for me) thing ;) > Could you please run the hal daemon in debug mode, and check if it > forwards the button presses to powersaved: > > hald --daemon=no --verbose=yes At the start this logs tons and tons of messages, which are too much to repeat here. On Fn+F4 it outputs just: 21:08:44.888 [D] hald_dbus.c:3153: udi=/org/freedesktop/Hal/devices/computer_logicaldev_input_2 [21225]: 21:08:44.889 [D] addon-acpi.c:195: event is 'ibm/hotkey HKEY 00000080 00001004' 21:08:44.910 [D] hald_dbus.c:3153: udi=/org/freedesktop/Hal/devices/platform_i8042_i8042_KBD_port_logicaldev_input (Together with the acpid+powersaved messages from above in syslog) c'ya sven -- The Internet treats censorship as a routing problem, and routes around it. (John Gilmore on http://www.cygnus.com/~gnu/) -- To UNSUBSCRIBE, email to [EMAIL PROTECTED] with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]