Ticket #190: ladspa_trace.txt

File ladspa_trace.txt, 15.1 kB (added by tanuk, 5 months ago)

pulseaudio crash backtrace

Line 
1 tanu@a9a:~$ LANG=C gdb --args pulseaudio -vv -n -F /home/tanu/.pulse/default.pa-alsa
2 GNU gdb 6.7.1-debian
3 Copyright (C) 2007 Free Software Foundation, Inc.
4 License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
5 This is free software: you are free to change and redistribute it.
6 There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
7 and "show warranty" for details.
8 This GDB was configured as "i486-linux-gnu"...
9 Using 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
11 Signal        Stop  Print   Pass to program Description
12 SIGXCPU       No    No  Yes     CPU time limit exceeded
13 SIGPWR        No    No  Yes     Power fail/restart
14 SIG33         No    No  Yes     Real-time event 33
15 SIG34         No    No  Yes     Real-time event 34
16 SIG42         No    No  Yes     Real-time event 42
17 SIG43         No    No  Yes     Real-time event 43
18 SIG44         No    No  Yes     Real-time event 44
19 SIG45         No    No  Yes     Real-time event 45
20 SIG46         No    No  Yes     Real-time event 46
21 SIG47         No    No  Yes     Real-time event 47
22 SIG48         No    No  Yes     Real-time event 48
23 SIG49         No    No  Yes     Real-time event 49
24 SIG50         No    No  Yes     Real-time event 50
25 SIG51         No    No  Yes     Real-time event 51
26 SIG52         No    No  Yes     Real-time event 52
27 SIG53         No    No  Yes     Real-time event 53
28 SIG58         No    No  Yes     Real-time event 58
29 SIG59         No    No  Yes     Real-time event 59
30 SIG60         No    No  Yes     Real-time event 60
31 SIG61         No    No  Yes     Real-time event 61
32 SIG62         No    No  Yes     Real-time event 62
33 SIG63         No    No  Yes     Real-time event 63
34 (gdb) run
35 Starting program: /home/tanu/roinaa/bin/pulseaudio -vv -n -F /home/tanu/.pulse/default.pa-alsa
36 [Thread debugging using libthread_db enabled]
37 I: main.c: setrlimit(RLIMIT_RTTIME, (1000000, 1000000)) failed: Invalid argument
38 I: core-util.c: Successfully acquired nice level -9, which is lower than the requested -11.
39 I: main.c: This is PulseAudio 0.9.11
40 I: main.c: Page size is 4096 bytes
41 I: main.c: Using runtime directory /home/tanu/.pulse.
42 I: main.c: Running in system mode: no
43 W: pid.c: Stale PID file, overwriting.
44 I: main.c: Fresh high-resolution timers available! Bon appetit!
45 [New Thread 0xb7768940 (LWP 13979)]
46 I: protocol-native.c: loading cookie from disk.
47 I: module.c: Loaded "module-native-protocol-unix" (index: #0; argument: "").
48 I: module.c: Loaded "module-esound-protocol-unix" (index: #1; argument: "").
49 D: alsa-util.c: Maximum hw buffer size is 371 ms
50 I: module-alsa-sink.c: Successfully opened device hw:T71Space.
51 I: module-alsa-sink.c: Successfully enabled mmap() mode.
52 I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
53 I: alsa-util.c: Successfully attached to mixer 'hw:T71Space'
54 I: alsa-util.c: Using mixer control "Master".
55 I: sink.c: Created sink 0 "direct" with sample spec s32le 4ch 44100Hz and channel map front-left,front-right,rear-left,rear-right
56 I: source.c: Created source 0 "direct.monitor" with sample spec s32le 4ch 44100Hz and channel map front-left,front-right,rear-left,rear-right
57 I: module-alsa-sink.c: Using 2 fragments of size 131072 bytes, buffer time is 371.52ms
58 I: module-alsa-sink.c: Time scheduling watermark is 20.00ms
59 D: module-alsa-sink.c: hwbuf_unused_frames=0
60 D: module-alsa-sink.c: setting avail_min=248017
61 I: alsa-util.c: ALSA device lacks separate volumes control for channel 'rear-left', falling back to software volume control.
62 D: alsa-util.c: snd_pcm_dump():
63 D: alsa-util.c: Hardware PCM card 2 'Terratec Aureon 7.1-Space' device 0 subdevice 0
64 D: alsa-util.c: Its setup is:
65 D: alsa-util.c:   stream       : PLAYBACK
66 D: alsa-util.c:   access       : MMAP_INTERLEAVED
67 D: alsa-util.c:   format       : S32_LE
68 D: alsa-util.c:   subformat    : STD
69 D: alsa-util.c:   channels     : 4
70 D: alsa-util.c:   rate         : 44100
71 D: alsa-util.c:   exact rate   : 44100 (44100/1)
72 D: alsa-util.c:   msbits       : 24
73 D: alsa-util.c:   buffer_size  : 16384
74 D: alsa-util.c:   period_size  : 8192
75 D: alsa-util.c:   period_time  : 185759
76 D: alsa-util.c:   tstamp_mode  : NONE
77 D: alsa-util.c:   period_step  : 1
78 D: alsa-util.c:   avail_min    : 248017
79 D: alsa-util.c:   start_threshold  : -1
80 D: alsa-util.c:   stop_threshold   : -1
81 D: alsa-util.c:   silence_threshold: 0
82 D: alsa-util.c:   silence_size : 0
83 D: alsa-util.c:   boundary     : 1073741824
84 [New Thread 0xb3726b90 (LWP 13982)]
85 D: module-alsa-sink.c: Thread starting up
86 I: core-util.c: Successfully enabled SCHED_FIFO scheduling for thread, with priority 65.
87 D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
88 I: module-alsa-sink.c: Starting playback.
89 I: 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").
90 D: module-suspend-on-idle.c: Sink direct becomes idle.
91 D: module-suspend-on-idle.c: Source direct.monitor becomes idle.
92 I: module.c: Loaded "module-suspend-on-idle" (index: #3; argument: "").
93 D: memblockq.c: memblockq requested: maxlength=16777216, tlength=0, base=8, prebuf=1, minreq=1 maxrewind=0
94 D: memblockq.c: memblockq sanitized: maxlength=16777216, tlength=16777216, base=8, prebuf=8, minreq=8 maxrewind=0
95 D: module-ladspa-sink.c: Module: mbeq_1197
96 D: module-ladspa-sink.c: Label: mbeq
97 D: module-ladspa-sink.c: Unique ID: 1197
98 D: module-ladspa-sink.c: Name: Multiband EQ
99 D: module-ladspa-sink.c: Maker: Steve Harris <steve@plugin.org.uk>
100 D: module-ladspa-sink.c: Copyright: GPL
101 D: module-ladspa-sink.c: Ignored control output port "latency".
102 D: module-ladspa-sink.c: Binding 11.621622 to port 50Hz gain (low shelving)
103 D: module-ladspa-sink.c: Binding 10.000000 to port 100Hz gain
104 D: module-ladspa-sink.c: Binding 4.594594 to port 156Hz gain
105 D: module-ladspa-sink.c: Binding 2.702703 to port 220Hz gain
106 D: module-ladspa-sink.c: Binding 0.000000 to port 311Hz gain
107 D: module-ladspa-sink.c: Binding 0.000000 to port 440Hz gain
108 D: module-ladspa-sink.c: Binding -1.621622 to port 622Hz gain
109 D: module-ladspa-sink.c: Binding -0.270270 to port 880Hz gain
110 D: module-ladspa-sink.c: Binding -5.405406 to port 1250Hz gain
111 D: module-ladspa-sink.c: Binding -3.513514 to port 1750Hz gain
112 D: module-ladspa-sink.c: Binding -8.648648 to port 2500Hz gain
113 D: module-ladspa-sink.c: Binding -5.675676 to port 3500Hz gain
114 D: module-ladspa-sink.c: Binding -4.054054 to port 5000Hz gain
115 D: module-ladspa-sink.c: Binding 1.351351 to port 10000Hz gain
116 D: module-ladspa-sink.c: Binding 9.189189 to port 20000Hz gain
117 I: sink.c: Created sink 1 "headphones_eq" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
118 I: source.c: Created source 1 "headphones_eq.monitor" with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
119 D: module-suspend-on-idle.c: Sink direct becomes busy.
120 I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
121 D: resampler.c: Channel matrix:
122 D: resampler.c:        I00   I01
123 D: resampler.c:     +------------
124 D: resampler.c: O00 | 1.000 0.000
125 D: resampler.c: O01 | 0.000 1.000
126 D: resampler.c: O02 | 1.000 0.000
127 D: resampler.c: O03 | 0.000 1.000
128 I: resampler.c: Using resampler 'copy'
129 I: resampler.c: Using float32le as working format.
130 D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=16, prebuf=0, minreq=1 maxrewind=0
131 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=16, prebuf=0, minreq=16 maxrewind=0
132 I: sink-input.c: Created input 0 "LADSPA Stream" on direct with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
133 D: module-suspend-on-idle.c: Source headphones_eq.monitor becomes idle.
134 D: module-suspend-on-idle.c: Sink headphones_eq becomes idle.
135 D: module-alsa-sink.c: hwbuf_unused_frames=0
136 D: module-alsa-sink.c: setting avail_min=248017
137 D: module-alsa-sink.c: hwbuf_unused_frames=0
138 D: module-alsa-sink.c: setting avail_min=248017
139 D: module-ladspa-sink.c: Requesting rewind due to state change.
140 D: module-alsa-sink.c: hwbuf_unused_frames=0
141 D: module-alsa-sink.c: setting avail_min=248017
142 D: module-alsa-sink.c: Requested to rewind 262144 bytes.
143 D: module-alsa-sink.c: Limited to 193472 bytes.
144 D: module-alsa-sink.c: before: 12092
145 D: module-alsa-sink.c: after: 12092
146 D: module-alsa-sink.c: Rewound 193472 bytes.
147 D: sink.c: Processing rewind...
148 D: sink-input.c: Have to rewind 193472 bytes on render memblockq.
149 D: source.c: Processing rewind...
150 I: 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").
151 I: module.c: Loaded "module-match" (index: #5; argument: "").
152 I: main.c: Daemon startup complete.
153 I: module-suspend-on-idle.c: Source direct.monitor idle for too long, suspending ...
154 I: module-suspend-on-idle.c: Sink headphones_eq idle for too long, suspending ...
155 D: module-alsa-sink.c: Requested to rewind 262144 bytes.
156 D: module-alsa-sink.c: Limited to 242752 bytes.
157 D: module-alsa-sink.c: before: 15172
158 D: module-alsa-sink.c: after: 15172
159 D: module-alsa-sink.c: Rewound 242752 bytes.
160 D: sink.c: Processing rewind...
161 D: sink-input.c: Have to rewind 242752 bytes on render memblockq.
162 D: sink-input.c: Have to rewind 121376 bytes on implementor.
163 D: module-suspend-on-idle.c: Sink direct becomes idle.
164 I: module-suspend-on-idle.c: Source headphones_eq.monitor idle for too long, suspending ...
165 I: module-suspend-on-idle.c: Sink direct idle for too long, suspending ...
166 W: pcm_hw.c: SNDRV_PCM_IOCTL_DRAIN failed
167 I: module-alsa-sink.c: Device suspended...
168 I: client.c: Created 0 "Native client (UNIX socket client)"
169 D: protocol-native.c: Protocol version: remote 13, local 13
170 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
171 D: protocol-native.c: SHM possible: yes
172 D: protocol-native.c: Negotiated SHM: yes
173 I: client.c: Freed 0 "Totem-elokuvasoitin"
174 I: protocol-native.c: connection died.
175 I: client.c: Created 1 "Native client (UNIX socket client)"
176 D: protocol-native.c: Protocol version: remote 13, local 13
177 I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
178 D: protocol-native.c: SHM possible: yes
179 D: protocol-native.c: Negotiated SHM: yes
180 I: module-alsa-sink.c: Trying resume...
181 D: alsa-util.c: Maximum hw buffer size is 371 ms
182 D: module-alsa-sink.c: hwbuf_unused_frames=0
183 D: module-alsa-sink.c: setting avail_min=248017
184 I: module-alsa-sink.c: Resumed successfully...
185 I: module-alsa-sink.c: Starting playback.
186 D: module-suspend-on-idle.c: Sink direct becomes busy.
187 D: module-suspend-on-idle.c: Sink headphones_eq becomes idle.
188 D: module-suspend-on-idle.c: Sink headphones_eq becomes busy.
189 D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=8, prebuf=0, minreq=1 maxrewind=0
190 D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=8, prebuf=0, minreq=8 maxrewind=0
191 I: sink-input.c: Created input 1 "Playback Stream" on headphones_eq with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
192 I: protocol-native.c: Requested tlength=200.00 ms, minreq=10.00 ms
193 D: memblockq.c: memblockq requested: maxlength=141120, tlength=70560, base=8, prebuf=67032, minreq=3528 maxrewind=0
194 D: memblockq.c: memblockq sanitized: maxlength=141120, tlength=70560, base=8, prebuf=67032, minreq=3528 maxrewind=0
195 I: protocol-native.c: Final latency 380.00 ms = 180.00 ms + 2*10.00 ms + 180.00 ms
196 D: module-alsa-sink.c: latency set to 180.00
197 D: module-alsa-sink.c: hwbuf_unused_frames=8446
198 D: module-alsa-sink.c: setting avail_min=121343
199 D: module-alsa-sink.c: Requesting rewind due to latency change.
200 D: module-alsa-sink.c: Requested to rewind 262144 bytes.
201 D: module-alsa-sink.c: Limited to 247392 bytes.
202 D: module-alsa-sink.c: before: 15462
203 D: module-alsa-sink.c: after: 15462
204 D: module-alsa-sink.c: Rewound 247392 bytes.
205 D: sink.c: Processing rewind...
206 D: sink-input.c: Have to rewind 247392 bytes on render memblockq.
207 D: module-match.c: changing volume of sink input 'Playback Stream' to 0xc000
208 D: core-subscribe.c: dropped redundant event.
209 D: protocol-native.c: Requesting rewind due to end of underrun.
210 D: protocol-native.c: Requesting rewind due to end of underrun.
211 E: 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
213 Program received signal SIGABRT, Aborted.
214 [Switching to Thread 0xb3726b90 (LWP 13982)]
215 0xffffe410 in __kernel_vsyscall ()
216 (gdb) thread apply all bt
217
218 Thread 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
236 Thread 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)