Ticket #522: output_pa.txt

File output_pa.txt, 61.9 KB (added by diafygi, 3 years ago)

output of pulseaudio -vvvvv, bluetooth crash

Line 
1
2I: caps.c: Limited capabilities successfully to CAP_SYS_NICE.
3I: caps.c: Dropping root privileges.
4I: caps.c: Limited capabilities successfully to CAP_SYS_NICE.
5D: main.c: Started as real root: no, suid root: yes
6I: main.c: PolicyKit refuses acquire-high-priority privilege.
7N: main.c: Called SUID root and real-time and/or high-priority scheduling was requested in the configuration. However, we lack the necessary privileges:
8N: main.c: We are not in group 'pulse-rt', PolicyKit refuse to grant us the requested privileges and we have no increase RLIMIT_NICE/RLIMIT_RTPRIO resource limits.
9N: main.c: For enabling real-time/high-priority scheduling please acquire the appropriate PolicyKit privileges, or become a member of 'pulse-rt', or increase the RLIMIT_NICE/RLIMIT_RTPRIO resource limits for this user.
10I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not permitted
11I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted
12D: main.c: Can realtime: no, can high-priority: no
13D: main.c: Can realtime: no, can high-priority: no
14I: main.c: This is PulseAudio 0.9.14
15D: main.c: Compilation host: i486-pc-linux-gnu
16D: main.c: Compilation CFLAGS: -g -O2 -g -Wall -O3 -Wall -W -Wextra -pedantic -pipe -Wno-long-long -Wvla -Wno-overlength-strings -Wconversion -Wundef -Wformat -Wlogical-op -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
17D: main.c: Running on host: Linux i686 2.6.28-11-generic #38-Ubuntu SMP Fri Mar 27 09:00:52 UTC 2009
18I: main.c: Page size is 4096 bytes
19D: main.c: Compiled with Valgrind support: no
20D: main.c: Running in valgrind mode: no
21D: main.c: Optimized build: yes
22I: main.c: Machine ID is 537c53393fd164341afc821b49cd7c8b.
23I: main.c: Using runtime directory /home/droesler/.pulse/537c53393fd164341afc821b49cd7c8b:runtime.
24I: main.c: Using state directory /home/droesler/.pulse.
25I: main.c: Running in system mode: no
26I: main.c: Fresh high-resolution timers available! Bon appetit!
27D: memblock.c: Using shared memory pool with 1024 slots of size 64.0 KiB each, total size is 64.0 MiB, maximum usable slot size is 65496
28D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9/modules/module-gconf.so': success
29I: module.c: Loaded "module-gconf" (index: #0; argument: "").
30I: module.c: Loaded "module-suspend-on-idle" (index: #1; argument: "").
31I: module-device-restore.c: Sucessfully opened database file '/home/droesler/.pulse/537c53393fd164341afc821b49cd7c8b:device-volumes.i486-pc-linux-gnu.gdbm'.
32I: module.c: Loaded "module-device-restore" (index: #2; argument: "").
33I: module-stream-restore.c: Sucessfully opened database file '/home/droesler/.pulse/537c53393fd164341afc821b49cd7c8b:stream-volumes.i486-pc-linux-gnu.gdbm'.
34I: module.c: Loaded "module-stream-restore" (index: #3; argument: "").
35D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9/modules/module-hal-detect.so': success
36I: module-hal-detect.c: Trying capability alsa
37D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_timer
38D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/computer_alsa_sequencer
39D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_playback_6
40D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_capture_6
41D: module-hal-detect.c: Loading module-alsa-sink with arguments 'device_id=0 sink_name=alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0 tsched=0'
42D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ...
43I: module-alsa-sink.c: Successfully opened device front:0.
44I: module-alsa-sink.c: Successfully enabled mmap() mode.
45I: (alsa-lib)control.c: Invalid CTL front:0
46I: alsa-util.c: Unable to attach to mixer front:0: No such file or directory
47I: alsa-util.c: Successfully attached to mixer 'hw:0'
48I: alsa-util.c: Using mixer control "Master".
49I: module-device-restore.c: Restoring volume for sink alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.
50I: module-device-restore.c: Restoring mute state for sink alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.
51I: sink.c: Created sink 0 "alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
52I: module-device-restore.c: Restoring volume for source alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor.
53I: module-device-restore.c: Restoring mute state for source alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor.
54I: source.c: Created source 0 "alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
55I: module-alsa-sink.c: Using 8 fragments of size 1792 bytes, buffer time is 81.27ms
56D: module-alsa-sink.c: hwbuf_unused=0
57D: module-alsa-sink.c: setting avail_min=1
58I: module-alsa-sink.c: Volume ranges from 0 to 64.
59I: module-alsa-sink.c: Volume ranges from -64.00 dB to 0.00 dB.
60I: alsa-util.c: ALSA device lacks independant volume controls for each channel.
61I: module-alsa-sink.c: Using hardware volume control. Hardware dB scale supported.
62D: alsa-util.c: snd_pcm_dump():
63D: alsa-util.c: Soft volume PCM
64D: alsa-util.c: Control: PCM Playback Volume
65D: alsa-util.c: min_dB: -51
66D: alsa-util.c: max_dB: 0
67D: alsa-util.c: resolution: 256
68D: alsa-util.c: Its setup is:
69D: alsa-util.c:   stream       : PLAYBACK
70D: alsa-util.c:   access       : MMAP_INTERLEAVED
71D: alsa-util.c:   format       : S16_LE
72D: alsa-util.c:   subformat    : STD
73D: alsa-util.c:   channels     : 2
74D: alsa-util.c:   rate         : 44100
75D: alsa-util.c:   exact rate   : 44100 (44100/1)
76D: alsa-util.c:   msbits       : 16
77D: alsa-util.c:   buffer_size  : 3584
78D: alsa-util.c:   period_size  : 448
79D: alsa-util.c:   period_time  : 10158
80D: alsa-util.c:   tstamp_mode  : ENABLE
81D: alsa-util.c:   period_step  : 1
82D: alsa-util.c:   avail_min    : 448
83D: alsa-util.c:   period_event : 0
84D: alsa-util.c:   start_threshold  : -1
85D: alsa-util.c:   stop_threshold   : 1879048192
86D: alsa-util.c:   silence_threshold: 0
87D: alsa-util.c:   silence_size : 0
88D: alsa-util.c:   boundary     : 1879048192
89D: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
90D: alsa-util.c: Its setup is:
91D: alsa-util.c:   stream       : PLAYBACK
92D: alsa-util.c:   access       : MMAP_INTERLEAVED
93D: alsa-util.c:   format       : S16_LE
94D: alsa-util.c:   subformat    : STD
95D: alsa-util.c:   channels     : 2
96D: alsa-util.c:   rate         : 44100
97D: alsa-util.c:   exact rate   : 44100 (44100/1)
98D: alsa-util.c:   msbits       : 16
99D: alsa-util.c:   buffer_size  : 3584
100D: alsa-util.c:   period_size  : 448
101D: alsa-util.c:   period_time  : 10158
102D: alsa-util.c:   tstamp_mode  : ENABLE
103D: alsa-util.c:   period_step  : 1
104D: alsa-util.c:   avail_min    : 448
105D: alsa-util.c:   period_event : 0
106D: alsa-util.c:   start_thre
107D: module-alsa-sink.c: Thread starting up
108D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+29
109D: module-alsa-sink.c: Requested volume: 0: 100% 1: 100%
110D: module-alsa-sink.c: Got hardware volume: 0: 100% 1: 100%
111D: module-alsa-sink.c: Calculated software volume: 0: 100% 1: 100%
112I: module-alsa-sink.c: Starting playback.
113D: module-suspend-on-idle.c: Source alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor becomes idle.
114D: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0 becomes idle.
115I: module.c: Loaded "module-alsa-sink" (index: #4; argument: "device_id=0 sink_name=alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0 tsched=0").
116D: module-hal-detect.c: Loading module-alsa-source with arguments 'device_id=0 source_name=alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 tsched=0'
117D: alsa-util.c: Trying front:0 with SND_PCM_NO_AUTO_FORMAT ...
118I: module-alsa-source.c: Successfully opened device front:0.
119I: module-alsa-source.c: Successfully enabled mmap() mode.
120I: (alsa-lib)control.c: Invalid CTL front:0
121I: alsa-util.c: Unable to attach to mixer front:0: No such file or directory
122I: alsa-util.c: Successfully attached to mixer 'hw:0'
123I: alsa-util.c: Using mixer control "Capture".
124I: module-device-restore.c: Restoring volume for source alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0.
125I: module-device-restore.c: Restoring mute state for source alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0.
126I: source.c: Created source 1 "alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
127I: module-alsa-source.c: Using 8 fragments of size 1792 bytes, buffer time is 81.27ms
128D: module-alsa-source.c: hwbuf_unused=0
129D: module-alsa-source.c: setting avail_min=1
130I: module-alsa-source.c: Volume ranges from 0 to 35.
131I: module-alsa-source.c: Volume ranges from 0.00 dB to 35.00 dB.
132I: alsa-util.c: All 2 channels can be mapped to mixer channels.
133I: module-alsa-source.c: Using hardware volume control. Hardware dB scale supported.
134D: alsa-util.c: snd_pcm_dump():
135D: alsa-util.c: Soft volume PCM
136D: alsa-util.c: Control: PCM Playback Volume
137D: alsa-util.c: min_dB: -51
138D: alsa-util.c: max_dB: 0
139D: alsa-util.c: resolution: 256
140D: alsa-util.c: Its setup is:
141D: alsa-util.c:   stream       : CAPTURE
142D: alsa-util.c:   access       : MMAP_INTERLEAVED
143D: alsa-util.c:   format       : S16_LE
144D: alsa-util.c:   subformat    : STD
145D: alsa-util.c:   channels     : 2
146D: alsa-util.c:   rate         : 44100
147D: alsa-util.c:   exact rate   : 44100 (44100/1)
148D: alsa-util.c:   msbits       : 16
149D: alsa-util.c:   buffer_size  : 3584
150D: alsa-util.c:   period_size  : 448
151D: alsa-util.c:   period_time  : 10158
152D: alsa-util.c:   tstamp_mode  : ENABLE
153D: alsa-util.c:   period_step  : 1
154D: alsa-util.c:   avail_min    : 448
155D: alsa-util.c:   period_event : 0
156D: alsa-util.c:   start_threshold  : -1
157D: alsa-util.c:   stop_threshold   : 1879048192
158D: alsa-util.c:   silence_threshold: 0
159D: alsa-util.c:   silence_size : 0
160D: alsa-util.c:   boundary     : 1879048192
161D: alsa-util.c: Slave: Hardware PCM card 0 'HDA Intel' device 0 subdevice 0
162D: alsa-util.c: Its setup is:
163D: alsa-util.c:   stream       : CAPTURE
164D: alsa-util.c:   access       : MMAP_INTERLEAVED
165D: alsa-util.c:   format       : S16_LE
166D: alsa-util.c:   subformat    : STD
167D: alsa-util.c:   channels     : 2
168D: alsa-util.c:   rate         : 44100
169D: alsa-util.c:   exact rate   : 44100 (44100/1)
170D: alsa-util.c:   msbits       : 16
171D: alsa-util.c:   buffer_size  : 3584
172D: alsa-util.c:   period_size  : 448
173D: alsa-util.c:   period_time  : 10158
174D: alsa-util.c:   tstamp_mode  : ENABLE
175D: alsa-util.c:   period_step  : 1
176D: alsa-util.c:   avail_min    : 448
177D: alsa-util.c:   period_event : 0
178D: alsa-util.c:   start_thresh
179D: module-alsa-source.c: Thread starting up
180D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+28
181D: module-alsa-source.c: Requested volume: 0:  41% 1:  41%
182D: module-alsa-source.c: Got hardware volume: 0:  43% 1:  43%
183D: module-alsa-source.c: Calculated software volume: 0:  98% 1:  98%
184D: module-suspend-on-idle.c: Source alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 becomes idle.
185I: module.c: Loaded "module-alsa-source" (index: #5; argument: "device_id=0 source_name=alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 tsched=0").
186D: module-hal-detect.c: Not loaded device /org/freedesktop/Hal/devices/pci_8086_27d8_sound_card_0_alsa_control__1
187I: module-hal-detect.c: Loaded 2 modules.
188I: module.c: Loaded "module-hal-detect" (index: #6; argument: "tsched=0").
189D: cli-command.c: Checking for existance of '/usr/lib/pulse-0.9/modules/module-esound-protocol-unix.so': success
190I: module.c: Loaded "module-esound-protocol-unix" (index: #7; argument: "").
191I: module.c: Loaded "module-native-protocol-unix" (index: #8; argument: "").
192I: module-default-device-restore.c: Restored default sink 'alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0'.
193D: core-subscribe.c: Dropped redundant event due to change event.
194I: module-default-device-restore.c: Restored default source 'alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0'.
195I: module.c: Loaded "module-default-device-restore" (index: #9; argument: "").
196I: module.c: Loaded "module-rescue-streams" (index: #10; argument: "").
197I: module.c: Loaded "module-always-sink" (index: #11; argument: "").
198I: client.c: Created 0 "ConsoleKit Session /org/freedesktop/ConsoleKit/Session1"
199D: module-console-kit.c: Added new session /org/freedesktop/ConsoleKit/Session1
200I: module.c: Loaded "module-console-kit" (index: #12; argument: "").
201I: module.c: Loaded "module-position-event-sounds" (index: #13; argument: "").
202I: main.c: Daemon startup complete.
203D: module-hal-detect.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired
204D: module-console-kit.c: dbus: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=NameAcquired
205I: module-suspend-on-idle.c: Sink alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0 idle for too long, suspending ...
206I: module-alsa-sink.c: Device suspended...
207I: module-suspend-on-idle.c: Source alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor idle for too long, suspending ...
208I: module-suspend-on-idle.c: Source alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 idle for too long, suspending ...
209I: module-alsa-source.c: Device suspended...
210I: client.c: Created 1 "Native client (UNIX socket client)"
211D: protocol-native.c: Protocol version: remote 14, local 14
212I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
213D: protocol-native.c: SHM possible: yes
214D: protocol-native.c: Negotiated SHM: yes
215D: alsa-util.c: Trying bluetooth with SND_PCM_NO_AUTO_FORMAT ...
216D: alsa-util.c: Maximum hw buffer size is 139 ms
217I: module-alsa-sink.c: Successfully opened device bluetooth.
218I: module-alsa-sink.c: Successfully enabled mmap() mode.
219I: module-alsa-sink.c: Successfully enabled timer-based scheduling mode.
220I: (alsa-lib)control.c: Invalid CTL bluetooth
221I: alsa-util.c: Unable to attach to mixer bluetooth: No such file or directory
222I: module-device-restore.c: Restoring volume for sink alsa_output.bluetooth.
223I: module-device-restore.c: Restoring mute state for sink alsa_output.bluetooth.
224I: sink.c: Created sink 1 "alsa_output.bluetooth" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
225I: module-device-restore.c: Restoring volume for source alsa_output.bluetooth.monitor.
226I: module-device-restore.c: Restoring mute state for source alsa_output.bluetooth.monitor.
227I: source.c: Created source 2 "alsa_output.bluetooth.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
228I: module-alsa-sink.c: Using 3 fragments of size 8192 bytes, buffer time is 139.32ms
229I: module-alsa-sink.c: Time scheduling watermark is 20.00ms
230D: module-alsa-sink.c: hwbuf_unused=0
231D: module-alsa-sink.c: setting avail_min=5262
232D: alsa-util.c: snd_pcm_dump():
233D: alsa-util.c: Bluetooth Audio Device
234D: alsa-util.c: Its setup is:
235D: alsa-util.c:   stream       : PLAYBACK
236D: alsa-util.c:   access       : MMAP_INTERLEAVED
237D: alsa-util.c:   format       : S16_LE
238D: alsa-util.c:   subformat    : STD
239D: alsa-util.c:   channels     : 2
240D: alsa-util.c:   rate         : 44100
241D: alsa-util.c:   exact rate   : 44100 (44100/1)
242D: alsa-util.c:   msbits       : 16
243D: alsa-util.c:   buffer_size  : 6144
244D: alsa-util.c:   period_size  : 2048
245D: alsa-util.c:   period_time  : 46439
246D: alsa-util.c:   tstamp_mode  : ENABLE
247D: alsa-util.c:   period_step  : 1
248D: alsa-util.c:   avail_min    : 5262
249D: alsa-util.c:   period_event : 0
250D: alsa-util.c:   start_threshold  : -1
251D: alsa-util.c:   stop_threshold   : 1610612736
252D: alsa-util.c:   silence_threshold: 0
253D: alsa-util.c:   silence_size : 0
254D: alsa-util.c:   boundary     : 1610612736
255D: module-alsa-sink.c: Thread starting up
256D: rtpoll.c: Acquired POSIX realtime signal SIGRTMIN+27
257I: module-alsa-sink.c: Starting playback.
258D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
259D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
260D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
261D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
262D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
263D: module-suspend-on-idle.c: Source alsa_output.bluetooth.monitor becomes idle.
264D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes idle.
265I: module.c: Loaded "module-alsa-sink" (index: #14; argument: "device=bluetooth").
266I: client.c: Freed 1 "pactl"
267I: protocol-native.c: Connection died.
268D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
269D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
270D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
271D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
272D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
273D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
274D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
275D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
276D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
277D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
278D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
279D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
280D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
281D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
282D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
283D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
284D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
285D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
286D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
287D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
288D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
289D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
290D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
291D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
292D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
293D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
294D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
295D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
296D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
297D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
298D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
299D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
300D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
301D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
302D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
303D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
304D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
305D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
306D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
307D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
308D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
309D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
310D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
311D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
312D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
313D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
314D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
315D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
316D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
317D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
318D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
319D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
320D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
321D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
322D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
323D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
324D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
325D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
326D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
327D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
328D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
329D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
330D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
331D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
332D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
333D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
334D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
335D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
336D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
337D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
338D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
339D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
340D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
341D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
342D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
343D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
344D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
345D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
346D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
347D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
348D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
349D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
350D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
351D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
352D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
353D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
354D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
355D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
356D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
357D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
358D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
359D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
360D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
361D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
362D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
363D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
364D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
365D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
366D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
367D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
368D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
369D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
370D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
371D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
372D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
373D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
374D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
375D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
376I: module-suspend-on-idle.c: Sink alsa_output.bluetooth idle for too long, suspending ...
377I: module-alsa-sink.c: Device suspended...
378I: module-suspend-on-idle.c: Source alsa_output.bluetooth.monitor idle for too long, suspending ...
379I: client.c: Created 2 "Native client (UNIX socket client)"
380D: protocol-native.c: Protocol version: remote 14, local 14
381I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
382D: protocol-native.c: SHM possible: yes
383D: protocol-native.c: Negotiated SHM: yes
384I: module-stream-restore.c: Not restoring device for stream source-output-by-application-id:org.PulseAudio.pavucontrol, because already set
385D: module-suspend-on-idle.c: Source alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor becomes idle.
386D: module-suspend-on-idle.c: Source alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor becomes busy.
387D: resampler.c: Channel matrix:
388D: resampler.c:        I00   I01
389D: resampler.c:     +------------
390D: resampler.c: O00 | 1.000 1.000
391I: resampler.c: Using resampler 'peaks'
392I: resampler.c: Using float32le as working format.
393D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
394D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
395I: source-output.c: Created output 0 "Peak detect" on alsa_output.pci_8086_27d8_sound_card_0_alsa_playback_0.monitor with sample spec float32le 1ch 25Hz and channel map mono
396D: memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
397D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
398I: protocol-native.c: Final latency 60.00 ms = 40.00 ms + 20.00 ms
399I: module-stream-restore.c: Storing volume/mute/device for stream source-output-by-application-id:org.PulseAudio.pavucontrol.
400I: module-stream-restore.c: Not restoring device for stream source-output-by-application-id:org.PulseAudio.pavucontrol, because already set
401I: module-alsa-source.c: Trying resume...
402D: module-alsa-source.c: hwbuf_unused=0
403D: module-alsa-source.c: setting avail_min=1
404I: module-alsa-source.c: Resumed successfully...
405D: module-suspend-on-idle.c: Source alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 becomes idle.
406D: module-suspend-on-idle.c: Source alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 becomes busy.
407D: resampler.c: Channel matrix:
408D: resampler.c:        I00   I01
409D: resampler.c:     +------------
410D: resampler.c: O00 | 1.000 1.000
411I: resampler.c: Using resampler 'peaks'
412I: resampler.c: Using float32le as working format.
413D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
414D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
415I: source-output.c: Created output 1 "Peak detect" on alsa_input.pci_8086_27d8_sound_card_0_alsa_capture_0 with sample spec float32le 1ch 25Hz and channel map mono
416D: memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
417D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
418I: protocol-native.c: Final latency 60.00 ms = 40.00 ms + 20.00 ms
419D: module-alsa-source.c: hwbuf_unused=0
420D: module-alsa-source.c: setting avail_min=1
421D: module-alsa-source.c: hwbuf_unused=0
422D: module-alsa-source.c: setting avail_min=1
423I: module-stream-restore.c: Storing volume/mute/device for stream source-output-by-application-id:org.PulseAudio.pavucontrol.
424I: module-stream-restore.c: Not restoring device for stream source-output-by-application-id:org.PulseAudio.pavucontrol, because already set
425D: module-suspend-on-idle.c: Source alsa_output.bluetooth.monitor becomes idle.
426D: module-suspend-on-idle.c: Source alsa_output.bluetooth.monitor becomes busy.
427D: resampler.c: Channel matrix:
428D: resampler.c:        I00   I01
429D: resampler.c:     +------------
430D: resampler.c: O00 | 1.000 1.000
431I: resampler.c: Using resampler 'peaks'
432I: resampler.c: Using float32le as working format.
433D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
434D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
435I: source-output.c: Created output 2 "Peak detect" on alsa_output.bluetooth.monitor with sample spec float32le 1ch 25Hz and channel map mono
436D: memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
437D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
438I: protocol-native.c: Final latency 60.00 ms = 40.00 ms + 20.00 ms
439I: module-stream-restore.c: Storing volume/mute/device for stream source-output-by-application-id:org.PulseAudio.pavucontrol.
440I: module-stream-restore.c: Synced.
441I: client.c: Created 3 "Native client (UNIX socket client)"
442I: client.c: Freed 3 "Native client (UNIX socket client)"
443I: protocol-native.c: Connection died.
444I: client.c: Created 4 "Native client (UNIX socket client)"
445D: protocol-native.c: Protocol version: remote 14, local 14
446I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
447D: protocol-native.c: SHM possible: yes
448D: protocol-native.c: Negotiated SHM: yes
449I: client.c: Freed 4 "ALSA plug-in [vlc]"
450I: protocol-native.c: Connection died.
451I: client.c: Created 5 "Native client (UNIX socket client)"
452D: protocol-native.c: Protocol version: remote 14, local 14
453I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
454D: protocol-native.c: SHM possible: yes
455D: protocol-native.c: Negotiated SHM: yes
456I: module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [vlc].
457I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:ALSA plug-in [vlc].
458D: module-stream-restore.c: Not restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [vlc], because already set.
459I: module-alsa-sink.c: Trying resume...
460D: alsa-util.c: Maximum hw buffer size is 139 ms
461D: module-alsa-sink.c: Latency set to 20.00ms
462D: module-alsa-sink.c: hwbuf_unused=21048
463D: module-alsa-sink.c: setting avail_min=5704
464I: module-alsa-sink.c: Resumed successfully...
465I: module-alsa-sink.c: Starting playback.
466D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes idle.
467D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes busy.
468I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
469D: resampler.c: Channel matrix:
470D: resampler.c:        I00
471D: resampler.c:     +------
472D: resampler.c: O00 | 1.000
473D: resampler.c: O01 | 1.000
474I: resampler.c: Using resampler 'copy'
475I: resampler.c: Using float32le as working format.
476D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
477D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
478I: sink-input.c: Created input 0 "ALSA Playback" on alsa_output.bluetooth with sample spec float32le 1ch 44100Hz and channel map mono
479I: protocol-native.c: Requested tlength=5944.31 ms, minreq=23.22 ms
480D: protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
481D: memblockq.c: memblockq requested: maxlength=4194304, tlength=1048576, base=4, prebuf=1044480, minreq=4096 maxrewind=0
482D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=1048576, base=4, prebuf=1044480, minreq=4096 maxrewind=0
483I: protocol-native.c: Final latency 5967.53 ms = 5897.87 ms + 2*23.22 ms + 23.22 ms
484D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
485D: module-alsa-sink.c: Latency set to 20.00ms
486D: module-alsa-sink.c: hwbuf_unused=21048
487D: module-alsa-sink.c: setting avail_min=5704
488D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
489D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
490D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
491D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
492D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
493D: module-suspend-on-idle.c: Source alsa_output.bluetooth.monitor becomes busy.
494D: resampler.c: Channel matrix:
495D: resampler.c:        I00   I01
496D: resampler.c:     +------------
497D: resampler.c: O00 | 1.000 1.000
498I: resampler.c: Using resampler 'peaks'
499I: resampler.c: Using float32le as working format.
500D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
501D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
502I: source-output.c: Created output 3 "Peak detect" on alsa_output.bluetooth.monitor with sample spec float32le 1ch 25Hz and channel map mono
503D: memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
504D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
505I: protocol-native.c: Final latency 60.00 ms = 40.00 ms + 20.00 ms
506D: module-alsa-sink.c: Latency set to 20.00ms
507D: module-alsa-sink.c: hwbuf_unused=21048
508D: module-alsa-sink.c: setting avail_min=5704
509D: module-alsa-sink.c: Latency set to 20.00ms
510D: module-alsa-sink.c: hwbuf_unused=21048
511D: module-alsa-sink.c: setting avail_min=5704
512D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
513D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
514D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
515D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
516D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
517D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
518D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
519D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
520I: module-alsa-sink.c: Underrun!
521N: module-alsa-sink.c: Increasing minimal latency to 8.00 ms
522D: module-alsa-sink.c: Latency set to 20.00ms
523D: module-alsa-sink.c: hwbuf_unused=21048
524D: module-alsa-sink.c: setting avail_min=5704
525D: module-alsa-sink.c: Latency set to 20.00ms
526D: module-alsa-sink.c: hwbuf_unused=21048
527D: module-alsa-sink.c: setting avail_min=5704
528D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
529D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
530I: module-alsa-sink.c: Starting playback.
531D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
532D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
533D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
534D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
535D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
536D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
537D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
538D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
539D: protocol-native.c: Requesting rewind due to end of underrun.
540D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
541D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
542D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
543D: protocol-native.c: Requesting rewind due to end of underrun.
544D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
545D: protocol-native.c: Requesting rewind due to end of underrun.
546D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
547D: protocol-native.c: Requesting rewind due to end of underrun.
548D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
549D: protocol-native.c: Requesting rewind due to end of underrun.
550D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
551D: protocol-native.c: Requesting rewind due to end of underrun.
552D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
553D: protocol-native.c: Requesting rewind due to end of underrun.
554D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
555D: protocol-native.c: Requesting rewind due to end of underrun.
556D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
557D: protocol-native.c: Requesting rewind due to end of underrun.
558D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
559D: protocol-native.c: Requesting rewind due to end of underrun.
560D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
561D: protocol-native.c: Requesting rewind due to end of underrun.
562D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
563D: protocol-native.c: Requesting rewind due to end of underrun.
564D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
565D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
566D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
567D: protocol-native.c: Requesting rewind due to end of underrun.
568D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
569D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
570D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
571D: protocol-native.c: Requesting rewind due to end of underrun.
572D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
573D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
574D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
575D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
576D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
577D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
578D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
579D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
580D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
581D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
582D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
583D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
584D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
585D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
586D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
587D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
588D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
589I: module-alsa-sink.c: Underrun!
590N: module-alsa-sink.c: Increasing minimal latency to 16.00 ms
591D: module-alsa-sink.c: Latency set to 20.00ms
592D: module-alsa-sink.c: hwbuf_unused=21048
593D: module-alsa-sink.c: setting avail_min=5704
594D: module-alsa-sink.c: Latency set to 20.00ms
595D: module-alsa-sink.c: hwbuf_unused=21048
596D: module-alsa-sink.c: setting avail_min=5704
597D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
598D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
599I: module-alsa-sink.c: Starting playback.
600D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
601D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
602D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
603D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
604D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
605D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
606D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
607D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
608D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
609D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
610D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
611D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
612D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
613D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
614D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
615I: module-alsa-sink.c: Underrun!
616N: module-alsa-sink.c: Increasing minimal latency to 32.00 ms
617D: module-alsa-sink.c: Latency set to 32.00ms
618D: module-alsa-sink.c: hwbuf_unused=18932
619D: module-alsa-sink.c: setting avail_min=5704
620D: module-alsa-sink.c: Latency set to 32.00ms
621D: module-alsa-sink.c: hwbuf_unused=18932
622D: module-alsa-sink.c: setting avail_min=5704
623D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
624D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
625I: module-alsa-sink.c: Starting playback.
626D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
627D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
628D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
629D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
630D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
631D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
632D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
633D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
634D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
635D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
636D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
637D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
638D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
639D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
640D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
641D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
642D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
643D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
644D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
645I: module-alsa-sink.c: Underrun!
646N: module-alsa-sink.c: Increasing wakeup watermark to 20.00 ms
647D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
648D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
649I: module-alsa-sink.c: Starting playback.
650D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
651D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
652D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
653D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
654D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
655D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
656D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
657D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
658D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
659D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
660D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
661D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
662D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
663D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
664D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
665D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
666D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
667D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
668D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
669D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
670D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
671D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
672D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
673D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
674D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
675D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
676D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
677D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
678D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
679D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
680I: module-alsa-sink.c: Underrun!
681N: module-alsa-sink.c: Increasing wakeup watermark to 22.00 ms
682D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
683D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
684I: module-alsa-sink.c: Starting playback.
685D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
686D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
687D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
688D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
689D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
690D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
691D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
692D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
693D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
694D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
695D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
696D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
697D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
698D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
699D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
700D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
701D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
702D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
703D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
704D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
705D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
706D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
707D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
708D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
709D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
710D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
711D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
712D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
713D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
714D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
715I: module-alsa-sink.c: Underrun!
716N: module-alsa-sink.c: Increasing minimal latency to 64.00 ms
717D: module-alsa-sink.c: Latency set to 64.00ms
718D: module-alsa-sink.c: hwbuf_unused=13288
719D: module-alsa-sink.c: setting avail_min=5175
720D: module-alsa-sink.c: Latency set to 64.00ms
721D: module-alsa-sink.c: hwbuf_unused=13288
722D: module-alsa-sink.c: setting avail_min=5175
723D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
724D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
725I: module-alsa-sink.c: Starting playback.
726D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
727D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
728D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
729D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
730D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
731D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
732D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
733D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
734D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
735D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
736D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
737D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
738D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
739D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
740D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
741D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
742D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
743I: module-alsa-sink.c: Underrun!
744N: module-alsa-sink.c: Increasing wakeup watermark to 43.99 ms
745D: module-alsa-sink.c: snd_pcm_mmap_commit: Broken pipe
746D: module-alsa-sink.c: snd_pcm_mmap_commit: Buffer underrun!
747I: module-alsa-sink.c: Starting playback.
748D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
749D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
750D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
751D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
752D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
753D: module-alsa-sink.c: ALSA woke us up to write new data to the device, but there was actually nothing to write! Most likely this is a Linux bug. Please report this issue to the ALSA developers. We were woken up with POLLOUT set -- however a subsequent snd_pcm_avail_update() returned 0.
754D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
755D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
756D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
757D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
758D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
759D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
760D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
761D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
762D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
763D: module-alsa-sink.c: Cutting sleep time for the initial iterations by half.
764D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
765D: module-alsa-sink.c: Latency set to 64.00ms
766D: module-alsa-sink.c: hwbuf_unused=13288
767D: module-alsa-sink.c: setting avail_min=4205
768I: source-output.c: Freeing output 3 "Peak detect"
769D: module-alsa-sink.c: Latency set to 64.00ms
770D: module-alsa-sink.c: hwbuf_unused=13288
771D: module-alsa-sink.c: setting avail_min=4205
772D: module-alsa-sink.c: Requested to rewind 24576 bytes.
773D: module-alsa-sink.c: Limited to 3528 bytes.
774D: module-alsa-sink.c: before: 882
775D: module-alsa-sink.c: after: 882
776D: module-alsa-sink.c: Rewound 3528 bytes.
777D: sink.c: Processing rewind...
778D: source.c: Processing rewind...
779D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes idle.
780D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes idle.
781I: sink-input.c: Freeing input 0 "ALSA Playback"
782I: module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [vlc].
783I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:ALSA plug-in [vlc].
784D: module-stream-restore.c: Not restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [vlc], because already set.
785D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes busy.
786I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
787D: resampler.c: Channel matrix:
788D: resampler.c:        I00
789D: resampler.c:     +------
790D: resampler.c: O00 | 1.000
791D: resampler.c: O01 | 1.000
792I: resampler.c: Using resampler 'copy'
793I: resampler.c: Using float32le as working format.
794D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
795D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
796I: sink-input.c: Created input 1 "ALSA Playback" on alsa_output.bluetooth with sample spec float32le 1ch 44100Hz and channel map mono
797I: protocol-native.c: Requested tlength=5944.31 ms, minreq=23.22 ms
798D: protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
799D: memblockq.c: memblockq requested: maxlength=4194304, tlength=1048576, base=4, prebuf=1044480, minreq=4096 maxrewind=0
800D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=1048576, base=4, prebuf=1044480, minreq=4096 maxrewind=0
801I: protocol-native.c: Final latency 5967.53 ms = 5897.87 ms + 2*23.22 ms + 23.22 ms
802D: module-alsa-sink.c: Latency set to 64.00ms
803D: module-alsa-sink.c: hwbuf_unused=13288
804D: module-alsa-sink.c: setting avail_min=4205
805D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
806D: protocol-native.c: Requesting rewind due to end of underrun.
807D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
808D: module-alsa-sink.c: Latency set to 64.00ms
809D: module-alsa-sink.c: hwbuf_unused=13288
810D: module-alsa-sink.c: setting avail_min=4205
811D: module-alsa-sink.c: Requested to rewind 24576 bytes.
812D: module-alsa-sink.c: Limited to 3528 bytes.
813D: module-alsa-sink.c: before: 882
814D: module-alsa-sink.c: after: 882
815D: module-alsa-sink.c: Rewound 3528 bytes.
816D: sink.c: Processing rewind...
817D: source.c: Processing rewind...
818D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes idle.
819D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes idle.
820I: sink-input.c: Freeing input 1 "ALSA Playback"
821I: module-stream-restore.c: Restoring device for stream sink-input-by-application-name:ALSA plug-in [vlc].
822I: module-stream-restore.c: Restoring volume for sink input sink-input-by-application-name:ALSA plug-in [vlc].
823D: module-stream-restore.c: Not restoring mute state for sink input sink-input-by-application-name:ALSA plug-in [vlc], because already set.
824D: module-suspend-on-idle.c: Sink alsa_output.bluetooth becomes busy.
825I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
826D: resampler.c: Channel matrix:
827D: resampler.c:        I00
828D: resampler.c:     +------
829D: resampler.c: O00 | 1.000
830D: resampler.c: O01 | 1.000
831I: resampler.c: Using resampler 'copy'
832I: resampler.c: Using float32le as working format.
833D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
834D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
835I: sink-input.c: Created input 2 "ALSA Playback" on alsa_output.bluetooth with sample spec float32le 1ch 44100Hz and channel map mono
836I: protocol-native.c: Requested tlength=5944.31 ms, minreq=23.22 ms
837D: protocol-native.c: Early requests mode enabled, configuring sink latency to minreq.
838D: memblockq.c: memblockq requested: maxlength=4194304, tlength=1048576, base=4, prebuf=1044480, minreq=4096 maxrewind=0
839D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=1048576, base=4, prebuf=1044480, minreq=4096 maxrewind=0
840I: protocol-native.c: Final latency 5967.53 ms = 5897.87 ms + 2*23.22 ms + 23.22 ms
841D: module-alsa-sink.c: Latency set to 64.00ms
842D: module-alsa-sink.c: hwbuf_unused=13288
843D: module-alsa-sink.c: setting avail_min=4205
844D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
845D: module-suspend-on-idle.c: Source alsa_output.bluetooth.monitor becomes busy.
846D: resampler.c: Channel matrix:
847D: resampler.c:        I00   I01
848D: resampler.c:     +------------
849D: resampler.c: O00 | 1.000 1.000
850I: resampler.c: Using resampler 'peaks'
851I: resampler.c: Using float32le as working format.
852D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
853D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
854I: source-output.c: Created output 4 "Peak detect" on alsa_output.bluetooth.monitor with sample spec float32le 1ch 25Hz and channel map mono
855D: memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
856D: memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
857I: protocol-native.c: Final latency 60.00 ms = 40.00 ms + 20.00 ms
858D: module-alsa-sink.c: Latency set to 64.00ms
859D: module-alsa-sink.c: hwbuf_unused=13288
860D: module-alsa-sink.c: setting avail_min=4205
861D: module-alsa-sink.c: Latency set to 64.00ms
862D: module-alsa-sink.c: hwbuf_unused=13288
863D: module-alsa-sink.c: setting avail_min=4205
864D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
865D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
866D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
867D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
868D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
869D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
870D: protocol-native.c: Requesting rewind due to end of underrun.
871D: protocol-native.c: Requesting rewind due to end of underrun.
872D: protocol-native.c: Requesting rewind due to end of underrun.
873D: protocol-native.c: Requesting rewind due to end of underrun.
874D: protocol-native.c: Requesting rewind due to end of underrun.
875D: protocol-native.c: Requesting rewind due to end of underrun.
876D: protocol-native.c: Requesting rewind due to end of underrun.
877D: protocol-native.c: Requesting rewind due to end of underrun.
878D: protocol-native.c: Requesting rewind due to end of underrun.
879D: protocol-native.c: Requesting rewind due to end of underrun.
880D: protocol-native.c: Requesting rewind due to end of underrun.
881D: protocol-native.c: Requesting rewind due to end of underrun.
882D: protocol-native.c: Requesting rewind due to end of underrun.
883D: protocol-native.c: Requesting rewind due to end of underrun.
884D: protocol-native.c: Requesting rewind due to end of underrun.
885D: protocol-native.c: Requesting rewind due to end of underrun.
886D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
887D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
888D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
889D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
890D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
891D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
892D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
893D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
894D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
895D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
896D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
897D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
898D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
899D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
900D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
901D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
902D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
903D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
904D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
905D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
906D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
907D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
908D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
909D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
910D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
911D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
912D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
913D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
914D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
915D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
916D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
917D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
918D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
919D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
920D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
921D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
922D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
923D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
924D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
925D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
926D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
927D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
928D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
929D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
930D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
931D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
932D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
933D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
934D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
935D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
936D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
937D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
938D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
939D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
940D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
941D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
942D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
943D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
944D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
945D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
946D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
947D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
948D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
949D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
950D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
951D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
952D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
953D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
954D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
955D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
956D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
957D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
958D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
959D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
960D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
961D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
962D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
963D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
964D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
965D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
966D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
967D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
968D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
969D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
970D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
971D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
972D: module-alsa-sink.c: Wakeup from ALSA! OUTPUT
973D: module-alsa-sink.c: Latency set to 64.00ms
974D: module-alsa-sink.c: hwbuf_unused=13288
975D: module-alsa-sink.c: setting avail_min=4205
976I: source-output.c: Freeing output 4 "Peak detect"
977D: module-alsa-sink.c: Latency set to 64.00ms
978D: module-alsa-sink.c: hwbuf_unused=13288
979D: module-alsa-sink.c: setting avail_min=4205
980D: module-alsa-sink.c: Requested to rewind 24576 bytes.
981D: module-alsa-sink.c: Limited to 3528 bytes.
982D: module-alsa-sink.c: before: 882
983D: module-alsa-sink.c: after: 882
984D: module-alsa-sink.c: Rewound 3528 bytes.
985D: sink.c: Processing rewind...
986D: source.c: Processing rewind...