I: [pulseaudio] client.c: Created 1 "Native client (UNIX socket client)" D: [pulseaudio] protocol-native.c: Protocol version: remote 29, local 29 I: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1 D: [pulseaudio] protocol-native.c: SHM possible: yes D: [pulseaudio] protocol-native.c: Negotiated SHM: yes D: [pulseaudio] module-augment-properties.c: Looking for .desktop file for skype D: [pulseaudio] module-augment-properties.c: Found /usr/share/applications/skype.desktop. D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "48000" format.channels = "1" format.channel_map = "\"mono\"" I: [pulseaudio] sink-input.c: Trying to change sample rate D: [pulseaudio] sink.c: Suspending sink alsa_output.pci-0000_00_1b.0.analog-stereo due to changing the sample rate. I: [pulseaudio] alsa-sink.c: Updating rate for device front:0, new rate is 48000 D: [pulseaudio] source.c: Suspending source alsa_output.pci-0000_00_1b.0.analog-stereo.monitor due to changing the sample rate. I: [pulseaudio] source.c: Changed sampling rate successfully I: [pulseaudio] sink.c: Changed sampling rate successfully I: [pulseaudio] sink-input.c: Rate changed to 48000 Hz I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:event. I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:event. D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming. D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming D: [pulseaudio] reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0' I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Trying resume... I: [alsa-sink-ALC887-VD Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only D: [alsa-sink-ALC887-VD Analog] alsa-util.c: Maximum hw buffer size is 21845 ms D: [alsa-sink-ALC887-VD Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples). I: [alsa-sink-ALC887-VD Analog] alsa-util.c: ALSA period wakeups disabled D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=0 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=87319 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=0 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=87319 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=0 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=87319 I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Time scheduling watermark is 18.38ms I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Resumed successfully... D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Starting playback. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half. D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. D: [pulseaudio] resampler.c: Channel matrix: D: [pulseaudio] resampler.c: I00 D: [pulseaudio] resampler.c: +------ D: [pulseaudio] resampler.c: O00 | 1.000 D: [pulseaudio] resampler.c: O01 | 1.000 I: [pulseaudio] remap_sse.c: Using SSE2 mono to stereo remapping D: [pulseaudio] resampler.c: Resampler: D: [pulseaudio] resampler.c: rate 48000 -> 48000 (method copy) D: [pulseaudio] resampler.c: format s16le -> s16le (intermediate s16le) D: [pulseaudio] resampler.c: channels 1 -> 2 (resampling 1) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: [pulseaudio] sink-input.c: Created input 0 "Event Sound" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 1ch 48000Hz and channel map mono I: [pulseaudio] sink-input.c: window.icon_name = "skype" I: [pulseaudio] sink-input.c: application.icon_name = "skype" I: [pulseaudio] sink-input.c: media.role = "event" I: [pulseaudio] sink-input.c: media.name = "Event Sound" I: [pulseaudio] sink-input.c: application.name = "Skype" I: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" I: [pulseaudio] sink-input.c: native-protocol.version = "29" I: [pulseaudio] sink-input.c: application.process.id = "691" I: [pulseaudio] sink-input.c: application.process.user = "ross" I: [pulseaudio] sink-input.c: application.process.host = "hobo" I: [pulseaudio] sink-input.c: application.process.binary = "skype" I: [pulseaudio] sink-input.c: application.language = "en_US.UTF-8" I: [pulseaudio] sink-input.c: window.x11.display = ":0" I: [pulseaudio] sink-input.c: application.process.machine_id = "5ce663ad0573416a8b1ced89348b22e5" I: [pulseaudio] sink-input.c: application.process.session_id = "1" I: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:event" I: [pulseaudio] protocol-native.c: Requested tlength=10.00 ms, minreq=2.50 ms D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half. D: [pulseaudio] protocol-native.c: Requested latency=2.50 ms, Received latency=2.50 ms D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0 I: [pulseaudio] protocol-native.c: Final latency 10.00 ms = 2.50 ms + 2*2.50 ms + 2.50 ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Cutting sleep time for the initial iterations by half. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Latency set to 2.50ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=352320 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=88141 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requesting rewind due to latency change. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested volume: front-left: 21698 / 33% / -28.80 dB, front-right: 21698 / 33% / -28.80 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Got hardware volume: front-left: 21702 / 33% / -28.80 dB, front-right: 21702 / 33% / -28.80 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Calculated software volume: front-left: 65524 / 100% / -0.00 dB, front-right: 65524 / 100% / -0.00 dB (accurate-enough=yes) D: [alsa-sink-ALC887-VD Analog] sink.c: Volume going down to 21698 at 184990590 D: [alsa-sink-ALC887-VD Analog] sink.c: Next volume change in 1829095 usec D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 352320 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 88080 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 88080 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 352320 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 2069 D: [alsa-sink-ALC887-VD Analog] sink.c: Volume change to 21698 at 183155592 was written 5932 usec late D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 352320 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink-input.c: Requesting rewind due to uncorking D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 128 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 32 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 32 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 128 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 2159 D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] protocol-native.c: Requesting rewind due to end of underrun. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 128 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 32 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 32 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 128 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 2199 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 128 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] ratelimit.c: 3853 events suppressed D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] source-output.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "48000" format.channels = "1" format.channel_map = "\"mono\"" I: [pulseaudio] source-output.c: Trying to change sample rate D: [pulseaudio] source.c: Suspending source alsa_input.pci-0000_00_1b.0.analog-stereo due to changing the sample rate. I: [pulseaudio] alsa-source.c: Updating rate for device front:0, new rate is 48000 I: [pulseaudio] source.c: Changed sampling rate successfully I: [pulseaudio] source-output.c: Rate changed to 48000 Hz D: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes busy, resuming. D: [pulseaudio] source.c: Suspend cause of source alsa_input.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming I: [alsa-source-ALC887-VD Analog] alsa-source.c: Trying resume... I: [alsa-source-ALC887-VD Analog] alsa-util.c: Trying to disable ALSA period wakeups, using timers only D: [alsa-source-ALC887-VD Analog] alsa-util.c: Maximum hw buffer size is 21845 ms D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-source-ALC887-VD Analog] alsa-util.c: Set buffer size first (to 88200 samples), period size second (to 44100 samples). I: [alsa-source-ALC887-VD Analog] alsa-util.c: ALSA period wakeups disabled D: [alsa-source-ALC887-VD Analog] alsa-source.c: hwbuf_unused=0 D: [alsa-source-ALC887-VD Analog] alsa-source.c: setting avail_min=87319 D: [alsa-source-ALC887-VD Analog] alsa-source.c: hwbuf_unused=0 D: [alsa-source-ALC887-VD Analog] alsa-source.c: setting avail_min=87319 I: [alsa-source-ALC887-VD Analog] alsa-source.c: Time scheduling watermark is 18.38ms I: [alsa-source-ALC887-VD Analog] alsa-source.c: Resumed successfully... I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Underrun! I: [alsa-source-ALC887-VD Analog] alsa-source.c: Starting capture. I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Increasing minimal latency to 1.00 ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Latency set to 2.50ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=352320 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=88141 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Latency set to 2.50ms D: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=352320 D: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=88141 D: [pulseaudio] resampler.c: Channel matrix: D: [pulseaudio] resampler.c: I00 I01 D: [pulseaudio] resampler.c: +------------ D: [pulseaudio] resampler.c: O00 | 0.500 0.500 D: [alsa-sink-ALC887-VD Analog] protocol-native.c: Implicit underrun of 'Event Sound' I: [pulseaudio] remap.c: Using generic matrix remapping D: [pulseaudio] resampler.c: Resampler: D: [pulseaudio] resampler.c: rate 48000 -> 48000 (method copy) D: [pulseaudio] resampler.c: format s16le -> s16le (intermediate s16le) D: [pulseaudio] resampler.c: channels 2 -> 1 (resampling 1) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: [pulseaudio] source-output.c: Created output 0 "Input" on alsa_input.pci-0000_00_1b.0.analog-stereo with sample spec s16le 1ch 48000Hz and channel map mono I: [pulseaudio] source-output.c: window.icon_name = "skype" I: [pulseaudio] source-output.c: application.icon_name = "skype" I: [pulseaudio] source-output.c: media.role = "phone" I: [pulseaudio] source-output.c: media.name = "Input" I: [pulseaudio] source-output.c: application.name = "Skype" I: [pulseaudio] source-output.c: native-protocol.peer = "UNIX socket client" D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (64 bytes ahead in playback buffer) I: [pulseaudio] source-output.c: native-protocol.version = "29" I: [pulseaudio] source-output.c: application.process.id = "691" I: [pulseaudio] source-output.c: application.process.user = "ross" I: [pulseaudio] source-output.c: application.process.host = "hobo" I: [pulseaudio] source-output.c: application.process.binary = "skype" I: [pulseaudio] source-output.c: application.language = "en_US.UTF-8" I: [pulseaudio] source-output.c: window.x11.display = ":0" I: [pulseaudio] source-output.c: application.process.machine_id = "5ce663ad0573416a8b1ced89348b22e5" I: [pulseaudio] source-output.c: application.process.session_id = "1" I: [pulseaudio] source-output.c: module-stream-restore.id = "source-output-by-media-role:phone" D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=2, prebuf=1, minreq=0 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=2, prebuf=2, minreq=2 maxrewind=0 I: [pulseaudio] protocol-native.c: Final latency 10.00 ms = 5.00 ms + 5.00 ms D: [alsa-source-ALC887-VD Analog] alsa-source.c: latency set to 5.00ms D: [alsa-source-ALC887-VD Analog] alsa-source.c: hwbuf_unused=351840 D: [alsa-source-ALC887-VD Analog] alsa-source.c: setting avail_min=121 D: [alsa-source-ALC887-VD Analog] alsa-source.c: latency set to 5.00ms D: [alsa-source-ALC887-VD Analog] alsa-source.c: hwbuf_unused=351840 D: [alsa-source-ALC887-VD Analog] alsa-source.c: setting avail_min=121 D: [alsa-source-ALC887-VD Analog] alsa-source.c: Requested volume: front-left: 16384 / 25% / -36.12 dB, front-right: 16384 / 25% / -36.12 dB D: [alsa-source-ALC887-VD Analog] alsa-source.c: Got hardware volume: front-left: 15843 / 24% / -37.00 dB, front-right: 15843 / 24% / -37.00 dB D: [alsa-source-ALC887-VD Analog] alsa-source.c: Calculated software volume: front-left: 67774 / 103% / 0.87 dB, front-right: 67774 / 103% / 0.87 dB (accurate-enough=no) D: [alsa-source-ALC887-VD Analog] source.c: Volume going down to 15843 at 188166326 D: [alsa-source-ALC887-VD Analog] source.c: Volume change to 15843 at 188166326 was written 7560 usec late D: [alsa-sink-ALC887-VD Analog] protocol-native.c: Requesting rewind due to end of underrun. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 384 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 96 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 24 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 24 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 96 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 531 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 96 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes busy, resuming. D: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. D: [alsa-source-ALC887-VD Analog] alsa-source.c: hwbuf_unused=0 D: [alsa-source-ALC887-VD Analog] alsa-source.c: setting avail_min=88009 D: [alsa-source-ALC887-VD Analog] alsa-source.c: Requested volume: front-left: 16384 / 25% / -36.12 dB, front-right: 16384 / 25% / -36.12 dB D: [alsa-source-ALC887-VD Analog] alsa-source.c: Got hardware volume: front-left: 15843 / 24% / -37.00 dB, front-right: 15843 / 24% / -37.00 dB D: [alsa-source-ALC887-VD Analog] alsa-source.c: Calculated software volume: front-left: 67774 / 103% / 0.87 dB, front-right: 67774 / 103% / 0.87 dB (accurate-enough=no) D: [alsa-source-ALC887-VD Analog] source.c: Volume not changing D: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. I: [pulseaudio] source-output.c: Freeing output 0 "Input" D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "48000" format.channels = "1" format.channel_map = "\"mono\"" I: [pulseaudio] sink-input.c: Trying to change sample rate I: [pulseaudio] sink-input.c: Rate changed to 48000 Hz I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:event. I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:event. D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming. I: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. D: [pulseaudio] resampler.c: Channel matrix: D: [pulseaudio] resampler.c: I00 D: [pulseaudio] resampler.c: +------ D: [pulseaudio] resampler.c: O00 | 1.000 D: [pulseaudio] resampler.c: O01 | 1.000 I: [pulseaudio] remap_sse.c: Using SSE2 mono to stereo remapping D: [pulseaudio] resampler.c: Resampler: D: [pulseaudio] resampler.c: rate 48000 -> 48000 (method copy) D: [pulseaudio] resampler.c: format s16le -> s16le (intermediate s16le) D: [pulseaudio] resampler.c: channels 1 -> 2 (resampling 1) D: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 I: [pulseaudio] sink-input.c: Created input 1 "Event Sound" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec s16le 1ch 48000Hz and channel map mono I: [pulseaudio] sink-input.c: window.icon_name = "skype" I: [pulseaudio] sink-input.c: application.icon_name = "skype" I: [pulseaudio] sink-input.c: media.role = "event" I: [pulseaudio] sink-input.c: media.name = "Event Sound" I: [pulseaudio] sink-input.c: application.name = "Skype" I: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" I: [pulseaudio] sink-input.c: native-protocol.version = "29" I: [pulseaudio] sink-input.c: application.process.id = "691" I: [pulseaudio] sink-input.c: application.process.user = "ross" I: [pulseaudio] sink-input.c: application.process.host = "hobo" I: [pulseaudio] sink-input.c: application.process.binary = "skype" I: [pulseaudio] sink-input.c: application.language = "en_US.UTF-8" I: [pulseaudio] sink-input.c: window.x11.display = ":0" I: [pulseaudio] sink-input.c: application.process.machine_id = "5ce663ad0573416a8b1ced89348b22e5" I: [pulseaudio] sink-input.c: application.process.session_id = "1" I: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:event" I: [pulseaudio] protocol-native.c: Requested tlength=10.00 ms, minreq=2.50 ms D: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. D: [pulseaudio] protocol-native.c: Requested latency=2.50 ms, Received latency=2.50 ms D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0 D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=720, base=2, prebuf=482, minreq=240 maxrewind=0 I: [pulseaudio] protocol-native.c: Final latency 10.00 ms = 2.50 ms + 2*2.50 ms + 2.50 ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Latency set to 2.50ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=352320 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=88141 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested volume: front-left: 21698 / 33% / -28.80 dB, front-right: 21698 / 33% / -28.80 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Got hardware volume: front-left: 21702 / 33% / -28.80 dB, front-right: 21702 / 33% / -28.80 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Calculated software volume: front-left: 65524 / 100% / -0.00 dB, front-right: 65524 / 100% / -0.00 dB (accurate-enough=yes) D: [alsa-sink-ALC887-VD Analog] sink.c: Volume not changing D: [alsa-sink-ALC887-VD Analog] sink-input.c: Requesting rewind due to uncorking D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 192 bytes. D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 48 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 48 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 192 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 1223 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 192 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] protocol-native.c: Requesting rewind due to end of underrun. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 224 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 56 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 56 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 224 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 1256 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 224 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 224 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink-input.c: Requesting rewind due to corking D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 224 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 192 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 48 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 48 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 192 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 1349 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 192 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 192 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 96 bytes on implementor. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 224 bytes ago (256 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 224 bytes ago (256 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 224 bytes ago (256 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 224 bytes ago (256 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 224 bytes ago (256 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Latency set to 2.50ms D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=352320 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=88141 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested volume: front-left: 21698 / 33% / -28.80 dB, front-right: 21698 / 33% / -28.80 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Got hardware volume: front-left: 21702 / 33% / -28.80 dB, front-right: 21702 / 33% / -28.80 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Calculated software volume: front-left: 65524 / 100% / -0.00 dB, front-right: 65524 / 100% / -0.00 dB (accurate-enough=yes) D: [alsa-sink-ALC887-VD Analog] sink.c: Volume not changing D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 224 bytes. I: [pulseaudio] sink-input.c: Freeing input 1 "Event Sound" D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 56 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 56 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 224 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 1189 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 224 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [pulseaudio] ratelimit.c: 5745 events suppressed D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full I: [pulseaudio] module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ... D: [pulseaudio] source.c: Suspend cause of source alsa_input.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending I: [alsa-source-ALC887-VD Analog] alsa-source.c: Device suspended... D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0. D: [alsa-sink-ALC887-VD Analog] ratelimit.c: 4082 events suppressed D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] ratelimit.c: 4416 events suppressed D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] ratelimit.c: 4311 events suppressed D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [pulseaudio] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] memblock.c: Pool full D: [alsa-sink-ALC887-VD Analog] sink-input.c: Requesting rewind due to corking D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 192 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 48 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 48 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 192 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 1283 D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 192 bytes on render memblockq. D: [alsa-sink-ALC887-VD Analog] sink-input.c: Have to rewind 96 bytes on implementor. D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (64 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (64 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (64 bytes ahead in playback buffer) D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (64 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (32 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] sink.c: Found underrun 384 bytes ago (32 bytes ahead in playback buffer) D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: hwbuf_unused=0 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: setting avail_min=88009 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested volume: front-left: 41086 / 63% / -12.17 dB, front-right: 41086 / 63% / -12.17 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Got hardware volume: front-left: 41350 / 63% / -12.00 dB, front-right: 41350 / 63% / -12.00 dB D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Calculated software volume: front-left: 65118 / 99% / -0.17 dB, front-right: 65118 / 99% / -0.17 dB (accurate-enough=yes) D: [alsa-sink-ALC887-VD Analog] sink.c: Volume going up to 41086 at 208825331 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Requested to rewind 352800 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Limited to 160 bytes. D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: before: 40 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: after: 40 D: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Rewound 160 bytes. D: [alsa-sink-ALC887-VD Analog] sink.c: Processing rewind... D: [alsa-sink-ALC887-VD Analog] sink.c: latency = 1224 D: [alsa-sink-ALC887-VD Analog] source.c: Processing rewind... D: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds. D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum. I: [pulseaudio] sink-input.c: Freeing input 0 "Event Sound" I: [pulseaudio] client.c: Freed 1 "Skype" I: [pulseaudio] protocol-native.c: Connection died. D: [alsa-sink-ALC887-VD Analog] sink.c: Volume change to 41086 at 208824500 was written 24 usec late I: [pulseaudio] module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ... D: [pulseaudio] sink.c: Suspend cause of sink alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending I: [alsa-sink-ALC887-VD Analog] alsa-sink.c: Device suspended... D: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum. D: [pulseaudio] module-udev-detect.c: /dev/snd/controlC0 is accessible: yes D: [pulseaudio] module-udev-detect.c: Resuming all sinks and sources of card alsa_card.pci-0000_00_1b.0.