Attachment 96262 Details for Bug 76532 – Output of loading skype without "PULSE_LATENCY_MSEC=30"

Output of loading skype without "PULSE_LATENCY_MSEC=30"

without-latency.log (text/plain), 32.42 KB, created by Ross Lagerwall on 2014-03-23 23:14:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ross Lagerwall

Created: 2014-03-23 23:14:37 UTC

Size: 32.42 KB

patch

obsolete

>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.

Actions: View

Attachments on bug 76532: 96261 | 96262

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.