Ticket #357: pulseaudio.speex

File pulseaudio.speex, 19.2 KB (added by yar1984, 3 years ago)

log of playing about 60 seconds of 2-channel flac with rhythmbox and pulseaudio-0.9.13 and 48000/2ch/speex-float-3 resampling

Line 
1D: main.c: Started as real root: yes, suid root: no
2I: core-util.c: Successfully gained nice level -11.
3D: main.c: Can realtime: yes, can high-priority: yes
4W: main.c: Running in system mode, but --disallow-exit not set!
5W: main.c: Running in system mode, but --disallow-module-loading not set!
6N: main.c: Running in system mode, forcibly disabling SHM mode!
7N: main.c: Running in system mode, forcibly disabling exit idle time!
8I: main.c: Found user 'pulse' (UID 103) and group 'pulse' (GID 1007).
9I: main.c: Successfully dropped root privileges.
10I: main.c: This is PulseAudio 0.9.13
11D: main.c: Compilation host: x86_64-pc-linux-gnu
12D: main.c: Compilation CFLAGS: -O2 -pipe -march=nocona -mtune=nocona -UNDEBUG -Wall -W -Wextra -pedantic -pipe -Wno-long-long -Wno-overlength-strings -Wconversion -Wundef -Wformat -Wpacked -Wformat-security -Wmissing-include-dirs -Wformat-nonliteral -Wold-style-definition -Wdeclaration-after-statement -Wfloat-equal -Wmissing-declarations -Wmissing-prototypes -Wstrict-prototypes -Wredundant-decls -Wmissing-noreturn -Wshadow -Wendif-labels -Wpointer-arith -Wcast-align -Wwrite-strings -Wno-unused-parameter -ffast-math
13D: main.c: Running on host: Linux x86_64 2.6.27-gentoo-r5 #7 SMP PREEMPT Sun Dec 14 18:05:28 MSK 2008
14I: main.c: Page size is 4096 bytes
15D: main.c: Compiled with Valgrind support: no
16D: main.c: Running in valgrind mode: no
17D: main.c: Optimized build: yes
18I: main.c: Machine ID is da714bc50e1520098eee3ddc49318ff0.
19I: main.c: Using runtime directory /var/run/pulse.
20I: main.c: Using state directory /var/lib/pulse.
21I: main.c: Running in system mode: yes
22I: main.c: Fresh high-resolution timers available! Bon appetit!
23D: memblock.c: Using private memory pool with 1024 slots of size 64,0 KiB each, total size is 64,0 MiB
24D: cli-command.c: Checking for existance of '/usr/lib64/pulse-0.9/modules/module-hal-detect.so': success
25I: module-hal-detect.c: Trying capability alsa
26D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_timer
27D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_3a3e_sound_card_0_alsa_capture_2
28D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_3a3e_sound_card_0_alsa_playback_1
29D: module-hal-detect.c: Loading module-alsa-sink with arguments 'device_id=0 sink_name=alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 tsched=1'
30D: alsa-util.c: Trying front:0...
31D: alsa-util.c: Maximum hw buffer size is 341 ms
32I: module-alsa-sink.c: Successfully opened device front:0.
33I: module-alsa-sink.c: Successfully enabled mmap() mode.
34I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
35I: (alsa-lib)control.c: Invalid CTL front:0
36I: alsa-util.c: Unable to attach to mixer front:0: Нет такого файла или каталога
37I: alsa-util.c: Successfully attached to mixer 'hw:0'
38I: alsa-util.c: Using mixer control "Master".
39I: sink.c: Created sink 0 "alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0" with sample spec s16le 2ch 48000Hz and channel map front-left,front-right
40I: source.c: Created source 0 "alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0.monitor" with sample spec s16le 2ch 48000Hz and channel map front-left,front-right
41I: module-alsa-sink.c: Using 2 fragments of size 32768 bytes, buffer time is 341,33ms
42I: module-alsa-sink.c: Time scheduling watermark is 20,00ms
43D: module-alsa-sink.c: hwbuf_unused_frames=0
44D: module-alsa-sink.c: setting avail_min=61693
45I: module-alsa-sink.c: Volume ranges from 0 to 31.
46I: module-alsa-sink.c: Volume ranges from -46,50 dB to 0,00 dB.
47I: alsa-util.c: ALSA device lacks independant volume controls for each channel.
48I: module-alsa-sink.c: Using hardware volume control. Hardware dB scale supported.
49D: alsa-util.c: snd_pcm_dump():
50D: alsa-util.c: Soft volume PCM
51D: alsa-util.c: Control: PCM Playback Volume
52D: alsa-util.c: min_dB: -51
53D: alsa-util.c: max_dB: 0
54D: alsa-util.c: resolution: 256
55D: alsa-util.c: Its setup is:
56D: alsa-util.c:   stream       : PLAYBACK
57D: alsa-util.c:   access       : MMAP_INTERLEAVED
58D: alsa-util.c:   format       : S16_LE
59D: alsa-util.c:   subformat    : STD
60D: alsa-util.c:   channels     : 2
61D: alsa-util.c:   rate         : 48000
62D: alsa-util.c:   exact rate   : 48000 (48000/1)
63D: alsa-util.c:   msbits       : 16
64D: alsa-util.c:   buffer_size  : 16384
65D: alsa-util.c:   period_size  : 8192
66D: alsa-util.c:   period_time  : 170666
67D: alsa-util.c:   tstamp_mode  : NONE
68D: alsa-util.c:   period_step  : 1
69D: alsa-util.c:   avail_min    : 61693
70D: alsa-util.c:   period_event : 0
71D: alsa-util.c:   start_threshold  : -1
72D: alsa-util.c:   stop_threshold   : -1
73D: alsa-util.c:   silence_threshold: 0
74D: alsa-util.c:   silence_size : 0
75D: alsa-util.c:   boundary     : 4611686018427387904
76D: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
77D: alsa-util.c: Its setup is:
78D: alsa-util.c:   stream       : PLAYBACK
79D: alsa-util.c:   access       : MMAP_INTERLEAVED
80D: alsa-util.c:   format       : S16_LE
81D: alsa-util.c:   subformat    : STD
82D: alsa-util.c:   channels     : 2
83D: alsa-util.c:   rate         : 48000
84D: alsa-util.c:   exact rate   : 48000 (48000/1)
85D: alsa-util.c:   msbits       : 16
86D: alsa-util.c:   buffer_size  : 16384
87D: alsa-util.c:   period_size  : 8192
88D: alsa-util.c:   period_time  : 170666
89D: alsa-util.c:   tstamp_mode  : NONE
90D: alsa-util.c:   period_step  : 1
91D: alsa-util.c:   avail_min    : 61693
92D: alsa-util.c:   period_event : 0
93D: alsa-util.c:   sta
94D: module-alsa-sink.c: Read hardware volume: 0: 100% 1: 100%
95D: module-alsa-sink.c: Thread starting up
96D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
97I: module-alsa-sink.c: Starting playback.
98I: module.c: Loaded "module-alsa-sink" (index: #0; argument: "device_id=0 sink_name=alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 tsched=1").
99D: module-hal-detect.c: Loading module-alsa-source with arguments 'device_id=0 source_name=alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0 tsched=1'
100D: alsa-util.c: Trying front:0...
101D: alsa-util.c: Maximum hw buffer size is 341 ms
102I: module-alsa-source.c: Successfully opened device front:0.
103I: module-alsa-source.c: Successfully enabled mmap() mode.
104I: module-alsa-source.c: Successfully enabled timer-based scheduling mode.
105I: (alsa-lib)control.c: Invalid CTL front:0
106I: alsa-util.c: Unable to attach to mixer front:0: Нет такого файла или каталога
107I: alsa-util.c: Successfully attached to mixer 'hw:0'
108I: alsa-util.c: Using mixer control "Capture".
109I: source.c: Created source 1 "alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0" with sample spec s16le 2ch 48000Hz and channel map front-left,front-right
110I: module-alsa-source.c: Using 2 fragments of size 32768 bytes, buffer time is 341,33ms
111I: module-alsa-source.c: Time scheduling watermark is 20,00ms
112D: module-alsa-source.c: hwbuf_unused_frames=0
113D: module-alsa-source.c: setting avail_min=61693
114I: module-alsa-source.c: Volume ranges from 0 to 31.
115I: module-alsa-source.c: Volume ranges from -16,50 dB to 30,00 dB.
116I: alsa-util.c: All 2 channels can be mapped to mixer channels.
117I: module-alsa-source.c: Using hardware volume control. Hardware dB scale supported.
118D: alsa-util.c: snd_pcm_dump():
119D: alsa-util.c: Soft volume PCM
120D: alsa-util.c: Control: PCM Playback Volume
121D: alsa-util.c: min_dB: -51
122D: alsa-util.c: max_dB: 0
123D: alsa-util.c: resolution: 256
124D: alsa-util.c: Its setup is:
125D: alsa-util.c:   stream       : CAPTURE
126D: alsa-util.c:   access       : MMAP_INTERLEAVED
127D: alsa-util.c:   format       : S16_LE
128D: alsa-util.c:   subformat    : STD
129D: alsa-util.c:   channels     : 2
130D: alsa-util.c:   rate         : 48000
131D: alsa-util.c:   exact rate   : 48000 (48000/1)
132D: alsa-util.c:   msbits       : 16
133D: alsa-util.c:   buffer_size  : 16384
134D: alsa-util.c:   period_size  : 8192
135D: alsa-util.c:   period_time  : 170666
136D: alsa-util.c:   tstamp_mode  : NONE
137D: alsa-util.c:   period_step  : 1
138D: alsa-util.c:   avail_min    : 61693
139D: alsa-util.c:   period_event : 0
140D: alsa-util.c:   start_threshold  : -1
141D: alsa-util.c:   stop_threshold   : -1
142D: alsa-util.c:   silence_threshold: 0
143D: alsa-util.c:   silence_size : 0
144D: alsa-util.c:   boundary     : 4611686018427387904
145D: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
146D: alsa-util.c: Its setup is:
147D: alsa-util.c:   stream       : CAPTURE
148D: alsa-util.c:   access       : MMAP_INTERLEAVED
149D: alsa-util.c:   format       : S16_LE
150D: alsa-util.c:   subformat    : STD
151D: alsa-util.c:   channels     : 2
152D: alsa-util.c:   rate         : 48000
153D: alsa-util.c:   exact rate   : 48000 (48000/1)
154D: alsa-util.c:   msbits       : 16
155D: alsa-util.c:   buffer_size  : 16384
156D: alsa-util.c:   period_size  : 8192
157D: alsa-util.c:   period_time  : 170666
158D: alsa-util.c:   tstamp_mode  : NONE
159D: alsa-util.c:   period_step  : 1
160D: alsa-util.c:   avail_min    : 61693
161D: alsa-util.c:   period_event : 0
162D: alsa-util.c:   start
163D: module-alsa-source.c: Read hardware volume: 0:  72% 1:  72%
164D: module-alsa-source.c: Thread starting up
165D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+28
166I: module.c: Loaded "module-alsa-source" (index: #1; argument: "device_id=0 source_name=alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0 tsched=1").
167D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_3a3e_sound_card_0_alsa_hw_specific_0
168D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_3a3e_sound_card_0_alsa_control__1
169I: module-hal-detect.c: Loaded 2 modules.
170I: module.c: Loaded "module-hal-detect" (index: #2; argument: "").
171D: cli-command.c: Checking for existance of '/usr/lib64/pulse-0.9/modules/module-esound-protocol-unix.so': success
172I: module.c: Loaded "module-esound-protocol-unix" (index: #3; argument: "").
173I: module.c: Loaded "module-native-protocol-unix" (index: #4; argument: "").
174I: module-stream-restore.c: Sucessfully opened database file '/var/lib/pulse/da714bc50e1520098eee3ddc49318ff0:stream-volumes.x86_64-pc-linux-gnu.gdbm'.
175I: module.c: Loaded "module-stream-restore" (index: #5; argument: "").
176I: module-device-restore.c: Sucessfully opened database file '/var/lib/pulse/da714bc50e1520098eee3ddc49318ff0:device-volumes.x86_64-pc-linux-gnu.gdbm'.
177I: module.c: Loaded "module-device-restore" (index: #6; argument: "").
178I: module-default-device-restore.c: Restored default sink 'alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0'.
179D: core-subscribe.c: Dropped redundant event due to change event.
180I: module-default-device-restore.c: Restored default source 'alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0'.
181I: module.c: Loaded "module-default-device-restore" (index: #7; argument: "").
182I: module.c: Loaded "module-rescue-streams" (index: #8; argument: "").
183I: module.c: Loaded "module-always-sink" (index: #9; argument: "").
184D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 becomes idle.
185D: module-suspend-on-idle.c: Source alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0.monitor becomes idle.
186D: module-suspend-on-idle.c: Source alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0 becomes idle.
187I: module.c: Loaded "module-suspend-on-idle" (index: #10; argument: "").
188I: module.c: Loaded "module-console-kit" (index: #11; argument: "").
189I: module.c: Loaded "module-position-event-sounds" (index: #12; argument: "").
190D: cli-command.c: Checking for existance of '/usr/lib64/pulse-0.9/modules/module-gconf.so': success
191I: module.c: Loaded "module-gconf" (index: #13; argument: "").
192I: main.c: Daemon startup complete.
193D: module-hal-detect.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired
194D: module-console-kit.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired
195I: module-suspend-on-idle.c: Source alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0 idle for too long, suspending ...
196I: module-alsa-source.c: Device suspended...
197I: module-suspend-on-idle.c: Source alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0.monitor idle for too long, suspending ...
198I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 idle for too long, suspending ...
199I: module-alsa-sink.c: Device suspended...
200I: client.c: Created 0 "Native client (UNIX socket client)"
201D: protocol-native.c: Protocol version: remote 14, local 14
202I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
203D: protocol-native.c: SHM possible: no
204D: protocol-native.c: Negotiated SHM: no
205I: module-stream-restore.c: Restoring device for stream sink-input-by-application-name:Rhythmbox.
206I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:Rhythmbox.
207D: module-stream-restore.c: Not restoring mute state for sink input sink-input-by-application-name:Rhythmbox, because already set.
208I: module-alsa-sink.c: Trying resume...
209D: alsa-util.c: Maximum hw buffer size is 341 ms
210D: module-alsa-sink.c: hwbuf_unused_frames=0
211D: module-alsa-sink.c: setting avail_min=61693
212I: module-alsa-sink.c: Resumed successfully...
213I: module-alsa-sink.c: Starting playback.
214D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 becomes idle.
215D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 becomes busy.
216I: resampler.c: Using resampler 'speex-float-3'
217I: resampler.c: Using float32le as working format.
218I: resampler.c: Choosing speex quality setting 3.
219D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
220D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
221I: sink-input.c: Created input 0 "Playback Stream" on alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
222I: protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms
223D: protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq.
224D: memblockq.c: memblockq requested: maxlength=70560, tlength=35280, base=4, prebuf=33516, minreq=1764 maxrewind=0
225D: memblockq.c: memblockq sanitized: maxlength=70560, tlength=35280, base=4, prebuf=33516, minreq=1764 maxrewind=0
226I: protocol-native.c: Final latency 380,00 ms = 180,00 ms + 2*10,00 ms + 180,00 ms
227I: module-alsa-sink.c: Underrun!
228N: module-alsa-sink.c: Increasing wakeup watermark to 40,00 ms
229D: module-alsa-sink.c: latency set to 180,00ms
230D: module-alsa-sink.c: hwbuf_unused_frames=7744
231D: module-alsa-sink.c: setting avail_min=34625
232D: module-alsa-sink.c: Requesting rewind due to latency change.
233D: module-alsa-sink.c: Requested to rewind 65536 bytes.
234D: module-alsa-sink.c: Limited to 57824 bytes.
235D: module-alsa-sink.c: before: 14456
236D: module-alsa-sink.c: after: 14456
237D: module-alsa-sink.c: Rewound 57824 bytes.
238D: sink.c: Processing rewind...
239D: sink-input.c: Have to rewind 57824 bytes on render memblockq.
240D: protocol-native.c: Requesting rewind due to end of underrun.
241D: module-alsa-sink.c: Requested to rewind 65532 bytes.
242D: module-alsa-sink.c: Limited to 15904 bytes.
243D: module-alsa-sink.c: before: 3976
244D: module-alsa-sink.c: after: 3976
245D: module-alsa-sink.c: Rewound 15904 bytes.
246D: sink.c: Processing rewind...
247D: sink-input.c: Have to rewind 15904 bytes on render memblockq.
248D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 becomes idle.
249D: module-alsa-sink.c: hwbuf_unused_frames=0
250D: module-alsa-sink.c: setting avail_min=57853
251D: module-alsa-sink.c: Requested to rewind 65536 bytes.
252D: module-alsa-sink.c: Limited to 24736 bytes.
253D: module-alsa-sink.c: before: 6184
254D: module-alsa-sink.c: after: 6184
255D: module-alsa-sink.c: Rewound 24736 bytes.
256D: sink.c: Processing rewind...
257D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0 becomes idle.
258I: sink-input.c: Freeing input 0 "Playback Stream"
259I: client.c: Freed 0 "Rhythmbox"
260I: protocol-native.c: Connection died.
261I: core.c: We are idle, quitting...
262I: main.c: Daemon shutdown initiated.
263I: module.c: Unloading "module-alsa-sink" (index: #0).
264D: module-always-sink.c: Autoloading null-sink as no other sinks detected.
265I: module-device-restore.c: Restoring volume for sink auto_null.
266I: module-device-restore.c: Restoring mute state for sink auto_null.
267I: sink.c: Created sink 1 "auto_null" with sample spec s16le 2ch 48000Hz and channel map front-left,front-right
268I: module-device-restore.c: Restoring volume for source auto_null.monitor.
269I: module-device-restore.c: Restoring mute state for source auto_null.monitor.
270I: source.c: Created source 2 "auto_null.monitor" with sample spec s16le 2ch 48000Hz and channel map front-left,front-right
271D: module-null-sink.c: Thread starting up
272D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+27
273D: module-suspend-on-idle.c: Source auto_null.monitor becomes idle.
274D: module-suspend-on-idle.c: Sink auto_null becomes idle.
275I: module.c: Loaded "module-null-sink" (index: #14; argument: "sink_name=auto_null").
276D: module-rescue-streams.c: No sink inputs to move away.
277D: module-rescue-streams.c: No source outputs to move away.
278D: module-alsa-sink.c: Thread shutting down
279I: sink.c: Freeing sink 0 "alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0"
280I: source.c: Freeing source 0 "alsa_output.pci_8086_3a3e_sound_card_0_alsa_playback_0.monitor"
281I: module.c: Unloaded "module-alsa-sink" (index: #0).
282I: module.c: Unloading "module-alsa-source" (index: #1).
283D: module-rescue-streams.c: No source outputs to move away.
284D: module-alsa-source.c: Thread shutting down
285I: source.c: Freeing source 1 "alsa_input.pci_8086_3a3e_sound_card_0_alsa_capture_0"
286I: module.c: Unloaded "module-alsa-source" (index: #1).
287I: module.c: Unloading "module-hal-detect" (index: #2).
288I: module.c: Unloaded "module-hal-detect" (index: #2).
289I: module.c: Unloading "module-esound-protocol-unix" (index: #3).
290I: module.c: Unloaded "module-esound-protocol-unix" (index: #3).
291I: module.c: Unloading "module-native-protocol-unix" (index: #4).
292I: module.c: Unloaded "module-native-protocol-unix" (index: #4).
293I: module.c: Unloading "module-stream-restore" (index: #5).
294I: module.c: Unloaded "module-stream-restore" (index: #5).
295I: module.c: Unloading "module-device-restore" (index: #6).
296I: module.c: Unloaded "module-device-restore" (index: #6).
297I: module.c: Unloading "module-default-device-restore" (index: #7).
298I: module.c: Unloaded "module-default-device-restore" (index: #7).
299I: module.c: Unloading "module-rescue-streams" (index: #8).
300I: module.c: Unloaded "module-rescue-streams" (index: #8).
301I: module.c: Unloading "module-always-sink" (index: #9).
302I: module.c: Unloaded "module-always-sink" (index: #9).
303I: module.c: Unloading "module-suspend-on-idle" (index: #10).
304I: module.c: Unloaded "module-suspend-on-idle" (index: #10).
305I: module.c: Unloading "module-console-kit" (index: #11).
306I: module.c: Unloaded "module-console-kit" (index: #11).
307I: module.c: Unloading "module-position-event-sounds" (index: #12).
308I: module.c: Unloaded "module-position-event-sounds" (index: #12).
309I: module.c: Unloading "module-gconf" (index: #13).
310I: module.c: Unloaded "module-gconf" (index: #13).
311I: module.c: Unloading "module-null-sink" (index: #14).
312D: core-subscribe.c: Dropped redundant event due to remove event.
313D: core-subscribe.c: Dropped redundant event due to remove event.
314D: module-null-sink.c: Thread shutting down
315I: sink.c: Freeing sink 1 "auto_null"
316I: source.c: Freeing source 2 "auto_null.monitor"
317I: module.c: Unloaded "module-null-sink" (index: #14).
318D: core-subscribe.c: Dropped redundant event due to remove event.
319I: main.c: Daemon terminated.