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