Attachment 139645 Details for Bug 106579 – connection log file

connection log file

raop_volume_1.log (text/plain), 12.16 KB, created by Fina Wilke on 2018-05-19 21:12:54 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Fina Wilke

Created: 2018-05-19 21:12:54 UTC

Size: 12.16 KB

patch

obsolete

>I: [pulseaudio] module-stream-restore.c: Restoring device for stream sink-input-by-media-role:music.
>D: [pulseaudio] module-intended-roles.c: Not setting device for stream Playback Stream, because already set.
>D: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\""  format.channels = "2"  format.rate = "44100"  format.channel_map = "\"front-left,front-right\""
>I: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:music.
>I: [pulseaudio] module-stream-restore.c: Restoring mute state for sink input sink-input-by-media-role:music.
>D: [pulseaudio] module-suspend-on-idle.c: Sink raop_output.Denon-AVR-X1400H--------------.local becomes busy, resuming.
>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 "Playback Stream" on raop_output.Denon-AVR-X1400H--------------.local with sample spec s16le 2ch 44100Hz and channel map front-left,front-right
>I: [pulseaudio] sink-input.c:     media.name = "Playback Stream"
>I: [pulseaudio] sink-input.c:     application.name = "Lollypop"
>I: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
>I: [pulseaudio] sink-input.c:     native-protocol.version = "32"
>I: [pulseaudio] sink-input.c:     media.role = "music"
>I: [pulseaudio] sink-input.c:     application.icon_name = "org.gnome.Lollypop"
>I: [pulseaudio] sink-input.c:     application.process.id = "3"
>I: [pulseaudio] sink-input.c:     application.process.user = "fina"
>I: [pulseaudio] sink-input.c:     application.process.host = "samarium"
>I: [pulseaudio] sink-input.c:     application.process.binary = "python3.5"
>I: [pulseaudio] sink-input.c:     application.language = "en_US.UTF-8"
>I: [pulseaudio] sink-input.c:     window.x11.display = ":99.0"
>I: [pulseaudio] sink-input.c:     application.process.machine_id = "samarium"
>I: [pulseaudio] sink-input.c:     application.process.session_id = "55"
>I: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-media-role:music"
>I: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 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=90,00 ms, Received latency=250,00 ms
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=47628, base=4, prebuf=0, minreq=1764 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=47628, base=4, prebuf=0, minreq=1764 maxrewind=0
>I: [pulseaudio] protocol-native.c: Final latency 520,00 ms = 250,00 ms + 2*10,00 ms + 250,00 ms
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [pulseaudio] sink-input.c: Sink input 1: proplist[media.name]: (data) -> (data)
>D: [pulseaudio] sink-input.c: Sink input 1: proplist[media.title]: (unset) -> (data)
>D: [pulseaudio] sink-input.c: Sink input 1: proplist[media.artist]: (unset) -> (data)
>D: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Can't stream, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] protocol-native.c: Requesting rewind due to end of underrun.
>D: [raop-sink-[192.168.1.52]:5000] sink-input.c: Requesting rewind due to uncorking
>D: [pulseaudio] module-suspend-on-idle.c: Sink raop_output.Denon-AVR-X1400H--------------.local becomes busy, resuming.
>D: [raop-sink-[192.168.1.52]:5000] raop-sink.c: RAOP: RUNNING
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Not alive, connection not established yet...
>D: [raop-sink-[192.168.1.52]:5000] rtsp_client.c: Attempting to connect to server '192.168.1.52:5000'
>D: [pulseaudio] rtsp_client.c: Established RTSP connection from local ip 192.168.1.110
>D: [pulseaudio] raop-client.c: RAOP: CONNECTED
>D: [pulseaudio] rtsp_client.c: Sending command: ANNOUNCE
>D: [pulseaudio] rtsp_client.c: Full response received. Dispatching
>D: [pulseaudio] raop-client.c: RAOP: ANNOUNCE
>D: [pulseaudio] raop-client.c: Socket bound to port 6001 (SOCK_DGRAM)
>D: [pulseaudio] raop-client.c: Socket bound to port 6002 (SOCK_DGRAM)
>D: [pulseaudio] rtsp_client.c: Sending command: SETUP
>D: [pulseaudio] module-intended-roles.c: Not setting device for stream Peak detect, because already set.
>D: [pulseaudio] source-output.c: Negotiated format: pcm, format.sample_format = "\"float32le\""  format.rate = "25"  format.channels = "1"  format.channel_map = "\"mono\""
>I: [pulseaudio] source-output.c: Trying to change sample rate
>I: [pulseaudio] source.c: Cannot update rate, SOURCE_IS_RUNNING, will keep using 44100 Hz
>D: [pulseaudio] module-suspend-on-idle.c: Sink raop_output.Denon-AVR-X1400H--------------.local becomes busy, resuming.
>D: [pulseaudio] resampler.c: Resampler:
>D: [pulseaudio] resampler.c:   rate 44100 -> 25 (method peaks)
>D: [pulseaudio] resampler.c:   format s16le -> float32le (intermediate s16le)
>D: [pulseaudio] resampler.c:   channels 2 -> 1 (resampling 1)
>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
>I: [pulseaudio] remap.c: Using stereo to mono remapping
>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 7 "Peak detect" on raop_output.Denon-AVR-X1400H--------------.local.monitor with sample spec float32le 1ch 25Hz and channel map mono
>I: [pulseaudio] source-output.c:     media.name = "Peak detect"
>I: [pulseaudio] source-output.c:     application.name = "PulseAudio Volume Control"
>I: [pulseaudio] source-output.c:     native-protocol.peer = "UNIX socket client"
>I: [pulseaudio] source-output.c:     native-protocol.version = "32"
>I: [pulseaudio] source-output.c:     application.id = "org.PulseAudio.pavucontrol"
>I: [pulseaudio] source-output.c:     application.icon_name = "audio-card"
>I: [pulseaudio] source-output.c:     application.version = "3.0"
>I: [pulseaudio] source-output.c:     application.process.id = "3451"
>I: [pulseaudio] source-output.c:     application.process.user = "fina"
>I: [pulseaudio] source-output.c:     application.process.host = "samarium"
>I: [pulseaudio] source-output.c:     application.process.binary = "pavucontrol"
>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 = "f3a6f7fb71dd4eec975ea359f3587cab"
>I: [pulseaudio] source-output.c:     application.process.session_id = "55"
>I: [pulseaudio] source-output.c:     module-stream-restore.id = "source-output-by-application-id:org.PulseAudio.pavucontrol"
>D: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=0, base=4, prebuf=1, minreq=0 maxrewind=0
>D: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=4194304, base=4, prebuf=4, minreq=4 maxrewind=0
>I: [pulseaudio] protocol-native.c: Final latency 490,00 ms = 240,00 ms + 250,00 ms
>D: [pulseaudio] rtsp_client.c: Full response received. Dispatching
>D: [pulseaudio] raop-client.c: RAOP: SETUP
>D: [pulseaudio] raop-client.c: Connected to 192.168.1.52 on port 51111 (SOCK_DGRAM)
>D: [pulseaudio] raop-client.c: Connected to 192.168.1.52 on port 38257 (SOCK_DGRAM)
>D: [pulseaudio] raop-client.c: Connected to 192.168.1.52 on port 56145 (SOCK_DGRAM)
>D: [pulseaudio] raop-client.c: Connection established (UDP;control_port=38257;timing_port=56145)
>D: [pulseaudio] raop-sink.c: State change recieved, informing IO thread...
>D: [pulseaudio] rtsp_client.c: Sending command: RECORD
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Received UDP timing packet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Sending timing packet at 9488541803209278136
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Received UDP timing packet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Sending timing packet at 9488541803213650413
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Received UDP timing packet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Sending timing packet at 9488541803217876661
>D: [pulseaudio] rtsp_client.c: Full response received. Dispatching
>D: [pulseaudio] raop-client.c: RAOP: RECORD
>D: [pulseaudio] raop-sink.c: State change recieved, informing IO thread...
>D: [raop-sink-[192.168.1.52]:5000] raop-sink.c: Volume adjusted: orig=17148 adjusted=32449
>D: [raop-sink-[192.168.1.52]:5000] raop-sink.c: Requested volume: front-left: 17148 /  26%,   front-right: 17148 /  26%
>D: [raop-sink-[192.168.1.52]:5000] raop-sink.c: Got hardware volume: front-left: 32449 /  50%,   front-right: 32449 /  50%
>D: [raop-sink-[192.168.1.52]:5000] raop-sink.c: Calculated software volume: front-left: 34633 /  53% / -16,62 dB,   front-right: 34633 /  53% / -16,62 dB
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: volume=32449 db=-18,316717
>D: [raop-sink-[192.168.1.52]:5000] rtsp_client.c: Sending command: SET_PARAMETER
>D: [pulseaudio] rtsp_client.c: Full response received. Dispatching
>D: [pulseaudio] raop-client.c: RAOP: SET_PARAMETER
>I: [alsa-source-ALC257 Analog] alsa-source.c: Overrun!
>I: [alsa-source-ALC257 Analog] alsa-source.c: Increasing wakeup watermark to 35,99 ms
>I: [alsa-source-ALC257 Analog] alsa-source.c: Overrun!
>I: [alsa-source-ALC257 Analog] alsa-source.c: Increasing minimal latency to 56,00 ms
>D: [alsa-source-ALC257 Analog] alsa-source.c: latency set to 56,00ms
>D: [alsa-source-ALC257 Analog] alsa-source.c: hwbuf_unused=342924
>D: [alsa-source-ALC257 Analog] alsa-source.c: setting avail_min=883
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Received UDP timing packet...
>D: [raop-sink-[192.168.1.52]:5000] raop-client.c: Sending timing packet at 9488541813291597899
>

Actions: View

Attachments on bug 106579: 139645

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.