Ticket #334: pulseaudio.txt

File pulseaudio.txt, 37.0 KB (added by dennisj, 4 years ago)

pulseaudio -vvv log

Line 
1D: main.c: Started as real root: no, suid root: yes
2I: main.c: We're in the group 'pulse-rt', allowing high-priority scheduling.
3I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not permitted
4I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted
5I: core-util.c: Successfully gained nice level -11.
6I: main.c: Giving up CAP_NICE
7D: main.c: Can realtime: no, can high-priority: no
8I: main.c: This is PulseAudio 0.9.11
9I: main.c: Page size is 4096 bytes
10I: main.c: Using runtime directory /home/dennis/.pulse.
11I: main.c: Running in system mode: no
12I: main.c: Fresh high-resolution timers available! Bon appetit!
13D: alsa-util.c: Maximum hw buffer size is 148 ms
14I: module-alsa-sink.c: Successfully opened device plug:front.
15I: module-alsa-sink.c: Successfully enabled mmap() mode.
16I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
17W: control.c: Invalid CTL plug:front
18I: alsa-util.c: Unable to attach to mixer plug:front: No such file or directory
19I: alsa-util.c: Successfully attached to mixer 'hw:0'
20I: alsa-util.c: Cannot find mixer control "Master".
21W: alsa-util.c: Cannot find fallback mixer control "PCM".
22I: sink.c: Created sink 0 "alsa_output.plug_front" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
23I: source.c: Created source 0 "alsa_output.plug_front.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
24I: module-alsa-sink.c: Using 2 fragments of size 13104 bytes, buffer time is 148.57ms
25I: module-alsa-sink.c: Time scheduling watermark is 20.00ms
26D: module-alsa-sink.c: hwbuf_unused_frames=0
27D: module-alsa-sink.c: setting avail_min=22677
28D: alsa-util.c: snd_pcm_dump():
29D: alsa-util.c: Plug PCM: Route conversion PCM (sformat=S32_LE)
30D: alsa-util.c:   Transformation table:
31D: alsa-util.c:     0 <- 0
32D: alsa-util.c:     1 <- 1
33D: alsa-util.c:     2 <- none
34D: alsa-util.c:     3 <- none
35D: alsa-util.c:     4 <- none
36D: alsa-util.c:     5 <- none
37D: alsa-util.c:     6 <- none
38D: alsa-util.c:     7 <- none
39D: alsa-util.c:     8 <- none
40D: alsa-util.c:     9 <- none
41D: alsa-util.c: Its setup is:
42D: alsa-util.c:   stream       : PLAYBACK
43D: alsa-util.c:   access       : MMAP_INTERLEAVED
44D: alsa-util.c:   format       : S16_LE
45D: alsa-util.c:   subformat    : STD
46D: alsa-util.c:   channels     : 2
47D: alsa-util.c:   rate         : 44100
48D: alsa-util.c:   exact rate   : 44100 (44100/1)
49D: alsa-util.c:   msbits       : 16
50D: alsa-util.c:   buffer_size  : 6552
51D: alsa-util.c:   period_size  : 3276
52D: alsa-util.c:   period_time  : 74285
53D: alsa-util.c:   tstamp_mode  : NONE
54D: alsa-util.c:   period_step  : 1
55D: alsa-util.c:   avail_min    : 22677
56D: alsa-util.c:   period_event : 0
57D: alsa-util.c:   start_threshold  : -1
58D: alsa-util.c:   stop_threshold   : -1
59D: alsa-util.c:   silence_threshold: 0
60D: alsa-util.c:   silence_size : 0
61D: alsa-util.c:   boundary     : 1717567488
62D: alsa-util.c: Slave: Route conversion PCM
63D: alsa-util.c:   Transformation table:
64D: alsa-util.c:     0 <- 0
65D: alsa-util.c:     1 <- 1
66D: alsa-util.c: Its setup is:
67D: alsa-util.c:   stream       : PLAYBACK
68D: alsa-util.c:   access       : MMAP_INTERLEAVED
69D: alsa-util.c:   format       : S32_LE
70D: alsa-util.c:   subformat    : STD
71D: alsa-util.c:   channels     : 10
72D: alsa-util.c:   rate         : 44100
73D: alsa-util.c:   exact rate   : 44100 (44100/1)
74D: alsa-util.c:   msbits       : 32
75D: alsa-util.c:   buffer_size  : 6552
76D: alsa-util.c:   period
77D: module-alsa-sink.c: Thread starting up
78D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
79I: module-alsa-sink.c: Starting playback.
80I: module.c: Loaded "module-alsa-sink" (index: #0; argument: "device=plug:front channels=2 channel_map=front-left,front-right").
81D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9/modules/module-hal-detect.so': success
82I: module-hal-detect.c: Trying capability alsa
83D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_timer
84D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_sequencer
85D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_10de_3f0_sound_card_0_alsa_capture_2
86D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_10de_3f0_sound_card_0_alsa_playback_1
87D: module-hal-detect.c: Loading module-alsa-sink with arguments 'device_id=1 sink_name=alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0'
88D: alsa-util.c: Trying front:1...
89D: alsa-util.c: Maximum hw buffer size is 371 ms
90I: module-alsa-sink.c: Successfully opened device front:1.
91I: module-alsa-sink.c: Successfully enabled mmap() mode.
92I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
93W: control.c: Invalid CTL front:1
94I: alsa-util.c: Unable to attach to mixer front:1: No such file or directory
95I: alsa-util.c: Successfully attached to mixer 'hw:1'
96I: alsa-util.c: Using mixer control "Master".
97I: sink.c: Created sink 1 "alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
98I: source.c: Created source 1 "alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
99I: module-alsa-sink.c: Using 2 fragments of size 32768 bytes, buffer time is 371.52ms
100I: module-alsa-sink.c: Time scheduling watermark is 20.00ms
101D: module-alsa-sink.c: hwbuf_unused_frames=0
102D: module-alsa-sink.c: setting avail_min=62005
103I: alsa-util.c: ALSA device lacks independant volume controls for each channel, falling back to software volume control.
104D: alsa-util.c: snd_pcm_dump():
105D: alsa-util.c: Hardware PCM card 1 'HDA NVidia' device 0 subdevice 0
106D: alsa-util.c: Its setup is:
107D: alsa-util.c:   stream       : PLAYBACK
108D: alsa-util.c:   access       : MMAP_INTERLEAVED
109D: alsa-util.c:   format       : S16_LE
110D: alsa-util.c:   subformat    : STD
111D: alsa-util.c:   channels     : 2
112D: alsa-util.c:   rate         : 44100
113D: alsa-util.c:   exact rate   : 44100 (44100/1)
114D: alsa-util.c:   msbits       : 16
115D: alsa-util.c:   buffer_size  : 16384
116D: alsa-util.c:   period_size  : 8192
117D: alsa-util.c:   period_time  : 185759
118D: alsa-util.c:   tstamp_mode  : NONE
119D: alsa-util.c:   period_step  : 1
120D: alsa-util.c:   avail_min    : 62005
121D: alsa-util.c:   period_event : 0
122D: alsa-util.c:   start_threshold  : -1
123D: alsa-util.c:   stop_threshold   : -1
124D: alsa-util.c:   silence_threshold: 0
125D: alsa-util.c:   silence_size : 0
126D: alsa-util.c:   boundary     : 1073741824
127D: module-alsa-sink.c: Thread starting up
128D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+28
129I: module-alsa-sink.c: Starting playback.
130I: module.c: Loaded "module-alsa-sink" (index: #1; argument: "device_id=1 sink_name=alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0").
131D: module-hal-detect.c: Loading module-alsa-source with arguments 'device_id=1 source_name=alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0'
132D: alsa-util.c: Trying front:1...
133D: alsa-util.c: Maximum hw buffer size is 371 ms
134I: module-alsa-source.c: Successfully opened device front:1.
135I: module-alsa-source.c: Successfully enabled mmap() mode.
136I: module-alsa-source.c: Successfully enabled timer-based scheduling mode.
137W: control.c: Invalid CTL front:1
138I: alsa-util.c: Unable to attach to mixer front:1: No such file or directory
139I: alsa-util.c: Successfully attached to mixer 'hw:1'
140I: alsa-util.c: Using mixer control "Capture".
141I: source.c: Created source 2 "alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
142I: module-alsa-source.c: Using 2 fragments of size 32768 bytes, buffer time is 371.52ms
143I: module-alsa-source.c: Time scheduling watermark is 20.00ms
144D: module-alsa-source.c: hwbuf_unused_frames=0
145D: module-alsa-source.c: setting avail_min=62005
146I: alsa-util.c: All 2 channels can be mapped to mixer channels.
147I: module-alsa-source.c: Volume ranges from 0 to 31.
148I: module-alsa-source.c: Volume ranges from -16.50 dB to 30.00 dB.
149I: module-alsa-source.c: Device cannot attenuate for more than -60 dB (only -16.50 dB supported), falling back to software volume control.
150I: module-alsa-source.c: Using software volume control. Trying to reset sound card to 0 dB.
151D: alsa-util.c: snd_pcm_dump():
152D: alsa-util.c: Hardware PCM card 1 'HDA NVidia' device 0 subdevice 0
153D: alsa-util.c: Its setup is:
154D: alsa-util.c:   stream       : CAPTURE
155D: alsa-util.c:   access       : MMAP_INTERLEAVED
156D: alsa-util.c:   format       : S16_LE
157D: alsa-util.c:   subformat    : STD
158D: alsa-util.c:   channels     : 2
159D: alsa-util.c:   rate         : 44100
160D: alsa-util.c:   exact rate   : 44100 (44100/1)
161D: alsa-util.c:   msbits       : 16
162D: alsa-util.c:   buffer_size  : 16384
163D: alsa-util.c:   period_size  : 8192
164D: alsa-util.c:   period_time  : 185759
165D: alsa-util.c:   tstamp_mode  : NONE
166D: alsa-util.c:   period_step  : 1
167D: alsa-util.c:   avail_min    : 62005
168D: alsa-util.c:   period_event : 0
169D: alsa-util.c:   start_threshold  : -1
170D: alsa-util.c:   stop_threshold   : -1
171D: alsa-util.c:   silence_threshold: 0
172D: alsa-util.c:   silence_size : 0
173D: alsa-util.c:   boundary     : 1073741824
174D: module-alsa-source.c: Thread starting up
175D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+27
176I: module.c: Loaded "module-alsa-source" (index: #2; argument: "device_id=1 source_name=alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0").
177D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_10de_3f0_sound_card_0_alsa_hw_specific_0
178D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_10de_3f0_sound_card_0_alsa_control__1
179D: module-hal-detect.c: Loading module-alsa-sink with arguments 'device_id=0 sink_name=alsa_output.pci_1412_1712_sound_card_0_alsa_playback_0'
180D: alsa-util.c: Trying front:0...
181W: pcm_hw.c: open /dev/snd/pcmC0D0p failed
182I: alsa-util.c: Couldn't open PCM device front:0: Device or resource busy
183D: alsa-util.c: Trying surround40:0...
184W: pcm_hw.c: open /dev/snd/pcmC0D0p failed
185I: alsa-util.c: Couldn't open PCM device surround40:0: Device or resource busy
186D: alsa-util.c: Trying surround41:0...
187W: pcm_hw.c: open /dev/snd/pcmC0D0p failed
188I: alsa-util.c: Couldn't open PCM device surround41:0: Device or resource busy
189D: alsa-util.c: Trying surround50:0...
190W: pcm_hw.c: open /dev/snd/pcmC0D0p failed
191I: alsa-util.c: Couldn't open PCM device surround50:0: Device or resource busy
192D: alsa-util.c: Trying surround51:0...
193W: pcm_hw.c: open /dev/snd/pcmC0D0p failed
194I: alsa-util.c: Couldn't open PCM device surround51:0: Device or resource busy
195D: alsa-util.c: Trying surround71:0...
196W: confmisc.c: Unable to find definition 'cards.ICE1712.pcm.surround71.0:CARD=0'
197W: conf.c: function snd_func_refer returned error: No such file or directory
198W: conf.c: Evaluate error: No such file or directory
199W: pcm.c: Unknown PCM surround71:0
200I: alsa-util.c: Couldn't open PCM device surround71:0: No such file or directory
201D: alsa-util.c: Trying plughw:0 as last resort...
202W: pcm_hw.c: open /dev/snd/pcmC0D0p failed
203E: alsa-util.c: Error opening PCM device plughw:0: Device or resource busy
204E: module.c: Failed to load  module "module-alsa-sink" (argument: "device_id=0 sink_name=alsa_output.pci_1412_1712_sound_card_0_alsa_playback_0"): initialization failed.
205D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_1412_1712_sound_card_0_alsa_playback_0
206D: module-hal-detect.c: Loading module-alsa-source with arguments 'device_id=0 source_name=alsa_input.pci_1412_1712_sound_card_0_alsa_capture_0'
207D: alsa-util.c: Trying front:0...
208I: alsa-util.c: PCM device front:0 refused our hw parameters: Invalid argument
209D: alsa-util.c: Trying surround40:0...
210I: alsa-util.c: PCM device surround40:0 refused our hw parameters: Invalid argument
211D: alsa-util.c: Trying surround41:0...
212I: alsa-util.c: PCM device surround41:0 refused our hw parameters: Invalid argument
213D: alsa-util.c: Trying surround50:0...
214I: alsa-util.c: PCM device surround50:0 refused our hw parameters: Invalid argument
215D: alsa-util.c: Trying surround51:0...
216I: alsa-util.c: PCM device surround51:0 refused our hw parameters: Invalid argument
217D: alsa-util.c: Trying surround71:0...
218W: confmisc.c: Unable to find definition 'cards.ICE1712.pcm.surround71.0:CARD=0'
219W: conf.c: function snd_func_refer returned error: No such file or directory
220W: conf.c: Evaluate error: No such file or directory
221W: pcm.c: Unknown PCM surround71:0
222I: alsa-util.c: Couldn't open PCM device surround71:0: No such file or directory
223D: alsa-util.c: Trying plughw:0 as last resort...
224D: alsa-util.c: Maximum hw buffer size is 123 ms
225I: module-alsa-source.c: Successfully opened device plughw:0.
226I: module-alsa-source.c: Successfully enabled mmap() mode.
227I: module-alsa-source.c: Successfully enabled timer-based scheduling mode.
228W: control.c: Invalid CTL plughw:0
229I: alsa-util.c: Unable to attach to mixer plughw:0: No such file or directory
230I: alsa-util.c: Successfully attached to mixer 'hw:0'
231I: alsa-util.c: Cannot find mixer control "Capture".
232W: alsa-util.c: Cannot find fallback mixer control "Mic".
233I: source.c: Created source 3 "alsa_input.pci_1412_1712_sound_card_0_alsa_capture_0" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
234I: module-alsa-source.c: Using 2 fragments of size 10920 bytes, buffer time is 123.81ms
235I: module-alsa-source.c: Time scheduling watermark is 20.00ms
236D: module-alsa-source.c: hwbuf_unused_frames=0
237D: module-alsa-source.c: setting avail_min=18309
238D: alsa-util.c: snd_pcm_dump():
239D: alsa-util.c: Plug PCM: Route conversion PCM (sformat=S32_LE)
240D: alsa-util.c:   Transformation table:
241D: alsa-util.c:     0 <- 0
242D: alsa-util.c:     1 <- 1
243D: alsa-util.c: Its setup is:
244D: alsa-util.c:   stream       : CAPTURE
245D: alsa-util.c:   access       : MMAP_INTERLEAVED
246D: alsa-util.c:   format       : S16_LE
247D: alsa-util.c:   subformat    : STD
248D: alsa-util.c:   channels     : 2
249D: alsa-util.c:   rate         : 44100
250D: alsa-util.c:   exact rate   : 44100 (44100/1)
251D: alsa-util.c:   msbits       : 16
252D: alsa-util.c:   buffer_size  : 5460
253D: alsa-util.c:   period_size  : 2730
254D: alsa-util.c:   period_time  : 61904
255D: alsa-util.c:   tstamp_mode  : NONE
256D: alsa-util.c:   period_step  : 1
257D: alsa-util.c:   avail_min    : 18309
258D: alsa-util.c:   period_event : 0
259D: alsa-util.c:   start_threshold  : -1
260D: alsa-util.c:   stop_threshold   : -1
261D: alsa-util.c:   silence_threshold: 0
262D: alsa-util.c:   silence_size : 0
263D: alsa-util.c:   boundary     : 1431306240
264D: alsa-util.c: Slave: Hardware PCM card 0 'M Audio Audiophile 24/96' device 0 subdevice 0
265D: alsa-util.c: Its setup is:
266D: alsa-util.c:   stream       : CAPTURE
267D: alsa-util.c:   access       : MMAP_INTERLEAVED
268D: alsa-util.c:   format       : S32_LE
269D: alsa-util.c:   subformat    : STD
270D: alsa-util.c:   channels     : 12
271D: alsa-util.c:   rate         : 44100
272D: alsa-util.c:   exact rate   : 44100 (44100/1)
273D: alsa-util.c:   msbits       : 24
274D: alsa-util.c:   buffer_size  : 5460
275D: alsa-util.c:   period_size  : 2730
276D: alsa-util.c:   period_time  : 61904
277D: alsa-util.c:   tstamp_mode  : NONE
278D: alsa-util.c:   period_step  : 1
279D: alsa-util.c:   avail_min    : 18309
280D: alsa-util.c:   period_eve
281D: module-alsa-source.c: Thread starting up
282D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+26
283I: module.c: Loaded "module-alsa-source" (index: #3; argument: "device_id=0 source_name=alsa_input.pci_1412_1712_sound_card_0_alsa_capture_0").
284D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_1412_1712_sound_card_0_alsa_midi_0
285D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_1412_1712_sound_card_0_alsa_control__1
286I: module-hal-detect.c: Loaded 3 modules.
287I: module.c: Loaded "module-hal-detect" (index: #4; argument: "").
288D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9/modules/module-esound-protocol-unix.so': success
289I: module.c: Loaded "module-esound-protocol-unix" (index: #5; argument: "").
290I: protocol-native.c: loading cookie from disk.
291I: module.c: Loaded "module-native-protocol-unix" (index: #6; argument: "").
292I: module.c: Loaded "module-volume-restore" (index: #7; argument: "").
293I: module-default-device-restore.c: Restored default sink 'alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0'.
294D: core-subscribe.c: dropped redundant event.
295I: module-default-device-restore.c: Restored default source 'alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0'.
296I: module.c: Loaded "module-default-device-restore" (index: #8; argument: "").
297I: module.c: Loaded "module-rescue-streams" (index: #9; argument: "").
298I: module.c: Loaded "module-always-sink" (index: #10; argument: "").
299D: module-suspend-on-idle.c: Sink alsa_output.plug_front becomes idle.
300D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 becomes idle.
301D: module-suspend-on-idle.c: Source alsa_output.plug_front.monitor becomes idle.
302D: module-suspend-on-idle.c: Source alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0.monitor becomes idle.
303D: module-suspend-on-idle.c: Source alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0 becomes idle.
304D: module-suspend-on-idle.c: Source alsa_input.pci_1412_1712_sound_card_0_alsa_capture_0 becomes idle.
305I: module.c: Loaded "module-suspend-on-idle" (index: #11; argument: "").
306I: client.c: Created 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session5"
307D: module-console-kit.c: Added new session /org/freedesktop/ConsoleKit/Session5
308I: module.c: Loaded "module-console-kit" (index: #12; argument: "").
309I: module.c: Loaded "module-position-event-sounds" (index: #13; argument: "").
310D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9/modules/module-gconf.so': success
311I: module.c: Loaded "module-gconf" (index: #14; argument: "").
312I: main.c: Daemon startup complete.
313D: module-hal-detect.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired
314D: module-console-kit.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired
315I: module-suspend-on-idle.c: Source alsa_input.pci_1412_1712_sound_card_0_alsa_capture_0 idle for too long, suspending ...
316I: module-alsa-source.c: Device suspended...
317I: module-suspend-on-idle.c: Source alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0 idle for too long, suspending ...
318I: module-alsa-source.c: Device suspended...
319I: module-suspend-on-idle.c: Source alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0.monitor idle for too long, suspending ...
320I: module-suspend-on-idle.c: Source alsa_output.plug_front.monitor idle for too long, suspending ...
321I: module-suspend-on-idle.c: Sink alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 idle for too long, suspending ...
322W: pcm_hw.c: SNDRV_PCM_IOCTL_DRAIN failed
323I: module-alsa-sink.c: Device suspended...
324I: module-suspend-on-idle.c: Sink alsa_output.plug_front idle for too long, suspending ...
325W: pcm_hw.c: SNDRV_PCM_IOCTL_DRAIN failed
326I: module-alsa-sink.c: Device suspended...
327I: client.c: Created 1 "Native client (UNIX socket client)"
328D: protocol-native.c: Protocol version: remote 13, local 13
329I: protocol-native.c: Got credentials: uid=500 gid=500 success=1
330D: protocol-native.c: SHM possible: yes
331D: protocol-native.c: Negotiated SHM: yes
332I: module-volume-restore.c: Restoring sink for <pulsecore/protocol-native.c$Audacious>
333I: module-volume-restore.c: Restoring volume for <pulsecore/protocol-native.c$Audacious>
334I: module-alsa-sink.c: Trying resume...
335D: alsa-util.c: Maximum hw buffer size is 148 ms
336D: module-alsa-sink.c: hwbuf_unused_frames=0
337D: module-alsa-sink.c: setting avail_min=22677
338I: module-alsa-sink.c: Resumed successfully...
339D: module-suspend-on-idle.c: Sink alsa_output.plug_front becomes idle.
340D: module-suspend-on-idle.c: Sink alsa_output.plug_front becomes busy.
341D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
342D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
343I: sink-input.c: Created input 0 "05 - Feeder - Shade.mp3" on alsa_output.plug_front with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
344I: protocol-native.c: Requested tlength=250.00 ms, minreq=20.00 ms
345D: memblockq.c: memblockq requested: maxlength=4194304, tlength=44100, base=4, prebuf=44100, minreq=3528 maxrewind=0
346D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=44100, base=4, prebuf=44100, minreq=3528 maxrewind=0
347I: protocol-native.c: Final latency 398.57 ms = 210.00 ms + 2*20.00 ms + 148.57 ms
348I: module-alsa-sink.c: Starting playback.
349D: module-alsa-sink.c: latency set to 148.57
350D: module-alsa-sink.c: hwbuf_unused_frames=1
351D: module-alsa-sink.c: setting avail_min=22678
352D: module-alsa-sink.c: Requesting rewind due to latency change.
353D: module-alsa-sink.c: Requested to rewind 26208 bytes.
354D: module-alsa-sink.c: Limited to 22628 bytes.
355D: module-alsa-sink.c: before: 5657
356D: module-alsa-sink.c: after: 5657
357D: module-alsa-sink.c: Rewound 22628 bytes.
358D: sink.c: Processing rewind...
359D: sink-input.c: Have to rewind 22628 bytes on render memblockq.
360D: protocol-native.c: Requesting rewind due to end of underrun.
361D: module-alsa-sink.c: Requested to rewind 26208 bytes.
362D: module-alsa-sink.c: Limited to 19260 bytes.
363D: module-alsa-sink.c: before: 4815
364D: module-alsa-sink.c: after: 4815
365D: module-alsa-sink.c: Rewound 19260 bytes.
366D: sink.c: Processing rewind...
367D: sink-input.c: Have to rewind 19260 bytes on render memblockq.
368D: protocol-native.c: Requesting rewind due to end of underrun.
369D: module-alsa-sink.c: Requested to rewind 21084 bytes.
370D: module-alsa-sink.c: Limited to 19760 bytes.
371D: module-alsa-sink.c: before: 4940
372D: module-alsa-sink.c: after: 4940
373D: module-alsa-sink.c: Rewound 19760 bytes.
374D: sink.c: Processing rewind...
375D: sink-input.c: Have to rewind 19760 bytes on render memblockq.
376D: protocol-native.c: Requesting rewind due to end of underrun.
377D: module-alsa-sink.c: Requested to rewind 26208 bytes.
378D: module-alsa-sink.c: Limited to 19980 bytes.
379D: module-alsa-sink.c: before: 4995
380D: module-alsa-sink.c: after: 4995
381D: module-alsa-sink.c: Rewound 19980 bytes.
382D: sink.c: Processing rewind...
383D: sink-input.c: Have to rewind 19980 bytes on render memblockq.
384D: protocol-native.c: Requesting rewind due to end of underrun.
385D: module-alsa-sink.c: Requested to rewind 21776 bytes.
386D: module-alsa-sink.c: Limited to 19696 bytes.
387D: module-alsa-sink.c: before: 4924
388D: module-alsa-sink.c: after: 4924
389D: module-alsa-sink.c: Rewound 19696 bytes.
390D: sink.c: Processing rewind...
391D: sink-input.c: Have to rewind 19696 bytes on render memblockq.
392D: protocol-native.c: Requesting rewind due to end of underrun.
393D: module-alsa-sink.c: Requested to rewind 26208 bytes.
394D: module-alsa-sink.c: Limited to 19924 bytes.
395D: module-alsa-sink.c: before: 4981
396D: module-alsa-sink.c: after: 4981
397D: module-alsa-sink.c: Rewound 19924 bytes.
398D: sink.c: Processing rewind...
399D: sink-input.c: Have to rewind 19924 bytes on render memblockq.
400D: protocol-native.c: Requesting rewind due to end of underrun.
401D: module-alsa-sink.c: Requested to rewind 22232 bytes.
402D: module-alsa-sink.c: Limited to 19440 bytes.
403D: module-alsa-sink.c: before: 4860
404D: module-alsa-sink.c: after: 4860
405D: module-alsa-sink.c: Rewound 19440 bytes.
406D: sink.c: Processing rewind...
407D: sink-input.c: Have to rewind 19440 bytes on render memblockq.
408D: protocol-native.c: Requesting rewind due to end of underrun.
409D: module-alsa-sink.c: Requested to rewind 26208 bytes.
410D: module-alsa-sink.c: Limited to 21220 bytes.
411D: module-alsa-sink.c: before: 5305
412D: module-alsa-sink.c: after: 5305
413D: module-alsa-sink.c: Rewound 21220 bytes.
414D: sink.c: Processing rewind...
415D: sink-input.c: Have to rewind 21220 bytes on render memblockq.
416D: protocol-native.c: Requesting rewind due to end of underrun.
417D: module-alsa-sink.c: Requested to rewind 22340 bytes.
418D: module-alsa-sink.c: Limited to 20832 bytes.
419D: module-alsa-sink.c: before: 5208
420D: module-alsa-sink.c: after: 5208
421D: module-alsa-sink.c: Rewound 20832 bytes.
422D: sink.c: Processing rewind...
423D: sink-input.c: Have to rewind 20832 bytes on render memblockq.
424D: protocol-native.c: Requesting rewind due to end of underrun.
425D: module-alsa-sink.c: Requested to rewind 26208 bytes.
426D: module-alsa-sink.c: Limited to 20984 bytes.
427D: module-alsa-sink.c: before: 5246
428D: module-alsa-sink.c: after: 5246
429D: module-alsa-sink.c: Rewound 20984 bytes.
430D: sink.c: Processing rewind...
431D: sink-input.c: Have to rewind 20984 bytes on render memblockq.
432D: protocol-native.c: Requesting rewind due to end of underrun.
433D: module-alsa-sink.c: Requested to rewind 23472 bytes.
434D: module-alsa-sink.c: Limited to 20676 bytes.
435D: module-alsa-sink.c: before: 5169
436D: module-alsa-sink.c: after: 5169
437D: module-alsa-sink.c: Rewound 20676 bytes.
438D: sink.c: Processing rewind...
439D: sink-input.c: Have to rewind 20676 bytes on render memblockq.
440D: protocol-native.c: Requesting rewind due to end of underrun.
441D: module-alsa-sink.c: Requested to rewind 26208 bytes.
442D: module-alsa-sink.c: Limited to 22484 bytes.
443D: module-alsa-sink.c: before: 5621
444D: module-alsa-sink.c: after: 5621
445D: module-alsa-sink.c: Rewound 22484 bytes.
446D: sink.c: Processing rewind...
447D: sink-input.c: Have to rewind 22484 bytes on render memblockq.
448D: protocol-native.c: Requesting rewind due to end of underrun.
449D: module-alsa-sink.c: Requested to rewind 23588 bytes.
450D: module-alsa-sink.c: Limited to 21368 bytes.
451D: module-alsa-sink.c: before: 5342
452D: module-alsa-sink.c: after: 5342
453D: module-alsa-sink.c: Rewound 21368 bytes.
454D: sink.c: Processing rewind...
455D: sink-input.c: Have to rewind 21368 bytes on render memblockq.
456D: protocol-native.c: Requesting rewind due to end of underrun.
457D: module-alsa-sink.c: Requested to rewind 26208 bytes.
458D: module-alsa-sink.c: Limited to 22512 bytes.
459D: module-alsa-sink.c: before: 5628
460D: module-alsa-sink.c: after: 5628
461D: module-alsa-sink.c: Rewound 22512 bytes.
462D: sink.c: Processing rewind...
463D: sink-input.c: Have to rewind 22512 bytes on render memblockq.
464D: protocol-native.c: Requesting rewind due to end of underrun.
465D: module-alsa-sink.c: Requested to rewind 24344 bytes.
466D: module-alsa-sink.c: Limited to 22052 bytes.
467D: module-alsa-sink.c: before: 5513
468D: module-alsa-sink.c: after: 5513
469D: module-alsa-sink.c: Rewound 22052 bytes.
470D: sink.c: Processing rewind...
471D: sink-input.c: Have to rewind 22052 bytes on render memblockq.
472D: protocol-native.c: Requesting rewind due to end of underrun.
473D: module-alsa-sink.c: Requested to rewind 26208 bytes.
474D: module-alsa-sink.c: Limited to 22512 bytes.
475D: module-alsa-sink.c: before: 5628
476D: module-alsa-sink.c: after: 5628
477D: module-alsa-sink.c: Rewound 22512 bytes.
478D: sink.c: Processing rewind...
479D: sink-input.c: Have to rewind 22512 bytes on render memblockq.
480D: protocol-native.c: Requesting rewind due to end of underrun.
481D: module-alsa-sink.c: Requested to rewind 23836 bytes.
482D: module-alsa-sink.c: Limited to 21964 bytes.
483D: module-alsa-sink.c: before: 5491
484D: module-alsa-sink.c: after: 5491
485D: module-alsa-sink.c: Rewound 21964 bytes.
486D: sink.c: Processing rewind...
487D: sink-input.c: Have to rewind 21964 bytes on render memblockq.
488D: protocol-native.c: Requesting rewind due to end of underrun.
489D: module-alsa-sink.c: Requested to rewind 26208 bytes.
490D: module-alsa-sink.c: Limited to 22528 bytes.
491D: module-alsa-sink.c: before: 5632
492D: module-alsa-sink.c: after: 5632
493D: module-alsa-sink.c: Rewound 22528 bytes.
494D: sink.c: Processing rewind...
495D: sink-input.c: Have to rewind 22528 bytes on render memblockq.
496D: protocol-native.c: Requesting rewind due to end of underrun.
497D: module-alsa-sink.c: Requested to rewind 24352 bytes.
498D: module-alsa-sink.c: Limited to 21040 bytes.
499D: module-alsa-sink.c: before: 5260
500D: module-alsa-sink.c: after: 5260
501D: module-alsa-sink.c: Rewound 21040 bytes.
502D: sink.c: Processing rewind...
503D: sink-input.c: Have to rewind 21040 bytes on render memblockq.
504D: protocol-native.c: Requesting rewind due to end of underrun.
505D: module-alsa-sink.c: Requested to rewind 26208 bytes.
506D: module-alsa-sink.c: Limited to 22528 bytes.
507D: module-alsa-sink.c: before: 5632
508D: module-alsa-sink.c: after: 5632
509D: module-alsa-sink.c: Rewound 22528 bytes.
510D: sink.c: Processing rewind...
511D: sink-input.c: Have to rewind 22528 bytes on render memblockq.
512D: protocol-native.c: Requesting rewind due to end of underrun.
513D: module-alsa-sink.c: Requested to rewind 23844 bytes.
514D: module-alsa-sink.c: Limited to 22276 bytes.
515D: module-alsa-sink.c: before: 5569
516D: module-alsa-sink.c: after: 5569
517D: module-alsa-sink.c: Rewound 22276 bytes.
518D: sink.c: Processing rewind...
519D: sink-input.c: Have to rewind 22276 bytes on render memblockq.
520D: protocol-native.c: Requesting rewind due to end of underrun.
521D: module-alsa-sink.c: Requested to rewind 26208 bytes.
522D: module-alsa-sink.c: Limited to 22512 bytes.
523D: module-alsa-sink.c: before: 5628
524D: module-alsa-sink.c: after: 5628
525D: module-alsa-sink.c: Rewound 22512 bytes.
526D: sink.c: Processing rewind...
527D: sink-input.c: Have to rewind 22512 bytes on render memblockq.
528D: protocol-native.c: Requesting rewind due to end of underrun.
529D: module-alsa-sink.c: Requested to rewind 24352 bytes.
530D: module-alsa-sink.c: Limited to 20952 bytes.
531D: module-alsa-sink.c: before: 5238
532D: module-alsa-sink.c: after: 5238
533D: module-alsa-sink.c: Rewound 20952 bytes.
534D: sink.c: Processing rewind...
535D: sink-input.c: Have to rewind 20952 bytes on render memblockq.
536D: protocol-native.c: Requesting rewind due to end of underrun.
537D: module-alsa-sink.c: Requested to rewind 26208 bytes.
538D: module-alsa-sink.c: Limited to 22532 bytes.
539D: module-alsa-sink.c: before: 5633
540D: module-alsa-sink.c: after: 5633
541D: module-alsa-sink.c: Rewound 22532 bytes.
542D: sink.c: Processing rewind...
543D: sink-input.c: Have to rewind 22532 bytes on render memblockq.
544^CI: main.c: Got signal SIGINT.
545I: main.c: Exiting.
546I: main.c: Daemon shutdown initiated.
547I: module.c: Unloading "module-alsa-sink" (index: #0).
548I: module-alsa-sink.c: Trying resume...
549D: alsa-util.c: Maximum hw buffer size is 371 ms
550D: module-alsa-sink.c: hwbuf_unused_frames=0
551D: module-alsa-sink.c: setting avail_min=62005
552I: module-alsa-sink.c: Resumed successfully...
553D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 becomes idle.
554D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 becomes busy.
555D: module-suspend-on-idle.c: Sink alsa_output.plug_front becomes idle.
556D: sink-input.c: Have to rewind 13000 bytes on render memblockq.
557D: sink-input.c: Have to rewind 13000 bytes on implementor.
558I: module-alsa-sink.c: Starting playback.
559D: module-alsa-sink.c: hwbuf_unused_frames=0
560D: module-alsa-sink.c: setting avail_min=22677
561D: sink.c: Requesting rewind due to started move
562D: module-alsa-sink.c: Requested to rewind 26208 bytes.
563D: module-alsa-sink.c: Limited to 17476 bytes.
564D: module-alsa-sink.c: before: 4369
565D: module-alsa-sink.c: after: 4369
566D: module-alsa-sink.c: Rewound 17476 bytes.
567D: sink.c: Processing rewind...
568D: module-suspend-on-idle.c: Sink alsa_output.plug_front becomes idle.
569I: module-alsa-sink.c: Underrun!
570N: module-alsa-sink.c: Increasing wakeup watermark to 40.00 ms
571D: module-alsa-sink.c: latency set to 148.57
572D: module-alsa-sink.c: hwbuf_unused_frames=9833
573D: module-alsa-sink.c: setting avail_min=28982
574D: module-alsa-sink.c: Requesting rewind due to latency change.
575D: sink.c: Requesting rewind due to finished move
576D: module-alsa-sink.c: Requested to rewind 65536 bytes.
577D: module-alsa-sink.c: Limited to 57848 bytes.
578D: module-alsa-sink.c: before: 14462
579D: module-alsa-sink.c: after: 14462
580D: module-alsa-sink.c: Rewound 57848 bytes.
581D: sink.c: Processing rewind...
582D: sink-input.c: Have to rewind 57848 bytes on render memblockq.
583I: protocol-native.c: Requested tlength=411.52 ms, minreq=20.00 ms
584D: module-alsa-sink.c: latency set to 185.76
585D: module-alsa-sink.c: hwbuf_unused_frames=8193
586D: module-alsa-sink.c: setting avail_min=33902
587D: sink-input.c: Successfully moved sink input 0 from alsa_output.plug_front to alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0.
588I: module-rescue-streams.c: Sucessfully moved sink input 0 "Audacious" to alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0.
589D: module-rescue-streams.c: No source outputs to move away.
590D: module-alsa-sink.c: Thread shutting down
591I: sink.c: Freeing sink 0 "alsa_output.plug_front"
592I: source.c: Freeing source 0 "alsa_output.plug_front.monitor"
593I: module.c: Unloaded "module-alsa-sink" (index: #0).
594I: module.c: Unloading "module-alsa-sink" (index: #1).
595D: module-always-sink.c: Autoloading null-sink as no other sinks detected.
596I: sink.c: Created sink 2 "auto_null" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
597I: source.c: Created source 4 "auto_null.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
598D: module-null-sink.c: Thread starting up
599D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
600D: module-suspend-on-idle.c: Source auto_null.monitor becomes idle.
601D: module-suspend-on-idle.c: Sink auto_null becomes idle.
602I: module.c: Loaded "module-null-sink" (index: #15; argument: "sink_name=auto_null").
603D: module-suspend-on-idle.c: Sink auto_null becomes busy.
604D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 becomes idle.
605D: sink-input.c: Have to rewind 196680 bytes on render memblockq.
606D: sink-input.c: Have to rewind 196680 bytes on implementor.
607D: module-alsa-sink.c: hwbuf_unused_frames=0
608D: module-alsa-sink.c: setting avail_min=58477
609D: sink.c: Requesting rewind due to started move
610D: module-alsa-sink.c: Requested to rewind 65536 bytes.
611D: module-alsa-sink.c: Limited to 18444 bytes.
612D: module-alsa-sink.c: before: 4611
613D: module-alsa-sink.c: after: 4611
614D: module-alsa-sink.c: Rewound 18444 bytes.
615D: sink.c: Processing rewind...
616D: module-suspend-on-idle.c: Sink alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 becomes idle.
617D: sink.c: Requesting rewind due to finished move
618D: module-null-sink.c: Requested to rewind 352800 bytes.
619D: sink.c: Processing rewind...
620D: sink-input.c: Have to rewind 352800 bytes on render memblockq.
621D: source.c: Processing rewind...
622D: module-null-sink.c: Rewound 352800 bytes.
623I: protocol-native.c: Requested tlength=2040.00 ms, minreq=20.00 ms
624D: sink-input.c: Successfully moved sink input 0 from alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0 to auto_null.
625D: core-subscribe.c: dropped redundant event.
626I: module-rescue-streams.c: Sucessfully moved sink input 0 "Audacious" to auto_null.
627D: module-rescue-streams.c: No source outputs to move away.
628D: module-alsa-sink.c: Thread shutting down
629I: sink.c: Freeing sink 1 "alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0"
630I: source.c: Freeing source 1 "alsa_output.pci_10de_3f0_sound_card_0_alsa_playback_0.monitor"
631I: module.c: Unloaded "module-alsa-sink" (index: #1).
632I: module.c: Unloading "module-alsa-source" (index: #2).
633D: module-rescue-streams.c: No source outputs to move away.
634D: module-alsa-source.c: Thread shutting down
635I: source.c: Freeing source 2 "alsa_input.pci_10de_3f0_sound_card_0_alsa_capture_0"
636I: module.c: Unloaded "module-alsa-source" (index: #2).
637I: module.c: Unloading "module-alsa-source" (index: #3).
638D: module-rescue-streams.c: No source outputs to move away.
639D: module-alsa-source.c: Thread shutting down
640I: source.c: Freeing source 3 "alsa_input.pci_1412_1712_sound_card_0_alsa_capture_0"
641I: module.c: Unloaded "module-alsa-source" (index: #3).
642I: module.c: Unloading "module-hal-detect" (index: #4).
643I: module.c: Unloaded "module-hal-detect" (index: #4).
644I: module.c: Unloading "module-esound-protocol-unix" (index: #5).
645I: module.c: Unloaded "module-esound-protocol-unix" (index: #5).
646I: module.c: Unloading "module-native-protocol-unix" (index: #6).
647D: module-suspend-on-idle.c: Sink auto_null becomes idle.
648D: module-null-sink.c: Requested to rewind 352800 bytes.
649D: core-subscribe.c: dropped redundant event.
650D: sink.c: Processing rewind...
651D: source.c: Processing rewind...
652D: module-suspend-on-idle.c: Sink auto_null becomes idle.
653I: sink-input.c: Freeing input 0 "05 - Feeder - Shade.mp3"
654D: module-null-sink.c: Rewound 39376 bytes.
655I: client.c: Freed 1 "Audacious"
656I: module.c: Unloaded "module-native-protocol-unix" (index: #6).
657I: module.c: Unloading "module-volume-restore" (index: #7).
658I: module.c: Unloaded "module-volume-restore" (index: #7).
659I: module.c: Unloading "module-default-device-restore" (index: #8).
660I: module.c: Unloaded "module-default-device-restore" (index: #8).
661I: module.c: Unloading "module-rescue-streams" (index: #9).
662I: module.c: Unloaded "module-rescue-streams" (index: #9).
663I: module.c: Unloading "module-always-sink" (index: #10).
664I: module.c: Unloaded "module-always-sink" (index: #10).
665I: module.c: Unloading "module-suspend-on-idle" (index: #11).
666I: module.c: Unloaded "module-suspend-on-idle" (index: #11).
667I: module.c: Unloading "module-console-kit" (index: #12).
668D: module-console-kit.c: Removing session /org/freedesktop/ConsoleKit/Session5
669I: client.c: Freed 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session5"
670I: module.c: Unloaded "module-console-kit" (index: #12).
671I: module.c: Unloading "module-position-event-sounds" (index: #13).
672I: module.c: Unloaded "module-position-event-sounds" (index: #13).
673I: module.c: Unloading "module-gconf" (index: #14).
674I: module.c: Unloaded "module-gconf" (index: #14).
675I: module.c: Unloading "module-null-sink" (index: #15).
676D: core-subscribe.c: dropped redundant event.
677D: core-subscribe.c: dropped redundant event.
678D: module-null-sink.c: Thread shutting down
679I: sink.c: Freeing sink 2 "auto_null"
680I: source.c: Freeing source 4 "auto_null.monitor"
681I: module.c: Unloaded "module-null-sink" (index: #15).
682D: core-subscribe.c: dropped redundant event.
683I: main.c: Daemon terminated.
684