Hello
I'm trying out a set up that uses rtp-modules and the loopback module.
The latencies seem ok, though they could be better, but after a minute
long session I start getting the following messages and the sound
deformed because of it.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=76986460 ri=75295321
D: module-rtp-recv.c: Write index deviates by 1739.96 ms, expected
500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 57834 Hz.
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 4740 bytes.
D: alsa-sink.c: before: 1185
D: alsa-sink.c: after: 1185
D: alsa-sink.c: Rewound 4740 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 4740 bytes on render memblockq.
D: sink-input.c: Have to rewind 4740 bytes on render memblockq.
D: source.c: Processing rewind...
Is there a way to prevent these underruns?
Reloading the modules helps for a while until the same deformation in
the sound come back. Also the log is filled with the followin errors and
warnings.
E: rtp.c: sendmsg() failed: Invalid argument
E: rtp.c: sendmsg() failed: Invalid argument
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
I don't have enough time to start digging the code. So I though I would
ask here if there were any easy fix?
Cheers,
Jukka
I: main.c: setrlimit(RLIMIT_NICE, (31, 31)) failed: Operation not permitted
I: main.c: setrlimit(RLIMIT_RTPRIO, (9, 9)) failed: Operation not permitted
D: core-rtclock.c: Timer slack is set to 50 us.
I: core-util.c: Failed to acquire high-priority scheduling: No such file or
directory
I: main.c: This is PulseAudio 0.9.19
D: main.c: Compilation host: i486-pc-linux-gnu
D: main.c: Compilation CFLAGS: -g -O2 -g -Wall -O3 -Wall -W -Wextra -pipe
-Wno-long-long -Winline -Wvla -Wno-overlength-strings
-Wunsafe-loop-optimizations -Wundef -Wformat=2 -Wlogical-op -Wsign-compare
-Wformat-security -Wmissing-include-dirs -Wformat-nonliteral
-Wold-style-definition -Wpointer-arith -Winit-self
-Wdeclaration-after-statement -Wfloat-equal -Wmissing-prototypes
-Wstrict-prototypes -Wredundant-decls -Wmissing-declarations -Wmissing-noreturn
-Wshadow -Wendif-labels -Wcast-align -Wstrict-aliasing=2 -Wwrite-strings
-Wno-unused-parameter -ffast-math -Wp,-D_FORTIFY_SOURCE=2 -fno-common
-fdiagnostics-show-option
D: main.c: Running on host: Linux i686 2.6.31-14-generic #48-Ubuntu SMP Fri Oct
16 14:04:26 UTC 2009
D: main.c: Found 2 CPUs.
I: main.c: Page size is 4096 bytes
D: main.c: Compiled with Valgrind support: no
D: main.c: Running in valgrind mode: no
D: main.c: Optimized build: yes
D: main.c: All asserts enabled.
I: main.c: Machine ID is 05b17a89f1263a612e97724a4aeedb0d.
I: main.c: Session ID is
05b17a89f1263a612e97724a4aeedb0d-1258318304.882918-1357487421.
I: main.c: Using runtime directory
/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-runtime.
I: main.c: Using state directory /home/mrpink/.pulse.
I: main.c: Using modules directory /usr/lib/pulse-0.9.19/modules.
I: main.c: Running in system mode: no
I: main.c: Fresh high-resolution timers available! Bon appetit!
I: cpu-x86.c: CPU flags: MMX SSE SSE2 SSE3 SSSE3
I: svolume_mmx.c: Initialising MMX optimized functions.
I: remap_mmx.c: Initialising MMX optimized remappers.
I: svolume_sse.c: Initialising SSE2 optimized functions.
I: remap_sse.c: Initialising SSE2 optimized remappers.
I: sconv_sse.c: Initialising SSE2 optimized conversions.
D: 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
D: database-tdb.c: Opened TDB database
'/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-device-volumes.tdb'
I: module-device-restore.c: Sucessfully opened database file
'/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-device-volumes'.
I: module.c: Loaded "module-device-restore" (index: #0; argument: "").
D: database-tdb.c: Opened TDB database
'/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-stream-volumes.tdb'
I: module-stream-restore.c: Sucessfully opened database file
'/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-stream-volumes'.
I: module.c: Loaded "module-stream-restore" (index: #1; argument: "").
D: database-tdb.c: Opened TDB database
'/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-card-database.tdb'
I: module-card-restore.c: Sucessfully opened database file
'/home/mrpink/.pulse/05b17a89f1263a612e97724a4aeedb0d-card-database'.
I: module.c: Loaded "module-card-restore" (index: #2; argument: "").
I: module.c: Loaded "module-augment-properties" (index: #3; argument: "").
D: cli-command.c: Checking for existance of
'/usr/lib/pulse-0.9.19/modules/module-udev-detect.so': success
D: module-udev-detect.c: /dev/snd/controlC0 is accessible: yes
D: module-udev-detect.c: /devices/pci0000:00/0000:00:1b.0/sound/card0 is busy:
no
D: module-udev-detect.c: Loading module-alsa-card with arguments 'device_id="0"
name="pci-0000_00_1b.0" card_name="alsa_card.pci-0000_00_1b.0" tsched=yes
ignore_dB=no card_properties="module-udev-detect.discovered=1"'
D: dbus-util.c: Successfully connected to D-Bus session bus
8bbc06e56f9c2db137c07f354b0069e1 as :1.84
D: reserve-wrap.c: Successfully acquired reservation lock on device 'Audio0'
...
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=68365649 ri=68116570
D: module-rtp-recv.c: Write index deviates by 307.39 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 43528 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=76605703 ri=75787539
D: module-rtp-recv.c: Write index deviates by 882.15 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 46898 Hz.
D: module-rtp-recv.c: Checking for dead streams ...
I: module-loopback.c: Loopback overall latency is 61.48 ms + 140.77 ms + 1.28
ms = 203.53 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 23560 bytes
I: module-loopback.c: Old rate 44101 Hz, new rate 44101 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=76986460 ri=75295321
D: module-rtp-recv.c: Write index deviates by 1739.96 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 57834 Hz.
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 4740 bytes.
D: alsa-sink.c: before: 1185
D: alsa-sink.c: after: 1185
D: alsa-sink.c: Rewound 4740 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 4740 bytes on render memblockq.
D: sink-input.c: Have to rewind 4740 bytes on render memblockq.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 4644 bytes on render memblockq.
D: sink-input.c: Have to rewind 4644 bytes on render memblockq.
D: source.c: Processing rewind...
reload rtp-modules:
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=5015721 ri=4810884
D: module-rtp-recv.c: Write index deviates by 270.21 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 42074 Hz.
I: module-loopback.c: Loopback overall latency is 50.30 ms + 197.46 ms + 1.17
ms = 248.93 ms
I: module-loopback.c: Should buffer 23416 bytes, buffered at minimum 23872 bytes
I: module-loopback.c: Old rate 43908 Hz, new rate 44111 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=10538410 ri=10039454
D: module-rtp-recv.c: Write index deviates by 545.09 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 42471 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=15605029 ri=14971156
D: module-rtp-recv.c: Write index deviates by 702.85 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 44260 Hz.
D: module-rtp-recv.c: Checking for dead streams ...
I: module-loopback.c: Loopback overall latency is 60.15 ms + 185.37 ms + 0.12
ms = 245.64 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 31036 bytes
I: module-loopback.c: Old rate 44111 Hz, new rate 44287 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=19953945 ri=19354722
D: module-rtp-recv.c: Write index deviates by 653.54 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 45614 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=24195330 ri=23810233
D: module-rtp-recv.c: Write index deviates by 450.56 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 45178 Hz.
I: module-loopback.c: Loopback overall latency is 64.66 ms + 137.87 ms + 0.14
ms = 202.67 ms
I: module-loopback.c: Should buffer 23616 bytes, buffered at minimum 23932 bytes
I: module-loopback.c: Old rate 44287 Hz, new rate 44107 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=29371899 ri=29040683
D: module-rtp-recv.c: Write index deviates by 397.52 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 44275 Hz.
I: module-loopback.c: Loopback overall latency is 46.32 ms + 156.60 ms + 0.01
ms = 202.93 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23196 bytes
I: module-loopback.c: Old rate 44093 Hz, new rate 44093 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=50170504 ri=49765351
D: module-rtp-recv.c: Write index deviates by 474.68 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 43843 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=55478126 ri=55003535
D: module-rtp-recv.c: Write index deviates by 521.88 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 44035 Hz.
D: module-rtp-recv.c: Checking for dead streams ...
I: module-loopback.c: Loopback overall latency is 63.89 ms + 140.77 ms + 0.00
ms = 204.66 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23444 bytes
I: module-loopback.c: Old rate 44093 Hz, new rate 44099 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=60174515 ri=59770636
D: module-rtp-recv.c: Write index deviates by 457.52 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 43661 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=65889865 ri=65310460
D: module-rtp-recv.c: Write index deviates by 642.61 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 44918 Hz.
I: module-loopback.c: Loopback overall latency is 53.00 ms + 152.04 ms + 0.00
ms = 205.04 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23744 bytes
I: module-loopback.c: Old rate 44099 Hz, new rate 44105 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=69114368 ri=68498152
D: module-rtp-recv.c: Write index deviates by 678.63 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 46493 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=71597159 ri=71167702
D: module-rtp-recv.c: Write index deviates by 478.41 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 46303 Hz.
D: module-rtp-recv.c: Checking for dead streams ...
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5316 bytes.
D: alsa-sink.c: before: 1329
D: alsa-sink.c: after: 1329
D: alsa-sink.c: Rewound 5316 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5316 bytes on render memblockq.
D: sink-input.c: Have to rewind 5316 bytes on render memblockq.
D: source.c: Processing rewind...
I: module-loopback.c: Loopback overall latency is 55.26 ms + 148.37 ms + 0.09
ms = 203.73 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 23656 bytes
I: module-loopback.c: Old rate 44105 Hz, new rate 44103 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=76385001 ri=76290521
D: module-rtp-recv.c: Write index deviates by 162.26 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 43325 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=87707447 ri=86527409
D: module-rtp-recv.c: Write index deviates by 1241.66 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 49866 Hz.
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5188 bytes.
D: alsa-sink.c: before: 1297
D: alsa-sink.c: after: 1297
D: alsa-sink.c: Rewound 5188 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5188 bytes on render memblockq.
D: sink-input.c: Have to rewind 5188 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5668 bytes.
D: alsa-sink.c: before: 1417
D: alsa-sink.c: after: 1417
D: alsa-sink.c: Rewound 5668 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5668 bytes on render memblockq.
D: sink-input.c: Have to rewind 5668 bytes on render memblockq.
D: source.c: Processing rewind...
I: module-loopback.c: Loopback overall latency is 66.02 ms + 135.55 ms + 0.99
ms = 202.56 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 23412 bytes
I: module-loopback.c: Old rate 44103 Hz, new rate 44098 Hz
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 4676 bytes.
D: alsa-sink.c: before: 1169
D: alsa-sink.c: after: 1169
D: alsa-sink.c: Rewound 4676 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 4676 bytes on render memblockq.
D: sink-input.c: Have to rewind 4676 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=87745348 ri=79586090
D: module-rtp-recv.c: Write index deviates by 8220.93 ms, expected 500.00 ms
D: module-rtp-recv.c: Hmmm, rate fix is too large (68098 Hz), not applying.
D: module-rtp-recv.c: Updated sampling rate to 49866 Hz.
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 4420 bytes.
D: alsa-sink.c: before: 1105
D: alsa-sink.c: after: 1105
D: alsa-sink.c: Rewound 4420 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 4420 bytes on render memblockq.
D: sink-input.c: Have to rewind 4420 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5412 bytes.
D: alsa-sink.c: before: 1353
D: alsa-sink.c: after: 1353
D: alsa-sink.c: Rewound 5412 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5412 bytes on render memblockq.
D: sink-input.c: Have to rewind 5412 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=87745348 ri=84193638
D: module-rtp-recv.c: Write index deviates by 3616.53 ms, expected 500.00 ms
D: module-rtp-recv.c: Hmmm, rate fix is too large (27487 Hz), not applying.
D: module-rtp-recv.c: Updated sampling rate to 49866 Hz.
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5252 bytes.
D: alsa-sink.c: before: 1313
D: alsa-sink.c: after: 1313
D: alsa-sink.c: Rewound 5252 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5252 bytes on render memblockq.
D: sink-input.c: Have to rewind 5252 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Checking for dead streams ...
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5252 bytes.
D: alsa-sink.c: before: 1313
D: alsa-sink.c: after: 1313
D: alsa-sink.c: Rewound 5252 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5252 bytes on render memblockq.
D: sink-input.c: Have to rewind 5252 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 6308 bytes.
D: alsa-sink.c: before: 1577
D: alsa-sink.c: after: 1577
D: alsa-sink.c: Rewound 6308 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 6308 bytes on render memblockq.
D: sink-input.c: Have to rewind 6308 bytes on render memblockq.
D: source.c: Processing rewind...
I: module-loopback.c: Loopback overall latency is 62.97 ms + 140.71 ms + 0.06
ms = 203.74 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23484 bytes
I: module-loopback.c: Old rate 44098 Hz, new rate 44100 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=88964424 ri=88576585
D: module-rtp-recv.c: Write index deviates by 439.68 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 49334 Hz.
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 5188 bytes.
D: alsa-sink.c: before: 1297
D: alsa-sink.c: after: 1297
D: alsa-sink.c: Rewound 5188 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 5188 bytes on render memblockq.
D: sink-input.c: Have to rewind 5188 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 6308 bytes.
D: alsa-sink.c: before: 1577
D: alsa-sink.c: after: 1577
D: alsa-sink.c: Rewound 6308 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 6308 bytes on render memblockq.
D: sink-input.c: Have to rewind 6308 bytes on render memblockq.
D: source.c: Processing rewind...
I: client.c: Created 9 "Native client (UNIX socket client)"
D: protocol-native.c: Protocol version: remote 16, local 16
I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
D: module-augment-properties.c: Looking for .desktop file for pactl
I: module.c: Unloading "module-rtp-recv" (index: #20).
I: module-rtp-recv.c: Freeing session 'PulseAudio RTP Stream on poro-laptop'
D: alsa-sink.c: Latency set to 66.67ms
D: alsa-sink.c: hwbuf_unused=53780
D: alsa-sink.c: setting avail_min=15503
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 7588 bytes.
D: alsa-sink.c: before: 1897
D: alsa-sink.c: after: 1897
D: alsa-sink.c: Rewound 7588 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 7588 bytes on render memblockq.
D: source.c: Processing rewind...
I: sink-input.c: Freeing input 2 "RTP Stream (PulseAudio RTP Stream on
poro-laptop)"
I: module.c: Unloaded "module-rtp-recv" (index: #20).
I: client.c: Freed 9 "pactl"
I: protocol-native.c: Connection died.
I: module-loopback.c: Loopback overall latency is 45.62 ms + 157.94 ms + 0.13
ms = 203.68 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23572 bytes
I: module-loopback.c: Old rate 44100 Hz, new rate 44101 Hz
I: client.c: Created 10 "Native client (UNIX socket client)"
I: alsa-source.c: Overrun!
D: protocol-native.c: Protocol version: remote 16, local 16
I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
I: alsa-source.c: Increasing minimal latency to 2.00 ms
D: alsa-source.c: latency set to 7.26ms
D: alsa-source.c: hwbuf_unused=64260
D: alsa-source.c: setting avail_min=160
D: module-augment-properties.c: Looking for .desktop file for pactl
I: module.c: Unloading "module-rtp-send" (index: #21).
D: alsa-source.c: latency set to 66.67ms
D: alsa-source.c: hwbuf_unused=53780
D: alsa-source.c: setting avail_min=2765
I: source-output.c: Freeing output 2 "RTP Monitor Stream"
I: module.c: Unloaded "module-rtp-send" (index: #21).
I: client.c: Freed 10 "pactl"
I: protocol-native.c: Connection died.
I: client.c: Created 11 "Native client (UNIX socket client)"
D: protocol-native.c: Protocol version: remote 16, local 16
I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
D: module-augment-properties.c: Looking for .desktop file for pactl
I: module.c: Loaded "module-rtp-recv" (index: #22; argument: "").
I: client.c: Freed 11 "pactl"
I: protocol-native.c: Connection died.
D: module-stream-restore.c: Not restoring device for stream
sink-input-by-media-role:stream, because already set.
D: module-intended-roles.c: Not setting device for stream RTP Stream
(PulseAudio RTP Stream on poro-laptop), because already set.
D: module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo
becomes busy.
I: resampler.c: Using resampler 'speex-float-1'
I: resampler.c: Using float32le as working format.
I: resampler.c: Choosing speex quality setting 1.
D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4,
prebuf=0, minreq=1 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432,
base=4, prebuf=0, minreq=4 maxrewind=0
I: sink-input.c: Created input 3 "RTP Stream (PulseAudio RTP Stream on
poro-laptop)" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec
s16be 2ch 44100Hz and channel map front-left,front-right
I: sink-input.c: media.role = "stream"
I: sink-input.c: media.name = "RTP Stream (PulseAudio RTP Stream on
poro-laptop)"
I: sink-input.c: rtp.session = "PulseAudio RTP Stream on poro-laptop"
I: sink-input.c: rtp.origin = "poro 3467312434 0 IN IP4 192.168.2.66"
I: sink-input.c: rtp.payload = "10"
I: sink-input.c: module-stream-restore.id =
"sink-input-by-media-role:stream"
D: memblockq.c: memblockq requested: maxlength=41943040, tlength=41943040,
base=4, prebuf=44100, minreq=0 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=41943040, tlength=41943040,
base=4, prebuf=44100, minreq=4 maxrewind=0
D: alsa-sink.c: Latency set to 66.67ms
D: alsa-sink.c: hwbuf_unused=53780
D: alsa-sink.c: setting avail_min=15503
I: module-rtp-recv.c: New session 'PulseAudio RTP Stream on poro-laptop'
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 7748 bytes.
D: alsa-sink.c: before: 1937
D: alsa-sink.c: after: 1937
D: alsa-sink.c: Rewound 7748 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 7748 bytes on render memblockq.
D: sink-input.c: Have to rewind 7748 bytes on render memblockq.
D: source.c: Processing rewind...
I: client.c: Created 12 "Native client (UNIX socket client)"
D: protocol-native.c: Protocol version: remote 16, local 16
I: protocol-native.c: Got credentials: uid=1000 gid=1000 success=1
D: protocol-native.c: SHM possible: yes
D: protocol-native.c: Negotiated SHM: yes
D: module-augment-properties.c: Looking for .desktop file for pactl
D: module-stream-restore.c: Not restoring device for stream
source-output-by-media-name:RTP Monitor Stream, because already set
D: module-intended-roles.c: Not setting device for stream RTP Monitor Stream,
because already set.
D: module-suspend-on-idle.c: Source alsa_input.pci-0000_00_1b.0.analog-stereo
becomes busy.
I: resampler.c: Forcing resampler 'copy', because of fixed, identical sample
rates.
I: resampler.c: Using resampler 'copy'
I: resampler.c: Using s16le as working format.
D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4,
prebuf=0, minreq=1 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432,
base=4, prebuf=0, minreq=4 maxrewind=0
I: source-output.c: Created output 3 "RTP Monitor Stream" on
alsa_input.pci-0000_00_1b.0.analog-stereo with sample spec s16be 2ch 44100Hz
and channel map front-left,front-right
I: source-output.c: media.name = "RTP Monitor Stream"
I: source-output.c: rtp.destination = "224.0.0.56"
I: source-output.c: rtp.mtu = "1280"
I: source-output.c: rtp.port = "46242"
I: source-output.c: rtp.ttl = "1"
I: source-output.c: module-stream-restore.id =
"source-output-by-media-name:RTP Monitor Stream"
I: module-rtp-send.c: Configured source latency of 7 ms.
D: memblockq.c: memblockq requested: maxlength=174080, tlength=174080, base=4,
prebuf=1, minreq=0 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=174080, tlength=174080, base=4,
prebuf=4, minreq=4 maxrewind=0
I: module-rtp-send.c: RTP stream initialized with mtu 1280 on 224.0.0.56:46242
ttl=1, SSRC=0x400a4264, payload=10, initial sequence #26152
I: module-rtp-send.c: SDP-Data:
I: module-rtp-send.c: v=0
I: module-rtp-send.c: o=mrpink 3467313274 0 IN IP4 192.168.2.67
I: module-rtp-send.c: s=PulseAudio RTP Stream on ubuntu.ubuntu-domain
I: module-rtp-send.c: c=IN IP4 224.0.0.56
I: module-rtp-send.c: t=3467313274 0
I: module-rtp-send.c: a=recvonly
I: module-rtp-send.c: m=audio 46242 RTP/AVP 10
I: module-rtp-send.c: a=rtpmap:10 L16/44100/2
I: module-rtp-send.c: a=type:broadcast
I: module-rtp-send.c: EOF
D: alsa-source.c: latency set to 7.26ms
D: alsa-source.c: hwbuf_unused=64260
D: alsa-source.c: setting avail_min=160
D: alsa-source.c: latency set to 7.26ms
D: alsa-source.c: hwbuf_unused=64260
D: alsa-source.c: setting avail_min=160
I: alsa-source.c: Overrun!
I: alsa-source.c: Increasing minimal latency to 4.00 ms
D: alsa-source.c: latency set to 7.26ms
D: alsa-source.c: hwbuf_unused=64260
D: alsa-source.c: setting avail_min=160
I: module.c: Loaded "module-rtp-send" (index: #23; argument: "").
I: client.c: Freed 12 "pactl"
I: protocol-native.c: Connection died.
I: alsa-source.c: Overrun!
I: alsa-source.c: Increasing minimal latency to 8.00 ms
D: alsa-source.c: latency set to 8.00ms
D: alsa-source.c: hwbuf_unused=64128
D: alsa-source.c: setting avail_min=177
I: module-loopback.c: Loopback overall latency is 55.52 ms + 147.93 ms + 0.00
ms = 203.45 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 13268 bytes
I: module-loopback.c: Old rate 44101 Hz, new rate 43844 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=5011073 ri=4789115
D: module-rtp-recv.c: Write index deviates by 271.22 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 42083 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=10514359 ri=10037307
D: module-rtp-recv.c: Write index deviates by 542.86 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 42461 Hz.
I: module-loopback.c: Loopback overall latency is 48.38 ms + 213.89 ms + 0.32
ms = 262.60 ms
I: module-loopback.c: Should buffer 23376 bytes, buffered at minimum 24120 bytes
I: module-loopback.c: Old rate 43844 Hz, new rate 44118 Hz
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=24170073 ri=23783973
D: module-rtp-recv.c: Write index deviates by 448.98 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 45175 Hz.
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=29368967 ri=29035528
D: module-rtp-recv.c: Write index deviates by 396.45 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 44262 Hz.
I: module-rtp-recv.c: Freeing session 'PulseAudio RTP Stream on poro-laptop'
D: alsa-sink.c: Latency set to 66.67ms
D: alsa-sink.c: hwbuf_unused=53780
D: alsa-sink.c: setting avail_min=15503
D: alsa-sink.c: Requested to rewind 65536 bytes.
I: sink-input.c: Freeing input 3 "RTP Stream (PulseAudio RTP Stream on
poro-laptop)"
D: alsa-sink.c: Limited to 6980 bytes.
D: alsa-sink.c: before: 1745
D: alsa-sink.c: after: 1745
D: alsa-sink.c: Rewound 6980 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 6980 bytes on render memblockq.
D: source.c: Processing rewind...
I: module-loopback.c: Loopback overall latency is 63.89 ms + 138.64 ms + 0.00
ms = 202.53 ms
I: module-loopback.c: Should buffer 23640 bytes, buffered at minimum 24100 bytes
I: module-loopback.c: Old rate 44338 Hz, new rate 44111 Hz
D: module-stream-restore.c: Not restoring device for stream
sink-input-by-media-role:stream, because already set.
D: module-intended-roles.c: Not setting device for stream RTP Stream
(PulseAudio RTP Stream on poro-laptop), because already set.
D: module-suspend-on-idle.c: Sink alsa_output.pci-0000_00_1b.0.analog-stereo
becomes busy.
I: resampler.c: Using resampler 'speex-float-1'
I: resampler.c: Using float32le as working format.
I: resampler.c: Choosing speex quality setting 1.
D: memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4,
prebuf=0, minreq=1 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432,
base=4, prebuf=0, minreq=4 maxrewind=0
I: sink-input.c: Created input 4 "RTP Stream (PulseAudio RTP Stream on
poro-laptop)" on alsa_output.pci-0000_00_1b.0.analog-stereo with sample spec
s16be 2ch 44100Hz and channel map front-left,front-right
I: sink-input.c: media.role = "stream"
I: sink-input.c: media.name = "RTP Stream (PulseAudio RTP Stream on
poro-laptop)"
I: sink-input.c: rtp.session = "PulseAudio RTP Stream on poro-laptop"
I: sink-input.c: rtp.origin = "poro 3467313308 0 IN IP4 192.168.2.66"
I: sink-input.c: rtp.payload = "10"
I: sink-input.c: module-stream-restore.id =
"sink-input-by-media-role:stream"
D: memblockq.c: memblockq requested: maxlength=41943040, tlength=41943040,
base=4, prebuf=44100, minreq=0 maxrewind=0
D: memblockq.c: memblockq sanitized: maxlength=41943040, tlength=41943040,
base=4, prebuf=44100, minreq=4 maxrewind=0
D: alsa-sink.c: Latency set to 66.67ms
D: alsa-sink.c: hwbuf_unused=53780
D: alsa-sink.c: setting avail_min=15503
I: module-rtp-recv.c: New session 'PulseAudio RTP Stream on poro-laptop'
D: module-rtp-recv.c: Requesting rewind due to end of underrun
D: alsa-sink.c: Requested to rewind 65536 bytes.
D: alsa-sink.c: Limited to 7716 bytes.
D: alsa-sink.c: before: 1929
D: alsa-sink.c: after: 1929
D: alsa-sink.c: Rewound 7716 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 7716 bytes on render memblockq.
D: sink-input.c: Have to rewind 7716 bytes on render memblockq.
D: source.c: Processing rewind...
D: module-rtp-recv.c: Checking for dead streams ...
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=5004024 ri=4789115
D: module-rtp-recv.c: Write index deviates by 275.26 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 42118 Hz.
I: module-loopback.c: Loopback overall latency is 63.49 ms + 137.22 ms + 0.00
ms = 200.71 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 23040 bytes
I: module-loopback.c: Old rate 44111 Hz, new rate 44088 Hz
D: module-rtp-recv.c: Checking for dead streams ...
D: module-rtp-recv.c: Updating sample rate
D: module-rtp-recv.c: wi=24204714 ri=23825976
D: module-rtp-recv.c: Write index deviates by 448.43 ms, expected 500.00 ms
D: module-rtp-recv.c: Updated sampling rate to 45103 Hz.
I: module-loopback.c: Loopback overall latency is 56.26 ms + 149.74 ms + 0.23
ms = 206.22 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23632 bytes
I: module-loopback.c: Old rate 44089 Hz, new rate 44103 Hz
I: module-rtp-recv.c: Freeing session 'PulseAudio RTP Stream on poro-laptop'
D: alsa-sink.c: Latency set to 66.67ms
D: alsa-sink.c: hwbuf_unused=53780
D: alsa-sink.c: setting avail_min=15503
D: alsa-sink.c: Requested to rewind 65536 bytes.
I: sink-input.c: Freeing input 4 "RTP Stream (PulseAudio RTP Stream on
poro-laptop)"
D: alsa-sink.c: Limited to 7972 bytes.
D: alsa-sink.c: before: 1993
D: alsa-sink.c: after: 1993
D: alsa-sink.c: Rewound 7972 bytes.
D: sink.c: Processing rewind...
D: sink-input.c: Have to rewind 7972 bytes on render memblockq.
D: source.c: Processing rewind...
I: module-loopback.c: Loopback overall latency is 65.12 ms + 138.88 ms + 1.30
ms = 205.31 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 23872 bytes
I: module-loopback.c: Old rate 44103 Hz, new rate 44108 Hz
D: module-rtp-recv.c: Checking for dead streams ...
I: module-loopback.c: Loopback overall latency is 54.17 ms + 149.38 ms + 0.09
ms = 203.64 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 24140 bytes
I: module-loopback.c: Old rate 44108 Hz, new rate 44115 Hz
I: module-loopback.c: Loopback overall latency is 65.98 ms + 133.45 ms + 0.70
ms = 200.12 ms
I: module-loopback.c: Should buffer 23520 bytes, buffered at minimum 23540 bytes
I: module-loopback.c: Old rate 44115 Hz, new rate 44100 Hz
D: module-rtp-recv.c: Checking for dead streams ...
I: module-loopback.c: Loopback overall latency is 50.17 ms + 149.80 ms + 0.21
ms = 200.17 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23540 bytes
I: module-loopback.c: Old rate 44100 Hz, new rate 44100 Hz
I: module-loopback.c: Loopback overall latency is 50.63 ms + 149.61 ms + 0.00
ms = 200.25 ms
I: module-loopback.c: Should buffer 23512 bytes, buffered at minimum 23544 bytes
I: module-loopback.c: Old rate 44100 Hz, new rate 44100 Hz
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: sap.c: sendmsg() failed: Invalid argument
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
E: rtp.c: sendmsg() failed: Invalid argument
E: rtp.c: sendmsg() failed: Invalid argument
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
W: module-rtp-send.c: Failed to push chunk into memblockq.
E: rtp.c: sendmsg() failed: Invalid argument
E: rtp.c: sendmsg() failed: Invalid argument
_______________________________________________
pulseaudio-discuss mailing list
pulseaudio-discuss@mail.0pointer.de
https://tango.0pointer.de/mailman/listinfo/pulseaudio-discuss
