Ticket #190: ladspa_trace.txt

File ladspa_trace.txt, 15.1 KB (added by tanuk, 4 years ago)

pulseaudio crash backtrace

Line 
1tanu@a9a:~$ LANG=C gdb --args pulseaudio -vv -n -F /home/tanu/.pulse/default.pa-alsa
2GNU gdb 6.7.1-debian
3Copyright (C) 2007 Free Software Foundation, Inc.
4License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
5This is free software: you are free to change and redistribute it.
6There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
7and "show warranty" for details.
8This GDB was configured as "i486-linux-gnu"...
9Using host libthread_db library "/lib/i686/cmov/libthread_db.so.1".
10(gdb) handle SIGXCPU SIGPWR SIG33 SIG34 SIG42 SIG43 SIG44 SIG45 SIG46 SIG47 SIG48 SIG49 SIG50 SIG51 SIG52 SIG53 SIG63 SIG62 SIG61 SIG60 SIG59 SIG58 nostop noprint
11Signal        Stop  Print   Pass to program Description
12SIGXCPU       No    No  Yes     CPU time limit exceeded
13SIGPWR        No    No  Yes     Power fail/restart
14SIG33         No    No  Yes     Real-time event 33
15SIG34         No    No  Yes     Real-time event 34
16SIG42         No    No  Yes     Real-time event 42
17SIG43         No    No  Yes     Real-time event 43
18SIG44         No    No  Yes     Real-time event 44
19SIG45         No    No  Yes     Real-time event 45
20SIG46         No    No  Yes     Real-time event 46
21SIG47         No    No  Yes     Real-time event 47
22SIG48         No    No  Yes     Real-time event 48
23SIG49         No    No  Yes     Real-time event 49
24SIG50         No    No  Yes     Real-time event 50
25SIG51         No    No  Yes     Real-time event 51
26SIG52         No    No  Yes     Real-time event 52
27SIG53         No    No  Yes     Real-time event 53
28SIG58         No    No  Yes     Real-time event 58
29SIG59         No    No  Yes     Real-time event 59
30SIG60         No    No  Yes     Real-time event 60
31SIG61         No    No  Yes     Real-time event 61
32SIG62         No    No  Yes     Real-time event 62
33SIG63         No    No  Yes     Real-time event 63
34(gdb) run
35Starting program: /home/tanu/roinaa/bin/pulseaudio -vv -n -F /home/tanu/.pulse/default.pa-alsa
36[Thread debugging using libthread_db enabled]
37I: main.c: setrlimit(RLIMIT_RTTIME, (1000000, 1000000)) failed: Invalid argument
38I: core-util.c: Successfully acquired nice level -9, which is lower than the requested -11.
39I: main.c: This is PulseAudio 0.9.11
40I: main.c: Page size is 4096 bytes
41I: main.c: Using runtime directory /home/tanu/.pulse.
42I: main.c: Running in system mode: no
43W: pid.c: Stale PID file, overwriting.
44I: main.c: Fresh high-resolution timers available! Bon appetit!
45[New Thread 0xb7768940 (LWP 13979)]
46I: protocol-native.c: loading cookie from disk.
47I: module.c: Loaded "module-native-protocol-unix" (index: #0; argument: "").
48I: module.c: Loaded "module-esound-protocol-unix" (index: #1; argument: "").
49D: alsa-util.c: Maximum hw buffer size is 371 ms
50I: module-alsa-sink.c: Successfully opened device hw:T71Space.
51I: module-alsa-sink.c: Successfully enabled mmap() mode.
52I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
53I: alsa-util.c: Successfully attached to mixer 'hw:T71Space'
54I: alsa-util.c: Using mixer control "Master".
55I: sink.c: Created sink 0 "direct" with sample spec s32le 4ch 44100Hz and channel map front-left,front-right,rear-left,rear-right
56I: source.c: Created source 0 "direct.monitor" with sample spec s32le 4ch 44100Hz and channel map front-left,front-right,rear-left,rear-right
57I: module-alsa-sink.c: Using 2 fragments of size 131072 bytes, buffer time is 371.52ms
58I: module-alsa-sink.c: Time scheduling watermark is 20.00ms
59D: module-alsa-sink.c: hwbuf_unused_frames=0
60D: module-alsa-sink.c: setting avail_min=248017
61I: alsa-util.c: ALSA device lacks separate volumes control for channel 'rear-left', falling back to software volume control.
62D: alsa-util.c: snd_pcm_dump():
63D: alsa-util.c: Hardware PCM card 2 'Terratec Aureon 7.1-Space' device 0 subdevice 0
64D: alsa-util.c: Its setup is:
65D: alsa-util.c:   stream       : PLAYBACK
66D: alsa-util.c:   access       : MMAP_INTERLEAVED
67D: alsa-util.c:   format       : S32_LE
68D: alsa-util.c:   subformat    : STD
69D: alsa-util.c:   channels     : 4
70D: alsa-util.c:   rate         : 44100
71D: alsa-util.c:   exact rate   : 44100 (44100/1)
72D: alsa-util.c:   msbits       : 24
73D: alsa-util.c:   buffer_size  : 16384
74D: alsa-util.c:   period_size  : 8192
75D: alsa-util.c:   period_time  : 185759
76D: alsa-util.c:   tstamp_mode  : NONE
77D: alsa-util.c:   period_step  : 1
78D: alsa-util.c:   avail_min    : 248017
79D: alsa-util.c:   start_threshold  : -1
80D: alsa-util.c:   stop_threshold   : -1
81D: alsa-util.c:   silence_threshold: 0
82D: alsa-util.c:   silence_size : 0
83D: alsa-util.c:   boundary     : 1073741824
84[New Thread 0xb3726b90 (LWP 13982)]
85D: module-alsa-sink.c: Thread starting up
86I: core-util.c: Successfully enabled SCHED_FIFO scheduling for thread, with priority 65.
87D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
88I: module-alsa-sink.c: Starting playback.
89I: module.c: Loaded "module-alsa-sink" (index: #2; argument: "sink_name=direct device=hw:T71Space channels=4 channel_map=front-left,front-right,rear-left,rear-right rate=44100 format=s32le").
90D: module-suspend-on-idle.c: Sink direct becomes idle.
91D: module-suspend-on-idle.c: Source direct.monitor becomes idle.
92I: module.c: Loaded "module-suspend-on-idle" (index: #3; argument: "").
93D: memblockq.c: memblockq requested: maxlength=16777216, tlength=0, base=8, prebuf=1, minreq=1 maxrewind=0
94D: memblockq.c: memblockq sanitized: maxlength=16777216, tlength=16777216, base=8, prebuf=8, minreq=8 maxrewind=0
95D: module-ladspa-sink.c: Module: mbeq_1197
96D: module-ladspa-sink.c: Label: mbeq
97D: module-ladspa-sink.c: Unique ID: 1197
98D: module-ladspa-sink.c: Name: Multiband EQ
99D: module-ladspa-sink.c: Maker: Steve Harris <steve@plugin.org.uk>
100D: module-ladspa-sink.c: Copyright: GPL
101D: module-ladspa-sink.c: Ignored control output port "latency".
102D: module-ladspa-sink.c: Binding 11.621622 to port 50Hz gain (low shelving)
103D: module-ladspa-sink.c: Binding 10.000000 to port 100Hz gain
104D: module-ladspa-sink.c: Binding 4.594594 to port 156Hz gain
105D: module-ladspa-sink.c: Binding 2.702703 to port 220Hz gain
106D: module-ladspa-sink.c: Binding 0.000000 to port 311Hz gain
107D: module-ladspa-sink.c: Binding 0.000000 to port 440Hz gain
108D: module-ladspa-sink.c: Binding -1.621622 to port 622Hz gain
109D: module-ladspa-sink.c: Binding -0.270270 to port 880Hz gain
110D: module-ladspa-sink.c: Binding -5.405406 to port 1250Hz gain
111D: module-ladspa-sink.c: Binding -3.513514 to port 1750Hz gain
112D: module-ladspa-sink.c: Binding -8.648648 to port 2500Hz gain
113D: module-ladspa-sink.c: Binding -5.675676 to port 3500Hz gain
114D: module-ladspa-sink.c: Binding -4.054054 to port 5000Hz gain
115D: module-ladspa-sink.c: Binding 1.351351 to port 10000Hz gain
116D: module-ladspa-sink.c: Binding 9.189189 to port 20000Hz gain
117I: sink.c: Created sink 1 "headphones_eq" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
118I: source.c: Created source 1 "headphones_eq.monitor" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
119D: module-suspend-on-idle.c: Sink direct becomes busy.
120I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
121D: resampler.c: Channel matrix:
122D: resampler.c:        I00   I01
123D: resampler.c:     +------------
124D: resampler.c: O00 | 1.000 0.000
125D: resampler.c: O01 | 0.000 1.000
126D: resampler.c: O02 | 1.000 0.000
127D: resampler.c: O03 | 0.000 1.000
128I: resampler.c: Using resampler 'copy'
129I: resampler.c: Using float32le as working format.
130D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=16, prebuf=0, minreq=1 maxrewind=0
131D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=16, prebuf=0, minreq=16 maxrewind=0
132I: sink-input.c: Created input 0 "LADSPA Stream" on direct with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
133D: module-suspend-on-idle.c: Source headphones_eq.monitor becomes idle.
134D: module-suspend-on-idle.c: Sink headphones_eq becomes idle.
135D: module-alsa-sink.c: hwbuf_unused_frames=0
136D: module-alsa-sink.c: setting avail_min=248017
137D: module-alsa-sink.c: hwbuf_unused_frames=0
138D: module-alsa-sink.c: setting avail_min=248017
139D: module-ladspa-sink.c: Requesting rewind due to state change.
140D: module-alsa-sink.c: hwbuf_unused_frames=0
141D: module-alsa-sink.c: setting avail_min=248017
142D: module-alsa-sink.c: Requested to rewind 262144 bytes.
143D: module-alsa-sink.c: Limited to 193472 bytes.
144D: module-alsa-sink.c: before: 12092
145D: module-alsa-sink.c: after: 12092
146D: module-alsa-sink.c: Rewound 193472 bytes.
147D: sink.c: Processing rewind...
148D: sink-input.c: Have to rewind 193472 bytes on render memblockq.
149D: source.c: Processing rewind...
150I: module.c: Loaded "module-ladspa-sink" (index: #4; argument: "sink_name=headphones_eq channel_map=front-left,front-right channels=2 master=direct plugin=mbeq_1197 label=mbeq control=11.621622,10,4.594594,2.702703,0,0,-1.621622,-0.270270,-5.405406,-3.513514,-8.648648,-5.675676,-4.054054,1.351351,9.189189").
151I: module.c: Loaded "module-match" (index: #5; argument: "").
152I: main.c: Daemon startup complete.
153I: module-suspend-on-idle.c: Source direct.monitor idle for too long, suspending ...
154I: module-suspend-on-idle.c: Sink headphones_eq idle for too long, suspending ...
155D: module-alsa-sink.c: Requested to rewind 262144 bytes.
156D: module-alsa-sink.c: Limited to 242752 bytes.
157D: module-alsa-sink.c: before: 15172
158D: module-alsa-sink.c: after: 15172
159D: module-alsa-sink.c: Rewound 242752 bytes.
160D: sink.c: Processing rewind...
161D: sink-input.c: Have to rewind 242752 bytes on render memblockq.
162D: sink-input.c: Have to rewind 121376 bytes on implementor.
163D: module-suspend-on-idle.c: Sink direct becomes idle.
164I: module-suspend-on-idle.c: Source headphones_eq.monitor idle for too long, suspending ...
165I: module-suspend-on-idle.c: Sink direct idle for too long, suspending ...
166W: pcm_hw.c: SNDRV_PCM_IOCTL_DRAIN failed
167I: module-alsa-sink.c: Device suspended...
168I: client.c: Created 0 "Native client (UNIX socket client)"
169D: protocol-native.c: Protocol version: remote 13, local 13
170I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
171D: protocol-native.c: SHM possible: yes
172D: protocol-native.c: Negotiated SHM: yes
173I: client.c: Freed 0 "Totem-elokuvasoitin"
174I: protocol-native.c: connection died.
175I: client.c: Created 1 "Native client (UNIX socket client)"
176D: protocol-native.c: Protocol version: remote 13, local 13
177I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
178D: protocol-native.c: SHM possible: yes
179D: protocol-native.c: Negotiated SHM: yes
180I: module-alsa-sink.c: Trying resume...
181D: alsa-util.c: Maximum hw buffer size is 371 ms
182D: module-alsa-sink.c: hwbuf_unused_frames=0
183D: module-alsa-sink.c: setting avail_min=248017
184I: module-alsa-sink.c: Resumed successfully...
185I: module-alsa-sink.c: Starting playback.
186D: module-suspend-on-idle.c: Sink direct becomes busy.
187D: module-suspend-on-idle.c: Sink headphones_eq becomes idle.
188D: module-suspend-on-idle.c: Sink headphones_eq becomes busy.
189D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=8, prebuf=0, minreq=1 maxrewind=0
190D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=8, prebuf=0, minreq=8 maxrewind=0
191I: sink-input.c: Created input 1 "Playback Stream" on headphones_eq with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
192I: protocol-native.c: Requested tlength=200.00 ms, minreq=10.00 ms
193D: memblockq.c: memblockq requested: maxlength=141120, tlength=70560, base=8, prebuf=67032, minreq=3528 maxrewind=0
194D: memblockq.c: memblockq sanitized: maxlength=141120, tlength=70560, base=8, prebuf=67032, minreq=3528 maxrewind=0
195I: protocol-native.c: Final latency 380.00 ms = 180.00 ms + 2*10.00 ms + 180.00 ms
196D: module-alsa-sink.c: latency set to 180.00
197D: module-alsa-sink.c: hwbuf_unused_frames=8446
198D: module-alsa-sink.c: setting avail_min=121343
199D: module-alsa-sink.c: Requesting rewind due to latency change.
200D: module-alsa-sink.c: Requested to rewind 262144 bytes.
201D: module-alsa-sink.c: Limited to 247392 bytes.
202D: module-alsa-sink.c: before: 15462
203D: module-alsa-sink.c: after: 15462
204D: module-alsa-sink.c: Rewound 247392 bytes.
205D: sink.c: Processing rewind...
206D: sink-input.c: Have to rewind 247392 bytes on render memblockq.
207D: module-match.c: changing volume of sink input 'Playback Stream' to 0xc000
208D: core-subscribe.c: dropped redundant event.
209D: protocol-native.c: Requesting rewind due to end of underrun.
210D: protocol-native.c: Requesting rewind due to end of underrun.
211E: sink-input.c: Assertion 'i->thread_info.rewrite_nbytes == 0' failed at pulsecore/sink-input.c:1144, function pa_sink_input_request_rewind(). Aborting.
212
213Program received signal SIGABRT, Aborted.
214[Switching to Thread 0xb3726b90 (LWP 13982)]
2150xffffe410 in __kernel_vsyscall ()
216(gdb) thread apply all bt
217
218Thread 2 (Thread 0xb3726b90 (LWP 13982)):
219#0  0xffffe410 in __kernel_vsyscall ()
220#1  0xb7914ef5 in raise () from /lib/i686/cmov/libc.so.6
221#2  0xb7916871 in abort () from /lib/i686/cmov/libc.so.6
222#3  0xb7d7db3a in pa_sink_input_request_rewind (i=0x80c0488, nbytes=4294967295, rewrite=false,
223    flush=true) at pulsecore/sink-input.c:1144
224#4  0xb7ec370d in handle_seek (s=0x809f350, indexw=67032) at pulsecore/protocol-native.c:1123
225#5  0xb7ec39ea in sink_input_process_msg (o=0x80c0488, code=7, userdata=0x0, offset=0, chunk=0xb37261cc)
226    at pulsecore/protocol-native.c:1181
227#6  0xb7d8ccf3 in pa_asyncmsgq_dispatch (object=0x80c0488, code=7, userdata=0x0, offset=0,
228    memchunk=0xb37261cc) at pulsecore/asyncmsgq.c:316
229#7  0xb7d93569 in asyncmsgq_read_work (i=0x8072398) at pulsecore/rtpoll.c:698
230#8  0xb7d91cf1 in pa_rtpoll_run (p=0x8071310, wait=true) at pulsecore/rtpoll.c:326
231#9  0xb7e016e9 in thread_func (userdata=0x8071200) at modules/module-alsa-sink.c:1036
232#10 0xb7d9ad52 in internal_thread_func (userdata=0x807b0a8) at pulsecore/thread-posix.c:71
233#11 0xb7a3d4fb in start_thread () from /lib/i686/cmov/libpthread.so.0
234#12 0xb79c0d7e in clone () from /lib/i686/cmov/libc.so.6
235
236Thread 1 (Thread 0xb7768940 (LWP 13979)):
237#0  0xffffe410 in __kernel_vsyscall ()
238#1  0xb7a4491b in write () from /lib/i686/cmov/libpthread.so.0
239#2  0xb7d8f18e in pa_fdsem_post (f=0x8071a20) at pulsecore/fdsem.c:218
240#3  0xb7d8d335 in push (l=0x80715d8, p=0x80a6a90, wait=false) at pulsecore/asyncq.c:161
241#4  0xb7d8d668 in pa_asyncq_push (l=0x80715d8, p=0x80a6a90, wait=false) at pulsecore/asyncq.c:193
242#5  0xb7d8d776 in pa_asyncq_post (l=0x80715d8, p=0x80a6a90) at pulsecore/asyncq.c:202
243#6  0xb7d8be9e in pa_asyncmsgq_post (a=0x8070bf0, object=0x80c0488, code=7, userdata=0x0, offset=0,
244    chunk=0xbff9aa58, free_cb=0) at pulsecore/asyncmsgq.c:137
245#7  0xb7ece1b9 in pstream_memblock_callback (p=0x80b0ed0, channel=0, offset=0, seek=PA_SEEK_RELATIVE,
246    chunk=0xbff9aa58, userdata=0x80ac728) at pulsecore/protocol-native.c:3835
247#8  0xb7f01354 in do_read (p=0x80b0ed0) at pulsecore/pstream.c:851
248#9  0xb7efe6d9 in do_something (p=0x80b0ed0) at pulsecore/pstream.c:184
249#10 0xb7efe8fe in io_callback (io=0x80a6850, userdata=0x80b0ed0) at pulsecore/pstream.c:213
250#11 0xb7f1843b in callback (m=0x8069ae4, e=0x80a9480, fd=9, f=PA_IO_EVENT_INPUT, userdata=0x80a6850)
251    at pulsecore/iochannel.c:119
252#12 0xb7d329f4 in dispatch_pollfds (m=0x8069aa0) at pulse/mainloop.c:677
253#13 0xb7d336d5 in pa_mainloop_dispatch (m=0x8069aa0) at pulse/mainloop.c:890
254#14 0xb7d3386a in pa_mainloop_iterate (m=0x8069aa0, block=1, retval=0xbff9ad14) at pulse/mainloop.c:920
255#15 0xb7d338d4 in pa_mainloop_run (m=0x8069aa0, retval=0xbff9ad14) at pulse/mainloop.c:935
256#16 0x0805dc78 in main (argc=5, argv=0xbff9ae04) at daemon/main.c:873
257#0  0xffffe410 in __kernel_vsyscall ()
258(gdb)