On 03/06/2014 01:30 PM, David C. Rankin wrote: > On 03/06/2014 01:06 PM, David C. Rankin wrote: >> On 03/06/2014 12:55 PM, David C. Rankin wrote: >>> On 03/06/2014 12:29 PM, Timothy Pearson wrote: >>>>> On 03/06/2014 10:52 AM, Dr. Nikolaus Klepp wrote: >>>>>> Am Donnerstag, 6. März 2014 schrieb David C. Rankin: >>>>>>> All, >>>>>>> >>>>>>> Testing the new soft-freeze packages has disclosed a horrible problem >>>>>>> with >>>>>>> tdepowersave taking nearly 100% of the CPU. My laptop was nearly on >>>>>>> fire this >>>>>>> morning: >>>>>>> >>>>>>> [screenshot] >>>>>>> http://www.3111skyline.com/dl/dt/trinity/ss/tdeowersave-97percent.jpg >>>>>>> >>>>>>> Earlier in development I saw tdepowesave in the 20-40% range, but >>>>>>> never at >>>>>>> near 100% of CPU. What to try? >>>>>>> >>>>>> >>>>>> What kernel do you use? I had the same problem on wheezy with 3.5.13.2. >>>>>> tdepowersave took 100% of one core. It occured after tdepowersave put >>>>>> the x61 to powersave the second time. It looks like the problem went >>>>>> away after changing to kernel 3.12-0.bpo.1. >>>>>> >>>>>> nik >>>>>> >>>>>> >>>>> >>>>> Kernel is 3.13-5.1 (just a few days old), So this is happening with >>>>> current kernels. >>>>> >>>>> -- >>>>> David C. Rankin, J.D.,P.E. >>>> >>>> Try attaching gdb to the runaway tdepowersave process--that should at >>>> least show you where it is stuck. >>>> >>>> Tim >>>> >>> >>> Will do - and report back. >>> >>> >> >> Darn, >> >> On attaching the debugger to the launch, you get some interesting output, but >> then tdepowersave goes back to taking its normal 5-7% of CPU instead of 97% of >> CPU. Maybe this just happens on initial startup? Is its failure to register with >> systemd the problem? Here is the output: >> >> [Thread debugging using libthread_db enabled] >> Using host libthread_db library "/usr/lib/libthread_db.so.1". >> [FIXME] UNCLASSIFIED DEVICE name: broadcast type: (null) subsystem: clockevents >> driver: (null) [Node Path: (null)] [Syspath: >> /sys/devices/system/clockevents/broadcast] [(null):(null)] >> [FIXME] UNCLASSIFIED DEVICE name: clockevent0 type: (null) subsystem: >> clockevents driver: (null) [Node Path: (null)] [Syspath: >> /sys/devices/system/clockevents/clockevent0] [(null):(null)] >> TQSocketNotifier: Multiple socket notifiers for same socket 12 and type read >> tdepowersave: WARNING: The session is not registered with systemd >> [Inferior 1 (process 31672) exited normally] >> >> Is there a way I can configure it to start with the debugger attached on tde >> start? >> > > Tim, > > tdepowersave starts at it's normal 5-7% and then steadily climbs in CPU use > until it will take 100% on my box. Attaching the pid to gdb yielded the following: > > sudo gdb attach 674 > ... > 0xb7795424 in __kernel_vsyscall () > (gdb) continue > > Continuing the tdepowersave process I watch the CPU use steadily climbing over > the next few minutes. I captured a screenshot at 14%, now it is well over 26% of > the CPU: > > http://www.3111skyline.com/dl/dt/trinity/ss/tdeowersave-14percent.jpg > > Interrupting tdepowesave in gdb output the following: > > ^C > Program received signal SIGINT, Interrupt. > 0xb5ac1b1f in ?? () from /usr/lib/libdbus-1.so.3 > > I don't know if that is where it is stuck or if it is stuck at the > __kernel_vsyscall ()? > > What would you like me to try? > > > Checking the journal, it looks like tdepowersave is in a fight with dbus-1, the following set of messages repeats over-and-over in the logs. It did not do this prior to the soft-freeze build yesterday: Mar 06 13:31:28 valhalla dbus[176]: [system] Rejected send message, 2 matched rules; type="method_call", sender=":1.37" (uid=1000 pid=674 comm="tdepowersave [tdeinit] ") interface="org.trinitydesktop.hardwarecontrol.InputEvents" member="GetActiveSwitches" error name="(unset)" requested_reply="0" destination="org.trinitydesktop.hardwarecontrol" (uid=0 pid=12964 comm="/opt/trinity/bin/tde_dbus_hardwarecontrol ") Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Name request failed with error 'Rejected send message, 2 matched rules; type="method_call", sender=":1.37" (uid=1000 pid=674 comm="tdepowersave [tdeinit] ") interface="org.trinitydesktop.hardwarecontrol.InputEvents" member="GetActiveSwitches" error name="(unset)" requested_reply="0" destination="org.trinitydesktop.hardwarecontrol" (uid=0 pid=12964 comm="/opt/trinity/bin/tde_dbus_hardwarecontrol ")' Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Not primary owner (-1), exiting! Mar 06 13:31:28 valhalla dbus[176]: [system] Activated service 'org.trinitydesktop.hardwarecontrol' failed: Launch helper exited with unknown return code 1 Mar 06 13:31:28 valhalla dbus[176]: [system] Activating service name='org.trinitydesktop.hardwarecontrol' (using servicehelper) Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Listening... Mar 06 13:31:28 valhalla dbus[176]: [system] Successfully activated service 'org.trinitydesktop.hardwarecontrol' Mar 06 13:31:28 valhalla dbus[176]: [system] Rejected send message, 2 matched rules; type="method_call", sender=":1.37" (uid=1000 pid=674 comm="tdepowersave [tdeinit] ") interface="org.trinitydesktop.hardwarecontrol.InputEvents" member="GetProvidedSwitches" error name="(unset)" requested_reply="0" destination="org.trinitydesktop.hardwarecontrol" (uid=0 pid=12966 comm="/opt/trinity/bin/tde_dbus_hardwarecontrol ") Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Name request failed with error 'Rejected send message, 2 matched rules; type="method_call", sender=":1.37" (uid=1000 pid=674 comm="tdepowersave [tdeinit] ") interface="org.trinitydesktop.hardwarecontrol.InputEvents" member="GetProvidedSwitches" error name="(unset)" requested_reply="0" destination="org.trinitydesktop.hardwarecontrol" (uid=0 pid=12966 comm="/opt/trinity/bin/tde_dbus_hardwarecontrol ")' Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Not primary owner (-1), exiting! Mar 06 13:31:28 valhalla dbus[176]: [system] Activated service 'org.trinitydesktop.hardwarecontrol' failed: Launch helper exited with unknown return code 1 Mar 06 13:31:28 valhalla dbus[176]: [system] Activating service name='org.trinitydesktop.hardwarecontrol' (using servicehelper) Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Listening... Mar 06 13:31:28 valhalla dbus[176]: [system] Successfully activated service 'org.trinitydesktop.hardwarecontrol' Mar 06 13:31:28 valhalla dbus[176]: [system] Rejected send message, 2 matched rules; type="method_call", sender=":1.37" (uid=1000 pid=674 comm="tdepowersave [tdeinit] ") interface="org.trinitydesktop.hardwarecontrol.InputEvents" member="GetActiveSwitches" error name="(unset)" requested_reply="0" destination="org.trinitydesktop.hardwarecontrol" (uid=0 pid=12968 comm="/opt/trinity/bin/tde_dbus_hardwarecontrol ") Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Name request failed with error 'Rejected send message, 2 matched rules; type="method_call", sender=":1.37" (uid=1000 pid=674 comm="tdepowersave [tdeinit] ") interface="org.trinitydesktop.hardwarecontrol.InputEvents" member="GetActiveSwitches" error name="(unset)" requested_reply="0" destination="org.trinitydesktop.hardwarecontrol" (uid=0 pid=12968 comm="/opt/trinity/bin/tde_dbus_hardwarecontrol ")' Mar 06 13:31:28 valhalla org.trinitydesktop.hardwarecontrol[176]: [tde_dbus_hardwarecontrol] Not primary owner (-1), exiting! Mar 06 13:31:28 valhalla dbus[176]: [system] Activated service 'org.trinitydesktop.hardwarecontrol' failed: Launch helper exited with unknown return code 1 -- David C. Rankin, J.D.,P.E.