Attachment 82923 Details for Bug 66884 – Log of the *local* PulseAudio server, with --log-level=debug, when the issue occurred

Log of the *local* PulseAudio server, with --log-level=debug, when the issue occurred

pulseaudio_network_sound_latency_bug_debug_log (text/plain), 7.58 KB, created by Kerrick Staley on 2013-07-24 10:45:52 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Kerrick Staley

Created: 2013-07-24 10:45:52 UTC

Size: 7.58 KB

patch

obsolete

>I: [lt-pulseaudio] client.c: Created 4 "Native client (UNIX socket client)"
>D: [lt-pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Client added for object /org/pulseaudio/core1/client4
>D: [lt-pulseaudio] protocol-native.c: Protocol version: remote 29, local 29
>I: [lt-pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
>D: [lt-pulseaudio] protocol-native.c: SHM possible: yes
>D: [lt-pulseaudio] protocol-native.c: Negotiated SHM: yes
>D: [lt-pulseaudio] module-augment-properties.c: Looking for .desktop file for vlc
>D: [lt-pulseaudio] module-augment-properties.c: Found /usr/share/applications/vlc.desktop.
>I: [lt-pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-media-role:video.
>D: [lt-pulseaudio] module-intended-roles.c: Not setting device for stream audio stream, because already set.
>D: [lt-pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "48000"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
>I: [lt-pulseaudio] sink-input.c: Trying to change sample rate
>I: [lt-pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:video.
>I: [lt-pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:video.
>D: [lt-pulseaudio] module-suspend-on-idle.c: Sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
>D: [lt-pulseaudio] sink.c: Suspend cause of sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0000, resuming
>D: [lt-pulseaudio] module-suspend-on-idle.c: Sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
>I: [lt-pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates.
>I: [lt-pulseaudio] resampler.c: Using resampler 'copy'
>I: [lt-pulseaudio] resampler.c: Using s16le as working format.
>D: [lt-pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0
>D: [lt-pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0
>I: [lt-pulseaudio] sink-input.c: Created input 0 "audio stream" on tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec float32le 2ch 44100Hz and channel map front-left,front-right
>I: [lt-pulseaudio] sink-input.c:     media.role = "video"
>I: [lt-pulseaudio] sink-input.c:     media.name = "audio stream"
>I: [lt-pulseaudio] sink-input.c:     application.name = "VLC media player (LibVLC 2.2.0-git)"
>I: [lt-pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
>I: [lt-pulseaudio] sink-input.c:     native-protocol.version = "29"
>I: [lt-pulseaudio] sink-input.c:     application.id = "org.VideoLAN.VLC"
>I: [lt-pulseaudio] sink-input.c:     application.version = "2.2.0-git"
>I: [lt-pulseaudio] sink-input.c:     application.icon_name = "vlc"
>I: [lt-pulseaudio] sink-input.c:     application.language = "en_US.utf8"
>I: [lt-pulseaudio] sink-input.c:     application.process.id = "20457"
>I: [lt-pulseaudio] sink-input.c:     application.process.user = "kerrick"
>I: [lt-pulseaudio] sink-input.c:     application.process.host = "kerrick-Gazelle2"
>I: [lt-pulseaudio] sink-input.c:     application.process.binary = "vlc"
>I: [lt-pulseaudio] sink-input.c:     window.x11.display = ":0"
>I: [lt-pulseaudio] sink-input.c:     application.process.machine_id = "kerrick-Gazelle2"
>I: [lt-pulseaudio] sink-input.c:     application.process.session_id = "1"
>I: [lt-pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-media-role:video"
>I: [lt-pulseaudio] protocol-native.c: Requested tlength=130.61 ms, minreq=43.54 ms
>D: [lt-pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq.
>D: [lt-pulseaudio] protocol-native.c: Requested latency=43.54 ms, Received latency=250.00 ms
>D: [lt-pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=118920, base=8, prebuf=0, minreq=15360 maxrewind=0
>D: [lt-pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=118920, base=8, prebuf=0, minreq=15360 maxrewind=0
>I: [lt-pulseaudio] protocol-native.c: Final latency 587.07 ms = 250.00 ms + 2*43.54 ms + 250.00 ms
>D: [lt-pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream0
>D: [lt-pulseaudio] module-tunnel.c: Server reports playback started.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] sink-input.c: Requesting rewind due to uncorking
>D: [lt-pulseaudio] module-suspend-on-idle.c: Sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo becomes busy, resuming.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] protocol-native.c: Requesting rewind due to end of underrun.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>[... and so on until I stop the video ...]
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] protocol-native.c: Implicit underrun of 'audio stream'
>D: [module-tunnel] protocol-native.c: Requesting rewind due to rewrite.
>D: [module-tunnel] sink-input.c: Requesting rewind due to corking
>D: [lt-pulseaudio] module-suspend-on-idle.c: Sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo becomes idle, timeout in 5 seconds.
>I: [lt-pulseaudio] module-suspend-on-idle.c: Sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo idle for too long, suspending ...
>D: [lt-pulseaudio] sink.c: Suspend cause of sink tunnel.kerrick-ThinkPad.local.alsa_output.pci-0000_00_1b.0.analog-stereo is 0x0004, suspending

Actions: View

Attachments on bug 66884: 82923

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.