#869: 100% cpu use
--------------------+-------------------------------------------------------
 Reporter:  mccann  |       Owner:  lennart
     Type:  defect  |      Status:  new    
Milestone:          |   Component:  daemon 
 Keywords:          |  
--------------------+-------------------------------------------------------
 Noticed my battery drain very very quickly.  Looked at top:
   PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND
 17959 mccann     9 -11  107m 2944 2320 R 99.7  0.1 850:13.90 pulseaudio
 Yikes.

 strace showed:
 read(32, 0xbfeba8c4, 72)                = -1 ENODEV (No such device)
 ppoll([{fd=4, events=POLLIN}, {fd=38, events=POLLIN}, {fd=37,
 events=POLLIN}, {fd=27, events=POLLIN}, {fd=33, events=POLLIN}, {fd=10,
 events=POLLIN}, {fd=21, events=POLLIN}, {fd=7, events=POLLIN}, {fd=20,
 events=POLLIN}, {fd=26, events=POLLIN}, {fd=32, events=POLLIN}, {fd=36,
 events=POLLIN|POLLERR|POLLHUP}, {fd=36, events=0}, {fd=35, events=POLLIN},
 {fd=34, events=POLLIN}, {fd=14, events=POLLIN}, {fd=28, events=POLLIN},
 {fd=31, events=POLLIN}, {fd=22, events=POLLIN}, {fd=25, events=POLLIN},
 {fd=19, events=POLLIN|POLLERR|POLLHUP}, {fd=19, events=0}, {fd=15,
 events=POLLIN}, {fd=18, events=POLLIN}, {fd=8, events=POLLIN}], 25, NULL,
 NULL, 8) = 1 ([{fd=32, revents=POLLERR}])
 read(32, 0xbfeba8c4, 72)                = -1 ENODEV (No such device)
 ppoll([{fd=4, events=POLLIN}, {fd=38, events=POLLIN}, {fd=37,
 events=POLLIN}, {fd=27, events=POLLIN}, {fd=33, events=POLLIN}, {fd=10,
 events=POLLIN}, {fd=21, events=POLLIN}, {fd=7, events=POLLIN}, {fd=20,
 events=POLLIN}, {fd=26, events=POLLIN}, {fd=32, events=POLLIN}, {fd=36,
 events=POLLIN|POLLERR|POLLHUP}, {fd=36, events=0}, {fd=35, events=POLLIN},
 {fd=34, events=POLLIN}, {fd=14, events=POLLIN}, {fd=28, events=POLLIN},
 {fd=31, events=POLLIN}, {fd=22, events=POLLIN}, {fd=25, events=POLLIN},
 {fd=19, events=POLLIN|POLLERR|POLLHUP}, {fd=19, events=0}, {fd=15,
 events=POLLIN}, {fd=18, events=POLLIN}, {fd=8, events=POLLIN}], 25, NULL,
 NULL, 8) = 1 ([{fd=32, revents=POLLERR}])
 read(32, 0xbfeba8c4, 72)                = -1 ENODEV (No such device)
 ppoll([{fd=4, events=POLLIN}, {fd=38, events=POLLIN}, {fd=37,
 events=POLLIN}, {fd=27, events=POLLIN}, {fd=33, events=POLLIN}, {fd=10,
 events=POLLIN}, {fd=21, events=POLLIN}, {fd=7, events=POLLIN}, {fd=20,
 events=POLLIN}, {fd=26, events=POLLIN}, {fd=32, events=POLLIN}, {fd=36,
 events=POLLIN|POLLERR|POLLHUP}, {fd=36, events=0}, {fd=35, events=POLLIN},
 {fd=34, events=POLLIN}, {fd=14, events=POLLIN}, {fd=28, events=POLLIN},
 {fd=31, events=POLLIN}, {fd=22, events=POLLIN}, {fd=25, events=POLLIN},
 {fd=19, events=POLLIN|POLLERR|POLLHUP}, {fd=19, events=0}, {fd=15,
 events=POLLIN}, {fd=18, events=POLLIN}, {fd=8, events=POLLIN}], 25, NULL,
 NULL, 8) = 1 ([{fd=32, revents=POLLERR}])
 read(32, 0xbfeba8c4, 72)                = -1 ENODEV (No such device)

 And gdb showed:
 Thread 4 (Thread 0xb753bb70 (LWP 17960)):
 #0  0x00efc416 in __kernel_vsyscall ()
 #1  0x006bdec5 in ppoll (fds=0x847aa58, nfds=2, timeout=<value optimized
 out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:58
 #2  0x0059dea0 in pa_rtpoll_run (p=0x8477dc0, wait_op=true) at
 pulsecore/rtpoll.c:304
 #3  0x001d061c in thread_func (userdata=0x847a890) at modules/alsa/alsa-
 sink.c:1430
 #4  0x003bd442 in internal_thread_func (userdata=0x84c6410) at pulsecore
 /thread-posix.c:72
 #5  0x00510919 in start_thread (arg=0xb753bb70) at pthread_create.c:301
 #6  0x006c8cbe in clone () at ../sysdeps/unix/sysv/linux/i386/clone.S:133

 Thread 3 (Thread 0xb294eb70 (LWP 17961)):
 #0  0x00efc416 in __kernel_vsyscall ()
 #1  0x006bdec5 in ppoll (fds=0x847f958, nfds=2, timeout=<value optimized
 out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:58
 #2  0x0059dea0 in pa_rtpoll_run (p=0x84bf188, wait_op=true) at
 pulsecore/rtpoll.c:304
 #3  0x001d6d86 in thread_func (userdata=0x84a0a10) at modules/alsa/alsa-
 source.c:1274
 #4  0x003bd442 in internal_thread_func (userdata=0x84d1290) at pulsecore
 /thread-posix.c:72
 #5  0x00510919 in start_thread (arg=0xb294eb70) at pthread_create.c:301
 #6  0x006c8cbe in clone () at ../sysdeps/unix/sysv/linux/i386/clone.S:133

 Thread 2 (Thread 0xb1dffb70 (LWP 17962)):
 #0  0x00efc416 in __kernel_vsyscall ()
 #1  0x006bdec5 in ppoll (fds=0x847e7b8, nfds=2, timeout=<value optimized
 out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:58
 #2  0x0059dea0 in pa_rtpoll_run (p=0x847e790, wait_op=true) at
 pulsecore/rtpoll.c:304
 #3  0x001d6d86 in thread_func (userdata=0x847e5e0) at modules/alsa/alsa-
 source.c:1274
 #4  0x003bd442 in internal_thread_func (userdata=0x84d8ba8) at pulsecore
 /thread-posix.c:72
 #5  0x00510919 in start_thread (arg=0xb1dffb70) at pthread_create.c:301
 #6  0x006c8cbe in clone () at ../sysdeps/unix/sysv/linux/i386/clone.S:133

 Thread 1 (Thread 0xb7743700 (LWP 17959)):
 #0  0x00efc416 in __kernel_vsyscall ()
 #1  0x006bdec5 in ppoll (fds=0x84700b8, nfds=25, timeout=<value optimized
 out>, sigmask=0x0) at ../sysdeps/unix/sysv/linux/ppoll.c:58
 #2  0x00b8145a in pa_mainloop_poll (m=0x846eb88) at pulse/mainloop.c:887
 #3  0x00b82bf3 in pa_mainloop_iterate (m=0x846eb88, block=1,
 retval=0xbfebab3c) at pulse/mainloop.c:961
 #4  0x00b82cd4 in pa_mainloop_run (m=0x846eb88, retval=0xbfebab3c) at
 pulse/mainloop.c:979
 #5  0x08051fee in main (argc=3, argv=0xbfebac04) at daemon/main.c:971

 And /proc/PID/fds shows:
 lr-x------ 1 mccann mccann 64 Oct 29 10:34 0 -> /dev/null
 l-wx------ 1 mccann mccann 64 Oct 29 10:34 1 -> /dev/null
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 10 -> socket:[620124]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 11 ->
 /home/mccann/.pulse/b3cb73d7e4a06a392b6f1f8d00000015-device-volumes.tdb
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 12 ->
 /home/mccann/.pulse/b3cb73d7e4a06a392b6f1f8d00000015-stream-volumes.tdb
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 13 ->
 /home/mccann/.pulse/b3cb73d7e4a06a392b6f1f8d00000015-card-database.tdb
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 14 -> socket:[619359]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 15 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 16 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 17 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 18 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 19 -> socket:[619349]
 l-wx------ 1 mccann mccann 64 Oct 29 10:34 2 -> /dev/null
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 20 -> /dev/snd/controlC0
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 21 -> socket:[620081]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 22 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 23 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 24 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 25 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 26 -> /dev/snd/controlC0
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 27 -> socket:[620351]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 28 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 29 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 3 -> socket:[619335]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 30 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 31 -> anon_inode:[eventfd]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 32 -> /dev/snd/controlC1
 (deleted)
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 33 -> socket:[620138]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 34 -> socket:[619362]
 lr-x------ 1 mccann mccann 64 Oct 29 10:34 35 -> pipe:[619364]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 36 -> socket:[619423]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 37 -> socket:[622274]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 38 -> socket:[4070034]
 lr-x------ 1 mccann mccann 64 Oct 29 10:34 4 -> pipe:[619346]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 5 -> socket:[619345]
 l-wx------ 1 mccann mccann 64 Oct 29 10:34 6 -> pipe:[619346]
 lrwx------ 1 mccann mccann 64 Oct 29 10:34 7 -> socket:[619426]
 lr-x------ 1 mccann mccann 64 Oct 29 10:34 8 -> pipe:[619348]
 l-wx------ 1 mccann mccann 64 Oct 29 10:34 9 -> pipe:[619348]


 I have no idea if I disconnected any devices or not.  I am currently using
 a different user account.  I have both jmccann and mccann accounts logged
 in and this looping PA is under the inactive session.

 I also probably undocked my laptop and suspended it in this time.

-- 
Ticket URL: <http://pulseaudio.org/ticket/869>
PulseAudio <http://pulseaudio.org/>
The PulseAudio Sound Server
_______________________________________________
pulseaudio-tickets mailing list
pulseaudio-tickets@mail.0pointer.de
https://tango.0pointer.de/mailman/listinfo/pulseaudio-tickets

Reply via email to