[INFO] crate srt-transport 0.2.0 is already in cache [INFO] extracting crate srt-transport 0.2.0 into work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/srt-transport/0.2.0 [INFO] extracting crate srt-transport 0.2.0 into work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/srt-transport/0.2.0 [INFO] validating manifest of srt-transport-0.2.0 on toolchain master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54 [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] validating manifest of srt-transport-0.2.0 on toolchain try#7fea0229842e79db1159ea58ad9a653b288efee9 [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started frobbing srt-transport-0.2.0 [INFO] finished frobbing srt-transport-0.2.0 [INFO] frobbed toml for srt-transport-0.2.0 written to work/ex/pr-57967/sources/master#c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54/reg/srt-transport/0.2.0/Cargo.toml [INFO] started frobbing srt-transport-0.2.0 [INFO] finished frobbing srt-transport-0.2.0 [INFO] frobbed toml for srt-transport-0.2.0 written to work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/srt-transport/0.2.0/Cargo.toml [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"` [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"` [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+c1c3c4e95b69dfeaca5c5db6c622d7f90ad30a54-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] running `"/mnt/big/crater/work/local/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] testing srt-transport-0.2.0 against try#7fea0229842e79db1159ea58ad9a653b288efee9 for pr-57967 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-7/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/srt-transport/0.2.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "build" "--frozen"` [INFO] [stdout] ed4f6c18fa08cc576d5d5100a89ddab88df8bbf69ba61c7cb24e1e8d373da505 [INFO] running `"docker" "start" "-a" "ed4f6c18fa08cc576d5d5100a89ddab88df8bbf69ba61c7cb24e1e8d373da505"` [INFO] [stderr] Compiling futures-timer v0.1.1 [INFO] [stderr] Compiling simple_logger v0.5.0 [INFO] [stderr] Compiling srt-transport v0.2.0 (/opt/crater/workdir) [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:175:39 [INFO] [stderr] | [INFO] [stderr] 175 | let udt_version = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(deprecated)] on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:176:58 [INFO] [stderr] | [INFO] [stderr] 176 | let sock_type = SocketType::from_i32(buf.get_i32::())?; [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:177:50 [INFO] [stderr] | [INFO] [stderr] 177 | let init_seq_num = SeqNumber(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:178:43 [INFO] [stderr] | [INFO] [stderr] 178 | let max_packet_size = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:179:41 [INFO] [stderr] | [INFO] [stderr] 179 | let max_flow_size = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:180:68 [INFO] [stderr] | [INFO] [stderr] 180 | let connection_type = ConnectionType::from_i32(buf.get_i32::())?; [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:181:46 [INFO] [stderr] | [INFO] [stderr] 181 | let socket_id = SocketID(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:182:38 [INFO] [stderr] | [INFO] [stderr] 182 | let syn_cookie = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:212:48 [INFO] [stderr] | [INFO] [stderr] 212 | let ack_number = SeqNumber(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:217:34 [INFO] [stderr] | [INFO] [stderr] 217 | Some(buf.get_i32::()) [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:245:40 [INFO] [stderr] | [INFO] [stderr] 245 | loss_info.push(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:295:22 [INFO] [stderr] | [INFO] [stderr] 295 | into.put_i32::(c.udt_version); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:296:22 [INFO] [stderr] | [INFO] [stderr] 296 | into.put_i32::(c.sock_type.to_i32()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:297:22 [INFO] [stderr] | [INFO] [stderr] 297 | into.put_i32::(c.init_seq_num.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:298:22 [INFO] [stderr] | [INFO] [stderr] 298 | into.put_i32::(c.max_packet_size); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:299:22 [INFO] [stderr] | [INFO] [stderr] 299 | into.put_i32::(c.max_flow_size); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:300:22 [INFO] [stderr] | [INFO] [stderr] 300 | into.put_i32::(c.connection_type.to_i32()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:301:22 [INFO] [stderr] | [INFO] [stderr] 301 | into.put_i32::(c.socket_id.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:302:22 [INFO] [stderr] | [INFO] [stderr] 302 | into.put_i32::(c.syn_cookie); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:315:22 [INFO] [stderr] | [INFO] [stderr] 315 | into.put_i32::(c.ack_number.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:316:22 [INFO] [stderr] | [INFO] [stderr] 316 | into.put_i32::(c.rtt.unwrap_or(10_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:317:22 [INFO] [stderr] | [INFO] [stderr] 317 | into.put_i32::(c.rtt_variance.unwrap_or(50_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:318:22 [INFO] [stderr] | [INFO] [stderr] 318 | into.put_i32::(c.buffer_available.unwrap_or(8175)); // TODO: better defaults [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:319:22 [INFO] [stderr] | [INFO] [stderr] 319 | into.put_i32::(c.packet_recv_rate.unwrap_or(10_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:320:22 [INFO] [stderr] | [INFO] [stderr] 320 | into.put_i32::(c.est_link_cap.unwrap_or(1_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:323:22 [INFO] [stderr] | [INFO] [stderr] 323 | into.put_i32::(loss); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:507:60 [INFO] [stderr] | [INFO] [stderr] 507 | let seq_number = SeqNumber(Cursor::new(first4).get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:510:35 [INFO] [stderr] | [INFO] [stderr] 510 | let second_line = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:519:33 [INFO] [stderr] | [INFO] [stderr] 519 | let timestamp = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:520:44 [INFO] [stderr] | [INFO] [stderr] 520 | let dest_sockid = SocketID(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:534:32 [INFO] [stderr] | [INFO] [stderr] 534 | let add_info = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:535:33 [INFO] [stderr] | [INFO] [stderr] 535 | let timestamp = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:536:35 [INFO] [stderr] | [INFO] [stderr] 536 | let dest_sockid = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le [INFO] [stderr] --> src/packet.rs:555:22 [INFO] [stderr] | [INFO] [stderr] 555 | into.put_i16::((i16::from(control_type.id_byte())) | (0b1 << 15)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le [INFO] [stderr] --> src/packet.rs:558:22 [INFO] [stderr] | [INFO] [stderr] 558 | into.put_i16::(0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:561:22 [INFO] [stderr] | [INFO] [stderr] 561 | into.put_i32::(control_type.additional_info()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:564:22 [INFO] [stderr] | [INFO] [stderr] 564 | into.put_i32::(*timestamp); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:567:22 [INFO] [stderr] | [INFO] [stderr] 567 | into.put_i32::(dest_sockid.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:581:22 [INFO] [stderr] | [INFO] [stderr] 581 | into.put_i32::(seq_number.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:582:22 [INFO] [stderr] | [INFO] [stderr] 582 | into.put_i32::( [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:587:22 [INFO] [stderr] | [INFO] [stderr] 587 | into.put_i32::(*timestamp); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:588:22 [INFO] [stderr] | [INFO] [stderr] 588 | into.put_i32::(dest_sockid.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: field is never used: `local_public` [INFO] [stderr] --> src/pending_connection/rendezvous.rs:10:5 [INFO] [stderr] | [INFO] [stderr] 10 | local_public: SocketAddr, [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(dead_code)] on by default [INFO] [stderr] [INFO] [stderr] warning: field is never used: `remote_public` [INFO] [stderr] --> src/pending_connection/rendezvous.rs:11:5 [INFO] [stderr] | [INFO] [stderr] 11 | remote_public: SocketAddr, [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: field is never used: `sock` [INFO] [stderr] --> src/pending_connection/rendezvous.rs:12:5 [INFO] [stderr] | [INFO] [stderr] 12 | sock: T, [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio::executor::current_thread': use tokio-current-thread crate or functions in tokio::runtime::current_thread instead [INFO] [stderr] --> src/bin.rs:18:13 [INFO] [stderr] | [INFO] [stderr] 18 | use tokio::{executor::current_thread, net::{UdpFramed, UdpSocket}}; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(deprecated)] on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec [INFO] [stderr] --> src/bin.rs:19:5 [INFO] [stderr] | [INFO] [stderr] 19 | use tokio_io::codec::BytesCodec; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec [INFO] [stderr] --> src/bin.rs:89:25 [INFO] [stderr] | [INFO] [stderr] 89 | BytesCodec::new(), [INFO] [stderr] | ^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec [INFO] [stderr] --> src/bin.rs:142:21 [INFO] [stderr] | [INFO] [stderr] 142 | BytesCodec::new(), [INFO] [stderr] | ^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio::executor::current_thread::run': use block_on_all instead [INFO] [stderr] --> src/bin.rs:159:5 [INFO] [stderr] | [INFO] [stderr] 159 | current_thread::run(|_| { [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 8.56s [INFO] running `"docker" "inspect" "ed4f6c18fa08cc576d5d5100a89ddab88df8bbf69ba61c7cb24e1e8d373da505"` [INFO] running `"docker" "rm" "-f" "ed4f6c18fa08cc576d5d5100a89ddab88df8bbf69ba61c7cb24e1e8d373da505"` [INFO] [stdout] ed4f6c18fa08cc576d5d5100a89ddab88df8bbf69ba61c7cb24e1e8d373da505 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-7/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/srt-transport/0.2.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen" "--no-run"` [INFO] [stdout] 48dd6af6571b32c0ef8908858ec7f0222cf0bb9cc42d3a47a25e450ffbb25d46 [INFO] running `"docker" "start" "-a" "48dd6af6571b32c0ef8908858ec7f0222cf0bb9cc42d3a47a25e450ffbb25d46"` [INFO] [stderr] Compiling srt-transport v0.2.0 (/opt/crater/workdir) [INFO] [stderr] warning: unused import: `DefaultReceiverCongestionCtrl` [INFO] [stderr] --> tests/tests.rs:22:31 [INFO] [stderr] | [INFO] [stderr] 22 | use srt::{ConnectionSettings, DefaultReceiverCongestionCtrl, DefaultSenderCongestionCtrl, [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(unused_imports)] on by default [INFO] [stderr] [INFO] [stderr] warning: unused import: `tokio::executor::current_thread` [INFO] [stderr] --> tests/tests.rs:25:5 [INFO] [stderr] | [INFO] [stderr] 25 | use tokio::executor::current_thread; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: unnecessary parentheses around function argument [INFO] [stderr] --> tests/tests.rs:216:35 [INFO] [stderr] | [INFO] [stderr] 216 | let counting_stream = iter_ok((init_seq_num as u64..(init_seq_num + iters))) [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ help: remove these parentheses [INFO] [stderr] | [INFO] [stderr] = note: #[warn(unused_parens)] on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio::executor::current_thread': use tokio-current-thread crate or functions in tokio::runtime::current_thread instead [INFO] [stderr] --> tests/tests.rs:25:5 [INFO] [stderr] | [INFO] [stderr] 25 | use tokio::executor::current_thread; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(deprecated)] on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio::executor::current_thread': use tokio-current-thread crate or functions in tokio::runtime::current_thread instead [INFO] [stderr] --> src/bin.rs:18:13 [INFO] [stderr] | [INFO] [stderr] 18 | use tokio::{executor::current_thread, net::{UdpFramed, UdpSocket}}; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(deprecated)] on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec [INFO] [stderr] --> src/bin.rs:19:5 [INFO] [stderr] | [INFO] [stderr] 19 | use tokio_io::codec::BytesCodec; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec [INFO] [stderr] --> src/bin.rs:89:25 [INFO] [stderr] | [INFO] [stderr] 89 | BytesCodec::new(), [INFO] [stderr] | ^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec [INFO] [stderr] --> src/bin.rs:142:21 [INFO] [stderr] | [INFO] [stderr] 142 | BytesCodec::new(), [INFO] [stderr] | ^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'tokio::executor::current_thread::run': use block_on_all instead [INFO] [stderr] --> src/bin.rs:159:5 [INFO] [stderr] | [INFO] [stderr] 159 | current_thread::run(|_| { [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: constant `init_seq_num` should have an upper case name [INFO] [stderr] --> tests/tests.rs:212:11 [INFO] [stderr] | [INFO] [stderr] 212 | const init_seq_num: u64 = 812731; [INFO] [stderr] | ^^^^^^^^^^^^ help: convert the identifier to upper case: `INIT_SEQ_NUM` [INFO] [stderr] [INFO] [stderr] warning: constant `iters` should have an upper case name [INFO] [stderr] --> tests/tests.rs:213:11 [INFO] [stderr] | [INFO] [stderr] 213 | const iters: u64 = 1_000_000; [INFO] [stderr] | ^^^^^ help: convert the identifier to upper case: `ITERS` [INFO] [stderr] [INFO] [stderr] warning: unused `std::result::Result` that must be used [INFO] [stderr] --> tests/tests.rs:253:9 [INFO] [stderr] | [INFO] [stderr] 253 | / counting_stream [INFO] [stderr] 254 | | .forward(sender) [INFO] [stderr] 255 | | .map_err(|e: Error| panic!("{:?}", e)) [INFO] [stderr] 256 | | .map(|_| ()) [INFO] [stderr] 257 | | .wait(); [INFO] [stderr] | |____________________^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(unused_must_use)] on by default [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] [INFO] [stderr] warning: unused `std::result::Result` that must be used [INFO] [stderr] --> tests/tests.rs:261:9 [INFO] [stderr] | [INFO] [stderr] 261 | / recvr [INFO] [stderr] 262 | | .forward(CounterChecker { [INFO] [stderr] 263 | | current: init_seq_num, [INFO] [stderr] 264 | | }) [INFO] [stderr] 265 | | .map_err(|e| panic!(e)) [INFO] [stderr] 266 | | .map(move |(_, c)| assert_eq!(c.current, init_seq_num + iters)) [INFO] [stderr] 267 | | .wait(); [INFO] [stderr] | |____________________^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] [INFO] [stderr] warning: unused `std::result::Result` that must be used [INFO] [stderr] --> tests/tests.rs:270:5 [INFO] [stderr] | [INFO] [stderr] 270 | t1.join(); [INFO] [stderr] | ^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] [INFO] [stderr] warning: unused `std::result::Result` that must be used [INFO] [stderr] --> tests/tests.rs:271:5 [INFO] [stderr] | [INFO] [stderr] 271 | t2.join(); [INFO] [stderr] | ^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:175:39 [INFO] [stderr] | [INFO] [stderr] 175 | let udt_version = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(deprecated)] on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:176:58 [INFO] [stderr] | [INFO] [stderr] 176 | let sock_type = SocketType::from_i32(buf.get_i32::())?; [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:177:50 [INFO] [stderr] | [INFO] [stderr] 177 | let init_seq_num = SeqNumber(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:178:43 [INFO] [stderr] | [INFO] [stderr] 178 | let max_packet_size = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:179:41 [INFO] [stderr] | [INFO] [stderr] 179 | let max_flow_size = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:180:68 [INFO] [stderr] | [INFO] [stderr] 180 | let connection_type = ConnectionType::from_i32(buf.get_i32::())?; [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:181:46 [INFO] [stderr] | [INFO] [stderr] 181 | let socket_id = SocketID(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:182:38 [INFO] [stderr] | [INFO] [stderr] 182 | let syn_cookie = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:212:48 [INFO] [stderr] | [INFO] [stderr] 212 | let ack_number = SeqNumber(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:217:34 [INFO] [stderr] | [INFO] [stderr] 217 | Some(buf.get_i32::()) [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:245:40 [INFO] [stderr] | [INFO] [stderr] 245 | loss_info.push(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:295:22 [INFO] [stderr] | [INFO] [stderr] 295 | into.put_i32::(c.udt_version); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:296:22 [INFO] [stderr] | [INFO] [stderr] 296 | into.put_i32::(c.sock_type.to_i32()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:297:22 [INFO] [stderr] | [INFO] [stderr] 297 | into.put_i32::(c.init_seq_num.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:298:22 [INFO] [stderr] | [INFO] [stderr] 298 | into.put_i32::(c.max_packet_size); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:299:22 [INFO] [stderr] | [INFO] [stderr] 299 | into.put_i32::(c.max_flow_size); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:300:22 [INFO] [stderr] | [INFO] [stderr] 300 | into.put_i32::(c.connection_type.to_i32()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:301:22 [INFO] [stderr] | [INFO] [stderr] 301 | into.put_i32::(c.socket_id.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:302:22 [INFO] [stderr] | [INFO] [stderr] 302 | into.put_i32::(c.syn_cookie); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:315:22 [INFO] [stderr] | [INFO] [stderr] 315 | into.put_i32::(c.ack_number.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:316:22 [INFO] [stderr] | [INFO] [stderr] 316 | into.put_i32::(c.rtt.unwrap_or(10_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:317:22 [INFO] [stderr] | [INFO] [stderr] 317 | into.put_i32::(c.rtt_variance.unwrap_or(50_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:318:22 [INFO] [stderr] | [INFO] [stderr] 318 | into.put_i32::(c.buffer_available.unwrap_or(8175)); // TODO: better defaults [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:319:22 [INFO] [stderr] | [INFO] [stderr] 319 | into.put_i32::(c.packet_recv_rate.unwrap_or(10_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:320:22 [INFO] [stderr] | [INFO] [stderr] 320 | into.put_i32::(c.est_link_cap.unwrap_or(1_000)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:323:22 [INFO] [stderr] | [INFO] [stderr] 323 | into.put_i32::(loss); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:507:60 [INFO] [stderr] | [INFO] [stderr] 507 | let seq_number = SeqNumber(Cursor::new(first4).get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:510:35 [INFO] [stderr] | [INFO] [stderr] 510 | let second_line = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:519:33 [INFO] [stderr] | [INFO] [stderr] 519 | let timestamp = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:520:44 [INFO] [stderr] | [INFO] [stderr] 520 | let dest_sockid = SocketID(buf.get_i32::()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:534:32 [INFO] [stderr] | [INFO] [stderr] 534 | let add_info = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:535:33 [INFO] [stderr] | [INFO] [stderr] 535 | let timestamp = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le [INFO] [stderr] --> src/packet.rs:536:35 [INFO] [stderr] | [INFO] [stderr] 536 | let dest_sockid = buf.get_i32::(); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le [INFO] [stderr] --> src/packet.rs:555:22 [INFO] [stderr] | [INFO] [stderr] 555 | into.put_i16::((i16::from(control_type.id_byte())) | (0b1 << 15)); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le [INFO] [stderr] --> src/packet.rs:558:22 [INFO] [stderr] | [INFO] [stderr] 558 | into.put_i16::(0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:561:22 [INFO] [stderr] | [INFO] [stderr] 561 | into.put_i32::(control_type.additional_info()); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:564:22 [INFO] [stderr] | [INFO] [stderr] 564 | into.put_i32::(*timestamp); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:567:22 [INFO] [stderr] | [INFO] [stderr] 567 | into.put_i32::(dest_sockid.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:581:22 [INFO] [stderr] | [INFO] [stderr] 581 | into.put_i32::(seq_number.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:582:22 [INFO] [stderr] | [INFO] [stderr] 582 | into.put_i32::( [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:587:22 [INFO] [stderr] | [INFO] [stderr] 587 | into.put_i32::(*timestamp); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le [INFO] [stderr] --> src/packet.rs:588:22 [INFO] [stderr] | [INFO] [stderr] 588 | into.put_i32::(dest_sockid.0); [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: field is never used: `local_public` [INFO] [stderr] --> src/pending_connection/rendezvous.rs:10:5 [INFO] [stderr] | [INFO] [stderr] 10 | local_public: SocketAddr, [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: #[warn(dead_code)] on by default [INFO] [stderr] [INFO] [stderr] warning: field is never used: `remote_public` [INFO] [stderr] --> src/pending_connection/rendezvous.rs:11:5 [INFO] [stderr] | [INFO] [stderr] 11 | remote_public: SocketAddr, [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: field is never used: `sock` [INFO] [stderr] --> src/pending_connection/rendezvous.rs:12:5 [INFO] [stderr] | [INFO] [stderr] 12 | sock: T, [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 4.38s [INFO] running `"docker" "inspect" "48dd6af6571b32c0ef8908858ec7f0222cf0bb9cc42d3a47a25e450ffbb25d46"` [INFO] running `"docker" "rm" "-f" "48dd6af6571b32c0ef8908858ec7f0222cf0bb9cc42d3a47a25e450ffbb25d46"` [INFO] [stdout] 48dd6af6571b32c0ef8908858ec7f0222cf0bb9cc42d3a47a25e450ffbb25d46 [INFO] running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-57967/worker-7/try#7fea0229842e79db1159ea58ad9a653b288efee9:/opt/crater/target:rw,Z" "-v" "/mnt/big/crater/work/ex/pr-57967/sources/try#7fea0229842e79db1159ea58ad9a653b288efee9/reg/srt-transport/0.2.0:/opt/crater/workdir:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/opt/crater/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/opt/crater/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/opt/crater/workdir" "-e" "MAP_USER_ID=1000" "-e" "CARGO_TARGET_DIR=/opt/crater/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/crater/cargo-home" "-e" "RUSTUP_HOME=/opt/crater/rustup-home" "-w" "/opt/crater/workdir" "-m" "1536M" "--network" "none" "rustops/crates-build-env" "/opt/crater/cargo-home/bin/cargo" "+7fea0229842e79db1159ea58ad9a653b288efee9-alt" "test" "--frozen"` [INFO] [stdout] be650755510d6fbb73b2c207a3c3917e01113aa323d4ee70c560c80d611dd440 [INFO] running `"docker" "start" "-a" "be650755510d6fbb73b2c207a3c3917e01113aa323d4ee70c560c80d611dd440"` [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.11s [INFO] [stderr] Running /opt/crater/target/debug/deps/srt-ec47aa0fb5e81eac [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test packet::handshake_ser_des_test ... ok [INFO] [stdout] test packet::packet_location_to_i32_test ... ok [INFO] [stdout] test seq_number::seq_num_test ... ok [INFO] [stdout] test loss_compression::loss_compression_test ... ok [INFO] [stdout] test packet::packet_location_from_i32_test ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/crater/target/debug/deps/stransmit_rs-b09c4f62eac78faf [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/crater/target/debug/deps/tests-d26a521790408eb3 [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812731 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812732 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812730, buffer=[812731] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812730, buffer=[812732, 812731] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812733 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812734 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812735 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812731) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812732) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812733), lr_ack_acked=SeqNumber(812731) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812732, buffer=[812733] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812736 [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812736), message_loc: Only, in_order_delivery: false, message_number: 5, timestamp: 10679, dest_sockid: SocketID(81), payload: b"812736" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812732, buffer=[812734, 812733] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 10366, dest_sockid: SocketID(13), control_type: Ack(1, AckControlInfo { ack_number: SeqNumber(812733), rtt: Some(10000), rtt_variance: Some(1000), buffer_available: None, packet_recv_rate: Some(0), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812732, buffer=[812735, 812734, 812733] [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Control { timestamp: 10752, dest_sockid: SocketID(81), control_type: Ack2(1) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::default_congestion_ctrl::sender] New window size: 16 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812733) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812734) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812735) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812736)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812735, buffer=[812737] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 10819, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812736] }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Loss list=[812736] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812736) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812735, buffer=[812737, 812736] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812738 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812739 [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812739), message_loc: Only, in_order_delivery: false, message_number: 8, timestamp: 18467, dest_sockid: SocketID(81), payload: b"812739" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812736) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812737) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812737, buffer=[812738] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812740 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812738) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812739)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812738, buffer=[812740] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 19689, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812739] }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Loss list=[812739] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812739), lr_ack_acked=SeqNumber(812731) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 20048, dest_sockid: SocketID(13), control_type: Ack(2, AckControlInfo { ack_number: SeqNumber(812739), rtt: Some(10000), rtt_variance: Some(1000), buffer_available: None, packet_recv_rate: Some(0), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::default_congestion_ctrl::sender] New window size: 16 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812739) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812738, buffer=[812740, 812739] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812741 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812739) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812740) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812740, buffer=[812741] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812742 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812741) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812741, buffer=[812742] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812743 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812742) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812742, buffer=[812743] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812744 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812745 [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812745), message_loc: Only, in_order_delivery: false, message_number: 14, timestamp: 30580, dest_sockid: SocketID(81), payload: b"812745" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812743) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812743, buffer=[812744] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812746 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812744) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812745)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812744, buffer=[812746] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 33327, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812745] }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Loss list=[812745] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812745) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812744, buffer=[812746, 812745] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812747 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812745) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812746) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812746, buffer=[812747] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812748 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812747) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812747, buffer=[812748] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812749 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812748) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812748, buffer=[812749] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812750 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812749) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812749, buffer=[812750] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812751 [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812751), message_loc: Only, in_order_delivery: false, message_number: 20, timestamp: 41842, dest_sockid: SocketID(81), payload: b"812751" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812752 [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812753), message_loc: Only, in_order_delivery: false, message_number: 22, timestamp: 46513, dest_sockid: SocketID(81), payload: b"812753" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Releasing SeqNumber(812750) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812751)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Control { timestamp: 46597, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812751] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812754 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812755 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812753)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812754, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812755, 812754, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 50019, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812753] }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Loss list=[812753] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812753) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812751), lr_ack_acked=SeqNumber(812739) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK with lost packets: ll=[812751] [SeqNumber(812751)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812751)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Control { timestamp: 66522, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812751] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 66428, dest_sockid: SocketID(13), control_type: Ack(3, AckControlInfo { ack_number: SeqNumber(812751), rtt: Some(8776), rtt_variance: Some(947), buffer_available: None, packet_recv_rate: Some(35237), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::default_congestion_ctrl::sender] New window size: 16 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812756 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812757 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812758 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812759 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812760 [INFO] [stdout] 2019-02-01 01:33:35 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812760), message_loc: Only, in_order_delivery: false, message_number: 29, timestamp: 76402, dest_sockid: SocketID(81), payload: b"812760" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812761 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812760)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812761, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 78093, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812760] }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Loss list=[812760] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812760) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812762 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812763 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812763, 812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812764 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812764, 812763, 812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812765 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812765, 812764, 812763, 812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812766 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812766, 812765, 812764, 812763, 812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet: 812767 [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812767, 812766, 812765, 812764, 812763, 812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK with lost packets: ll=[812751] [SeqNumber(812751)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] Sending NAK for=[SeqNumber(812751)] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Got packet: Control { timestamp: 110372, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812751] }) } [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Loss list=[812751] [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812751) [INFO] [stdout] 2019-02-01 01:33:35 INFO [srt::receiver] lr=812750, buffer=[812767, 812766, 812765, 812764, 812763, 812762, 812761, 812760, 812759, 812758, 812757, 812756, 812755, 812754, 812753, 812752, 812751] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812751) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812752) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812753) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812754) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812755) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812756) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812757) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812758) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812759) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812760) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812761) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812762) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812763) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812764) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812765) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812766) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812767) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812768), lr_ack_acked=SeqNumber(812751) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 154055, dest_sockid: SocketID(13), control_type: Ack(4, AckControlInfo { ack_number: SeqNumber(812768), rtt: Some(7721), rtt_variance: Some(863), buffer_available: None, packet_recv_rate: Some(77201), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 94 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812768 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812767, buffer=[812768] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812768) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812768, buffer=[812769] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812770 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812769) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812769, buffer=[812770] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812771 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812770) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812770, buffer=[812771] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812772 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812771) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812771, buffer=[812772] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812773 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812772) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812772, buffer=[812773] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812774 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812773) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812773, buffer=[812774] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812775 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812774) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812774, buffer=[812775] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812776 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812775) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812775, buffer=[812776] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812777 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812776) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812776, buffer=[812777] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812778 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812778), message_loc: Only, in_order_delivery: false, message_number: 47, timestamp: 174987, dest_sockid: SocketID(81), payload: b"812778" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812779 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812777) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812778)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812777, buffer=[812779] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 177172, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812778] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812778] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812778) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812778), message_loc: Only, in_order_delivery: false, message_number: 47, timestamp: 174987, dest_sockid: SocketID(81), payload: b"812778" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812780 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812777, buffer=[812780, 812779] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812781 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812777, buffer=[812781, 812780, 812779] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812782 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812777, buffer=[812782, 812781, 812780, 812779] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812783 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812777, buffer=[812783, 812782, 812781, 812780, 812779] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812778), lr_ack_acked=SeqNumber(812768) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812778] [SeqNumber(812778)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812778)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 192313, dest_sockid: SocketID(13), control_type: Ack(5, AckControlInfo { ack_number: SeqNumber(812778), rtt: Some(6786), rtt_variance: Some(801), buffer_available: None, packet_recv_rate: Some(167280), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 192590, dest_sockid: SocketID(81), control_type: Ack2(5) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 242 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 192370, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812778] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812778] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812778) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812777, buffer=[812783, 812782, 812781, 812780, 812779, 812778] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812784 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812785), message_loc: Only, in_order_delivery: false, message_number: 54, timestamp: 196751, dest_sockid: SocketID(81), payload: b"812785" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812778) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812779) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812780) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812781) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812782) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812783) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812783, buffer=[812784] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812786 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812784) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 199397, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812785] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812785) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812785), message_loc: Only, in_order_delivery: false, message_number: 54, timestamp: 196751, dest_sockid: SocketID(81), payload: b"812785" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812787 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812788 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812789 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812790 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812791 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812792 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812793 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812785), lr_ack_acked=SeqNumber(812768) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 230283, dest_sockid: SocketID(13), control_type: Ack(6, AckControlInfo { ack_number: SeqNumber(812785), rtt: Some(6786), rtt_variance: Some(801), buffer_available: None, packet_recv_rate: Some(200918), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785] [SeqNumber(812785)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 230356, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812785] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812785) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812785), message_loc: Only, in_order_delivery: false, message_number: 54, timestamp: 196751, dest_sockid: SocketID(81), payload: b"812785" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812794 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812795 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812796 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812797 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812798 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812798), message_loc: Only, in_order_delivery: false, message_number: 67, timestamp: 249041, dest_sockid: SocketID(81), payload: b"812798" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812799 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812798)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812799, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 266753, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812798] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812798] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812785), lr_ack_acked=SeqNumber(812768) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812798] [SeqNumber(812785)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 268367, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 268302, dest_sockid: SocketID(13), control_type: Ack(7, AckControlInfo { ack_number: SeqNumber(812785), rtt: Some(6786), rtt_variance: Some(801), buffer_available: None, packet_recv_rate: Some(221194), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 565 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812798) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812800 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812802 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812803 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812803), message_loc: Only, in_order_delivery: false, message_number: 72, timestamp: 280840, dest_sockid: SocketID(81), payload: b"812803" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812804 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812804), message_loc: Only, in_order_delivery: false, message_number: 73, timestamp: 284478, dest_sockid: SocketID(81), payload: b"812804" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812805 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812803), SeqNumber(812804)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812805, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 288183, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670845, 812804] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812803, 812804] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812803) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812804) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812804), message_loc: Only, in_order_delivery: false, message_number: 73, timestamp: 284478, dest_sockid: SocketID(81), payload: b"812804" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804] [SeqNumber(812785), SeqNumber(812804)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785), SeqNumber(812804)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 303333, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785, 812804] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812806 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812807 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812808 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812809 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812810 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812810), message_loc: Only, in_order_delivery: false, message_number: 79, timestamp: 327272, dest_sockid: SocketID(81), payload: b"812810" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812811 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812811), message_loc: Only, in_order_delivery: false, message_number: 80, timestamp: 334486, dest_sockid: SocketID(81), payload: b"812811" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804] [SeqNumber(812785), SeqNumber(812804)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785), SeqNumber(812804)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 337959, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785, 812804] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812812 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812812), message_loc: Only, in_order_delivery: false, message_number: 81, timestamp: 338871, dest_sockid: SocketID(81), payload: b"812812" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812813 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812813), message_loc: Only, in_order_delivery: false, message_number: 82, timestamp: 343536, dest_sockid: SocketID(81), payload: b"812813" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812814 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812810), SeqNumber(812811), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812814, 812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 350509, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670838, 812813] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812810, 812811, 812812, 812813] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812810) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812814, 812810, 812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812811) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812814, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812812) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812812), message_loc: Only, in_order_delivery: false, message_number: 81, timestamp: 338871, dest_sockid: SocketID(81), payload: b"812812" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804, 812812, 812813] [SeqNumber(812804), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812804), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804, 812812, 812813] [SeqNumber(812785), SeqNumber(812804), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785), SeqNumber(812804), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804, 812812, 812813] [SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804, 812812, 812813] [SeqNumber(812785), SeqNumber(812804), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785), SeqNumber(812804), SeqNumber(812812), SeqNumber(812813)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 372566, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812804, -2146670836, 812813] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812813, 812804, 812812, 812813] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 407203, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785, 812804, -2146670836, 812813] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812813, 812804, 812812, 812813, 812785, 812804, 812812, 812813] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 441816, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670836, 812813] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812813, 812804, 812812, 812813, 812785, 812804, 812812, 812813, 812812, 812813] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 476462, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785, 812804, -2146670836, 812813] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812813, 812804, 812812, 812813, 812785, 812804, 812812, 812813, 812812, 812813, 812785, 812804, 812812, 812813] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812813) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812804) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812804), message_loc: Only, in_order_delivery: false, message_number: 73, timestamp: 284478, dest_sockid: SocketID(81), payload: b"812804" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812785, 812804, 812812] [SeqNumber(812785), SeqNumber(812804), SeqNumber(812812)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812785), SeqNumber(812804), SeqNumber(812812)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 547028, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812785, 812804, 812812] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812812) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812814, 812813, 812812, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812813) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812813), message_loc: Only, in_order_delivery: false, message_number: 82, timestamp: 343536, dest_sockid: SocketID(81), payload: b"812813" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812785) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812784, buffer=[812814, 812813, 812812, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812804) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812785) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812786) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812787) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812788) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812789) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812790) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812791) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812792) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812793) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812794) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812795) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812796) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812797) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812798) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812799) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812800) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812801) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812802) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812803) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812804), lr_ack_acked=SeqNumber(812785) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 581940, dest_sockid: SocketID(13), control_type: Ack(8, AckControlInfo { ack_number: SeqNumber(812804), rtt: Some(5973), rtt_variance: Some(729), buffer_available: None, packet_recv_rate: Some(353723), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812803, buffer=[812814, 812813, 812812, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812812) [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812812), message_loc: Only, in_order_delivery: false, message_number: 81, timestamp: 338871, dest_sockid: SocketID(81), payload: b"812812" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812813) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812804) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812805) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812806) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812807) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812808) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812809) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812810) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812811) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812812) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812813) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812814) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Received packet SeqNumber(812813) twice [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812812) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Received packet SeqNumber(812812) twice [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812815), lr_ack_acked=SeqNumber(812785) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 615011, dest_sockid: SocketID(13), control_type: Ack(9, AckControlInfo { ack_number: SeqNumber(812815), rtt: Some(5973), rtt_variance: Some(729), buffer_available: None, packet_recv_rate: Some(414710), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 914 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812815 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812814, buffer=[812815] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812816 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812817), message_loc: Only, in_order_delivery: false, message_number: 86, timestamp: 639225, dest_sockid: SocketID(81), payload: b"812817" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812815) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812815, buffer=[812816] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812816) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812817), lr_ack_acked=SeqNumber(812815) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812818 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812818), message_loc: Only, in_order_delivery: false, message_number: 87, timestamp: 651301, dest_sockid: SocketID(81), payload: b"812818" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 651214, dest_sockid: SocketID(13), control_type: Ack(10, AckControlInfo { ack_number: SeqNumber(812817), rtt: Some(5528), rtt_variance: Some(969), buffer_available: None, packet_recv_rate: Some(459270), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812819 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812817), SeqNumber(812818)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812816, buffer=[812819] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 667175, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670831, 812818] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812817, 812818] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812817) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812816, buffer=[812819, 812817] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812817) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812818), lr_ack_acked=SeqNumber(812817) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK with lost packets: ll=[812818] [SeqNumber(812818)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812818)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 682082, dest_sockid: SocketID(13), control_type: Ack(11, AckControlInfo { ack_number: SeqNumber(812818), rtt: Some(4878), rtt_variance: Some(885), buffer_available: None, packet_recv_rate: Some(505670), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 682352, dest_sockid: SocketID(81), control_type: Ack2(11) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 682134, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812818] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812818, 812818] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812818) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812817, buffer=[812819, 812818] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812818) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812819) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812820), lr_ack_acked=SeqNumber(812817) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812820), lr_ack_acked=SeqNumber(812817) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 712495, dest_sockid: SocketID(13), control_type: Ack(12, AckControlInfo { ack_number: SeqNumber(812820), rtt: Some(4878), rtt_variance: Some(885), buffer_available: None, packet_recv_rate: Some(528848), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 742835, dest_sockid: SocketID(13), control_type: Ack(13, AckControlInfo { ack_number: SeqNumber(812820), rtt: Some(4878), rtt_variance: Some(885), buffer_available: None, packet_recv_rate: Some(528848), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812820 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812819, buffer=[812820] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812821 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812820) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812820, buffer=[812821] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812822 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812821) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812821, buffer=[812822] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812823 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812822) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812822, buffer=[812823] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812824 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812823) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812823, buffer=[812824] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812824) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812825), lr_ack_acked=SeqNumber(812820) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 810308, dest_sockid: SocketID(13), control_type: Ack(14, AckControlInfo { ack_number: SeqNumber(812825), rtt: Some(9574), rtt_variance: Some(8053), buffer_available: None, packet_recv_rate: Some(664768), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 814490, dest_sockid: SocketID(81), control_type: Ack2(14) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812825 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812824, buffer=[812825] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812826 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812826), message_loc: Only, in_order_delivery: false, message_number: 95, timestamp: 824745, dest_sockid: SocketID(81), payload: b"812826" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812827 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812827), message_loc: Only, in_order_delivery: false, message_number: 96, timestamp: 834623, dest_sockid: SocketID(81), payload: b"812827" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812828 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812828), message_loc: Only, in_order_delivery: false, message_number: 97, timestamp: 844492, dest_sockid: SocketID(81), payload: b"812828" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812829 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812825) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812826), SeqNumber(812827), SeqNumber(812828)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812825, buffer=[812829] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 859274, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670822, 812828] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812826, 812827, 812828] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812826) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812825, buffer=[812829, 812826] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812826) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812827), lr_ack_acked=SeqNumber(812820) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 866459, dest_sockid: SocketID(13), control_type: Ack(15, AckControlInfo { ack_number: SeqNumber(812827), rtt: Some(9574), rtt_variance: Some(8053), buffer_available: None, packet_recv_rate: Some(719892), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812827) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812826, buffer=[812829, 812827] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812828) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812827) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812827, buffer=[812829, 812828] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812830 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812830), message_loc: Only, in_order_delivery: false, message_number: 99, timestamp: 897532, dest_sockid: SocketID(81), payload: b"812830" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812831 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812828) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812829) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812830)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812829, buffer=[812831] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 909607, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812830] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812830] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812830), lr_ack_acked=SeqNumber(812827) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 918500, dest_sockid: SocketID(13), control_type: Ack(16, AckControlInfo { ack_number: SeqNumber(812830), rtt: Some(8404), rtt_variance: Some(7999), buffer_available: None, packet_recv_rate: Some(774779), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812830) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812829, buffer=[812831, 812830] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812832 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812830) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812831) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812831, buffer=[812832] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812832) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812832, buffer=[812833] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812833) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812834), lr_ack_acked=SeqNumber(812830) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 966616, dest_sockid: SocketID(13), control_type: Ack(17, AckControlInfo { ack_number: SeqNumber(812834), rtt: Some(7389), rtt_variance: Some(7927), buffer_available: None, packet_recv_rate: Some(831020), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Control { timestamp: 966843, dest_sockid: SocketID(81), control_type: Ack2(17) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812834 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812833, buffer=[812834] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812835 [INFO] [stdout] 2019-02-01 01:33:36 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812835), message_loc: Only, in_order_delivery: false, message_number: 104, timestamp: 998582, dest_sockid: SocketID(81), payload: b"812835" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812834) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812835), lr_ack_acked=SeqNumber(812830) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 1018427, dest_sockid: SocketID(13), control_type: Ack(18, AckControlInfo { ack_number: SeqNumber(812835), rtt: Some(7389), rtt_variance: Some(7927), buffer_available: None, packet_recv_rate: Some(850518), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812836 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending NAK for=[SeqNumber(812835)] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812834, buffer=[812836] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Got packet: Control { timestamp: 1020108, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812835] }) } [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Loss list=[812835] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812835) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812834, buffer=[812836, 812835] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812837 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812835) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812836) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] lr=812836, buffer=[812837] [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::sender] Sending packet: 812838 [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Releasing SeqNumber(812837) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812838), lr_ack_acked=SeqNumber(812835) [INFO] [stdout] 2019-02-01 01:33:36 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812838), lr_ack_acked=SeqNumber(812835) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1062616, dest_sockid: SocketID(13), control_type: Ack(19, AckControlInfo { ack_number: SeqNumber(812838), rtt: Some(6494), rtt_variance: Some(7869), buffer_available: None, packet_recv_rate: Some(906462), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812837, buffer=[812838] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1106453, dest_sockid: SocketID(13), control_type: Ack(20, AckControlInfo { ack_number: SeqNumber(812838), rtt: Some(6494), rtt_variance: Some(7869), buffer_available: None, packet_recv_rate: Some(906462), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812839 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812838) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812838, buffer=[812839] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812840 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812839) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812839, buffer=[812840] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812841 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812840) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812840, buffer=[812841] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812842 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812841) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812841, buffer=[812842] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812843 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812842) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812842, buffer=[812843] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812844 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812843) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812843, buffer=[812844] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812845 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812844) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812844, buffer=[812845] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812846 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812845) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812845, buffer=[812846] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812846) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812847), lr_ack_acked=SeqNumber(812838) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1258378, dest_sockid: SocketID(13), control_type: Ack(21, AckControlInfo { ack_number: SeqNumber(812847), rtt: Some(19399), rtt_variance: Some(22870), buffer_available: None, packet_recv_rate: Some(1104642), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Control { timestamp: 1258668, dest_sockid: SocketID(81), control_type: Ack2(21) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812847 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812846, buffer=[812847] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812848 [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812848), message_loc: Only, in_order_delivery: false, message_number: 117, timestamp: 1327549, dest_sockid: SocketID(81), payload: b"812848" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812849), message_loc: Only, in_order_delivery: false, message_number: 118, timestamp: 1328202, dest_sockid: SocketID(81), payload: b"812849" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812847) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812848), lr_ack_acked=SeqNumber(812838) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1368704, dest_sockid: SocketID(13), control_type: Ack(22, AckControlInfo { ack_number: SeqNumber(812848), rtt: Some(19399), rtt_variance: Some(22870), buffer_available: None, packet_recv_rate: Some(1126536), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Control { timestamp: 1381957, dest_sockid: SocketID(81), control_type: Ack2(22) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812850 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812848), SeqNumber(812849)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812847, buffer=[812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1382135, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670800, 812849] }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Loss list=[812848, 812849] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812848) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812847, buffer=[812850, 812848] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812849) [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812849), message_loc: Only, in_order_delivery: false, message_number: 118, timestamp: 1328202, dest_sockid: SocketID(81), payload: b"812849" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812851 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812848) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812851, 812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812852 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812852, 812851, 812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812853 [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812853), message_loc: Only, in_order_delivery: false, message_number: 122, timestamp: 1452158, dest_sockid: SocketID(81), payload: b"812853" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812854 [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812854), message_loc: Only, in_order_delivery: false, message_number: 123, timestamp: 1466461, dest_sockid: SocketID(81), payload: b"812854" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812849), lr_ack_acked=SeqNumber(812838) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK with lost packets: ll=[812849] [SeqNumber(812849)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812849)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Control { timestamp: 1479298, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812849] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1479235, dest_sockid: SocketID(13), control_type: Ack(23, AckControlInfo { ack_number: SeqNumber(812849), rtt: Some(19399), rtt_variance: Some(22870), buffer_available: None, packet_recv_rate: Some(1233320), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812855 [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812855), message_loc: Only, in_order_delivery: false, message_number: 124, timestamp: 1480740, dest_sockid: SocketID(81), payload: b"812855" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812856 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812853), SeqNumber(812854), SeqNumber(812855)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812856, 812852, 812851, 812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1495248, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670795, 812855] }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Loss list=[812853, 812854, 812855] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812853) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812856, 812853, 812852, 812851, 812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812854) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812856, 812854, 812853, 812852, 812851, 812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812855) [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812855), message_loc: Only, in_order_delivery: false, message_number: 124, timestamp: 1480740, dest_sockid: SocketID(81), payload: b"812855" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812857 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812857, 812856, 812854, 812853, 812852, 812851, 812850] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812858 [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812858), message_loc: Only, in_order_delivery: false, message_number: 127, timestamp: 1574315, dest_sockid: SocketID(81), payload: b"812858" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK with lost packets: ll=[812849, 812855] [SeqNumber(812849), SeqNumber(812855)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812849), SeqNumber(812855)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1582426, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812849, 812855] }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Loss list=[812849, 812855] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812849) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812848, buffer=[812857, 812856, 812854, 812853, 812852, 812851, 812850, 812849] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812855) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812849) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812850) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812851) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812852) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812853) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812854) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812854, buffer=[812857, 812856, 812855] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812855) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812856) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812857) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812858), lr_ack_acked=SeqNumber(812849) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1682122, dest_sockid: SocketID(13), control_type: Ack(24, AckControlInfo { ack_number: SeqNumber(812858), rtt: Some(17044), rtt_variance: Some(22729), buffer_available: None, packet_recv_rate: Some(1394701), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812859 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812858)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812857, buffer=[812859] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1695950, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812858] }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Loss list=[812858] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812858) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812857, buffer=[812859, 812858] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812858) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812859) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812860), lr_ack_acked=SeqNumber(812858) [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Control { timestamp: 1791776, dest_sockid: SocketID(13), control_type: Ack(25, AckControlInfo { ack_number: SeqNumber(812860), rtt: Some(16624), rtt_variance: Some(19308), buffer_available: None, packet_recv_rate: Some(1457582), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812860 [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812860), message_loc: Only, in_order_delivery: false, message_number: 129, timestamp: 1795404, dest_sockid: SocketID(81), payload: b"812860" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812861 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812860)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Control { timestamp: 1826332, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812860] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812859, buffer=[812861] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812862 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812859, buffer=[812862, 812861] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812863 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812859, buffer=[812863, 812862, 812861] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812860), lr_ack_acked=SeqNumber(812858) [INFO] [stdout] 2019-02-01 01:33:37 INFO [tests] Dropping packet: (Control { timestamp: 1887673, dest_sockid: SocketID(13), control_type: Ack(26, AckControlInfo { ack_number: SeqNumber(812860), rtt: Some(16624), rtt_variance: Some(19308), buffer_available: None, packet_recv_rate: Some(1570775), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK with lost packets: ll=[812860] [SeqNumber(812860)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending NAK for=[SeqNumber(812860)] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1887755, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812860] }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Loss list=[812860] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812860) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812859, buffer=[812863, 812862, 812861, 812860] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812864 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812860) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812861) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812862) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812863) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812863, buffer=[812864] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812864) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812864, buffer=[812865] [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Releasing SeqNumber(812865) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812866), lr_ack_acked=SeqNumber(812858) [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Got packet: Control { timestamp: 1983355, dest_sockid: SocketID(13), control_type: Ack(27, AckControlInfo { ack_number: SeqNumber(812866), rtt: Some(16624), rtt_variance: Some(19308), buffer_available: None, packet_recv_rate: Some(1675767), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::sender] Sending packet: 812866 [INFO] [stdout] 2019-02-01 01:33:37 INFO [srt::receiver] lr=812865, buffer=[812866] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet: 812867 [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812867), message_loc: Only, in_order_delivery: false, message_number: 136, timestamp: 2134531, dest_sockid: SocketID(81), payload: b"812867" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet: 812868 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Releasing SeqNumber(812866) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812867)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] lr=812866, buffer=[812868] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2156545, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812867] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812867] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812867) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] lr=812866, buffer=[812868, 812867] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Releasing SeqNumber(812867) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Releasing SeqNumber(812868) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812869), lr_ack_acked=SeqNumber(812866) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2182322, dest_sockid: SocketID(13), control_type: Ack(28, AckControlInfo { ack_number: SeqNumber(812869), rtt: Some(23187), rtt_variance: Some(26937), buffer_available: None, packet_recv_rate: Some(1802521), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet: 812869 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] lr=812868, buffer=[812869] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Releasing SeqNumber(812869) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812870), lr_ack_acked=SeqNumber(812869) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2300838, dest_sockid: SocketID(13), control_type: Ack(29, AckControlInfo { ack_number: SeqNumber(812870), rtt: Some(20318), rtt_variance: Some(26878), buffer_available: None, packet_recv_rate: Some(1848517), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet: 812870 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] lr=812869, buffer=[812870] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet: 812871 [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812871), message_loc: Only, in_order_delivery: false, message_number: 140, timestamp: 2324652, dest_sockid: SocketID(81), payload: b"812871" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet: 812872 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Releasing SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] lr=812870, buffer=[812872] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2349887, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812871] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK with lost packets: ll=[812871] [SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2409676, dest_sockid: SocketID(13), control_type: Ack(30, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(17853), rtt_variance: Some(26728), buffer_available: None, packet_recv_rate: Some(1946264), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Control { timestamp: 2416996, dest_sockid: SocketID(81), control_type: Ack2(30) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2409738, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812871] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812871, 812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812871), message_loc: Only, in_order_delivery: false, message_number: 140, timestamp: 2324652, dest_sockid: SocketID(81), payload: b"812871" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812871), message_loc: Only, in_order_delivery: false, message_number: 140, timestamp: 2324652, dest_sockid: SocketID(81), payload: b"812871" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK with lost packets: ll=[812871] [SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Control { timestamp: 2625976, dest_sockid: SocketID(13), control_type: Ack(32, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(17853), rtt_variance: Some(26728), buffer_available: None, packet_recv_rate: Some(1946264), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK with lost packets: ll=[812871] [SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Control { timestamp: 2734169, dest_sockid: SocketID(13), control_type: Ack(33, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(17853), rtt_variance: Some(26728), buffer_available: None, packet_recv_rate: Some(1946264), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK with lost packets: ll=[812871] [SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Control { timestamp: 2842325, dest_sockid: SocketID(13), control_type: Ack(34, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(17853), rtt_variance: Some(26728), buffer_available: None, packet_recv_rate: Some(1946264), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK with lost packets: ll=[812871] [SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending NAK for=[SeqNumber(812871)] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812870) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Control { timestamp: 2950457, dest_sockid: SocketID(13), control_type: Ack(35, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(17853), rtt_variance: Some(26728), buffer_available: None, packet_recv_rate: Some(1946264), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2517905, dest_sockid: SocketID(13), control_type: Ack(31, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(17853), rtt_variance: Some(26728), buffer_available: None, packet_recv_rate: Some(1946264), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2518109, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812871] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2626059, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812871] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812871, 812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2734355, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812871] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812871, 812871, 812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Got packet: Control { timestamp: 2842475, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812871] }) } [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Loss list=[812871, 812871, 812871, 812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::receiver] lr=812870, buffer=[812872, 812871] [INFO] [stdout] 2019-02-01 01:33:38 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:38 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812871), message_loc: Only, in_order_delivery: false, message_number: 140, timestamp: 2324652, dest_sockid: SocketID(81), payload: b"812871" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Releasing SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Releasing SeqNumber(812872) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Received packet SeqNumber(812871) twice [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:39 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812871), message_loc: Only, in_order_delivery: false, message_number: 140, timestamp: 2324652, dest_sockid: SocketID(81), payload: b"812871" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812873 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812872, buffer=[812873] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812874 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Releasing SeqNumber(812873) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812873, buffer=[812874] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Releasing SeqNumber(812874) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812875), lr_ack_acked=SeqNumber(812871) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Got packet: Control { timestamp: 3517525, dest_sockid: SocketID(13), control_type: Ack(36, AckControlInfo { ack_number: SeqNumber(812875), rtt: Some(80880), rtt_variance: Some(143882), buffer_available: None, packet_recv_rate: Some(2344411), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812875 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812874, buffer=[812875] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812876 [INFO] [stdout] 2019-02-01 01:33:39 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812876), message_loc: Only, in_order_delivery: false, message_number: 145, timestamp: 3722840, dest_sockid: SocketID(81), payload: b"812876" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812877 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Releasing SeqNumber(812875) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Sending NAK for=[SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:39 INFO [tests] Dropping packet: (Control { timestamp: 3807652, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812876] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812875, buffer=[812877] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812878 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812875, buffer=[812878, 812877] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812879 [INFO] [stdout] 2019-02-01 01:33:39 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812879), message_loc: Only, in_order_delivery: false, message_number: 148, timestamp: 3987574, dest_sockid: SocketID(81), payload: b"812879" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet: 812880 [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] Sending NAK for=[SeqNumber(812879)] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812875, buffer=[812880, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812875, buffer=[812881, 812880, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Got packet: Control { timestamp: 4056578, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812879] }) } [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Loss list=[812879] [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812879) [INFO] [stdout] 2019-02-01 01:33:39 INFO [srt::receiver] lr=812875, buffer=[812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812876), lr_ack_acked=SeqNumber(812875) [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending NAK with lost packets: ll=[812876] [SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending NAK for=[SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet: 812882 [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Got packet: Control { timestamp: 4167073, dest_sockid: SocketID(13), control_type: Ack(37, AckControlInfo { ack_number: SeqNumber(812876), rtt: Some(91083), rtt_variance: Some(112567), buffer_available: None, packet_recv_rate: Some(3114216), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] lr=812875, buffer=[812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Got packet: Control { timestamp: 4167132, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812876] }) } [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Loss list=[812876] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812876) [INFO] [stdout] 2019-02-01 01:33:40 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812876), message_loc: Only, in_order_delivery: false, message_number: 145, timestamp: 3722840, dest_sockid: SocketID(81), payload: b"812876" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet: 812883 [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] lr=812875, buffer=[812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending NAK with lost packets: ll=[812876] [SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending NAK for=[SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Got packet: Control { timestamp: 4612800, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812876] }) } [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Loss list=[812876] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812876) [INFO] [stdout] 2019-02-01 01:33:40 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812876), message_loc: Only, in_order_delivery: false, message_number: 145, timestamp: 3722840, dest_sockid: SocketID(81), payload: b"812876" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet: 812884 [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] lr=812875, buffer=[812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet: 812885 [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] lr=812875, buffer=[812885, 812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::sender] Sending packet: 812886 [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] lr=812875, buffer=[812886, 812885, 812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending NAK with lost packets: ll=[812876] [SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:40 INFO [srt::receiver] Sending NAK for=[SeqNumber(812876)] [INFO] [stdout] 2019-02-01 01:33:40 INFO [tests] Dropping packet: (Control { timestamp: 5054981, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812876] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet: 812887 [INFO] [stdout] 2019-02-01 01:33:41 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812887), message_loc: Only, in_order_delivery: false, message_number: 156, timestamp: 5130894, dest_sockid: SocketID(81), payload: b"812887" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet: 812888 [INFO] [stdout] 2019-02-01 01:33:41 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812888), message_loc: Only, in_order_delivery: false, message_number: 157, timestamp: 5189272, dest_sockid: SocketID(81), payload: b"812888" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet: 812889 [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Sending NAK for=[SeqNumber(812887), SeqNumber(812888)] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] lr=812875, buffer=[812889, 812886, 812885, 812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Got packet: Control { timestamp: 5250410, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670761, 812888] }) } [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Loss list=[812887, 812888] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812887) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Sending NAK with lost packets: ll=[812876, 812887, 812888] [SeqNumber(812876), SeqNumber(812887), SeqNumber(812888)] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Sending NAK for=[SeqNumber(812876), SeqNumber(812887), SeqNumber(812888)] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] lr=812875, buffer=[812889, 812887, 812886, 812885, 812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Got packet: Control { timestamp: 5499029, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812876, -2146670761, 812888] }) } [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Loss list=[812888, 812876, 812887, 812888] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812888) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] lr=812875, buffer=[812889, 812888, 812887, 812886, 812885, 812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812876) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] lr=812875, buffer=[812889, 812888, 812887, 812886, 812885, 812884, 812883, 812882, 812881, 812880, 812879, 812878, 812877, 812876] [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812887) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812876) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812877) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812878) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812879) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812880) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812881) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812882) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812883) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812884) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812885) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812886) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812887) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812888) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Releasing SeqNumber(812889) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Received packet SeqNumber(812887) twice [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812888) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Received packet SeqNumber(812888) twice [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812890), lr_ack_acked=SeqNumber(812876) [INFO] [stdout] 2019-02-01 01:33:41 INFO [tests] Dropping packet: (Control { timestamp: 5939410, dest_sockid: SocketID(13), control_type: Ack(38, AckControlInfo { ack_number: SeqNumber(812890), rtt: Some(80121), rtt_variance: Some(111720), buffer_available: None, packet_recv_rate: Some(4782404), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:41 INFO [srt::sender] Sending packet: 812890 [INFO] [stdout] 2019-02-01 01:33:41 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812890), message_loc: Only, in_order_delivery: false, message_number: 159, timestamp: 6081012, dest_sockid: SocketID(81), payload: b"812890" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet: 812891 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Sending NAK for=[SeqNumber(812890)] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812889, buffer=[812891] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Got packet: Control { timestamp: 6298918, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812890] }) } [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Loss list=[812890] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812890), lr_ack_acked=SeqNumber(812876) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Got packet: Control { timestamp: 6382218, dest_sockid: SocketID(13), control_type: Ack(39, AckControlInfo { ack_number: SeqNumber(812890), rtt: Some(80121), rtt_variance: Some(111720), buffer_available: None, packet_recv_rate: Some(4938103), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812890) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812889, buffer=[812891, 812890] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet: 812892 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Releasing SeqNumber(812890) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Releasing SeqNumber(812891) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812891, buffer=[812892] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet: 812893 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Releasing SeqNumber(812892) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812892, buffer=[812893] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet: 812894 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Releasing SeqNumber(812893) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812893, buffer=[812894] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Releasing SeqNumber(812894) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812895), lr_ack_acked=SeqNumber(812890) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Got packet: Control { timestamp: 6920301, dest_sockid: SocketID(13), control_type: Ack(40, AckControlInfo { ack_number: SeqNumber(812895), rtt: Some(83687), rtt_variance: Some(84556), buffer_available: None, packet_recv_rate: Some(5605585), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet: 812895 [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812894, buffer=[812895] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Sending packet: 812896 [INFO] [stdout] 2019-02-01 01:33:42 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812896), message_loc: Only, in_order_delivery: false, message_number: 165, timestamp: 7040917, dest_sockid: SocketID(81), payload: b"812896" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Releasing SeqNumber(812895) [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] Sending NAK for=[SeqNumber(812896)] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::receiver] lr=812895, buffer=[812897] [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Got packet: Control { timestamp: 7041374, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812896] }) } [INFO] [stdout] 2019-02-01 01:33:42 INFO [srt::sender] Loss list=[812896] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812896), lr_ack_acked=SeqNumber(812895) [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::receiver] Sending NAK with lost packets: ll=[812896] [SeqNumber(812896)] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::receiver] Sending NAK for=[SeqNumber(812896)] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812896), lr_ack_acked=SeqNumber(812895) [INFO] [stdout] 2019-02-01 01:33:43 INFO [tests] Dropping packet: (Control { timestamp: 7695610, dest_sockid: SocketID(13), control_type: Ack(42, AckControlInfo { ack_number: SeqNumber(812896), rtt: Some(73249), rtt_variance: Some(84508), buffer_available: None, packet_recv_rate: Some(5937171), est_link_cap: Some(0) }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::receiver] Sending NAK with lost packets: ll=[812896] [SeqNumber(812896)] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::receiver] Sending NAK for=[SeqNumber(812896)] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::sender] Got packet: Control { timestamp: 7308127, dest_sockid: SocketID(13), control_type: Ack(41, AckControlInfo { ack_number: SeqNumber(812896), rtt: Some(73249), rtt_variance: Some(84508), buffer_available: None, packet_recv_rate: Some(5937171), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::sender] Got packet: Control { timestamp: 7308204, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812896] }) } [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::sender] Loss list=[812896, 812896] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::sender] Got packet: Control { timestamp: 7695694, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812896] }) } [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::sender] Loss list=[812896, 812896, 812896] [INFO] [stdout] 2019-02-01 01:33:43 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812896) [INFO] [stdout] 2019-02-01 01:33:43 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812896), message_loc: Only, in_order_delivery: false, message_number: 165, timestamp: 7040917, dest_sockid: SocketID(81), payload: b"812896" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812896) [INFO] [stdout] 2019-02-01 01:33:44 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812896), message_loc: Only, in_order_delivery: false, message_number: 165, timestamp: 7040917, dest_sockid: SocketID(81), payload: b"812896" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812896) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] lr=812895, buffer=[812897, 812896] [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet: 812898 [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Releasing SeqNumber(812896) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Releasing SeqNumber(812897) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] lr=812897, buffer=[812898] [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet: 812899 [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Releasing SeqNumber(812898) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] lr=812898, buffer=[812899] [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Releasing SeqNumber(812899) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812900), lr_ack_acked=SeqNumber(812896) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Got packet: Control { timestamp: 8567918, dest_sockid: SocketID(13), control_type: Ack(43, AckControlInfo { ack_number: SeqNumber(812900), rtt: Some(132020), rtt_variance: Some(178108), buffer_available: None, packet_recv_rate: Some(6612736), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:44 INFO [tests] Dropping packet: (Control { timestamp: 8570466, dest_sockid: SocketID(81), control_type: Ack2(43) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet: 812900 [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] lr=812899, buffer=[812900] [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet: 812901 [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Releasing SeqNumber(812900) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] lr=812900, buffer=[812901] [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::sender] Sending packet: 812902 [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] Releasing SeqNumber(812901) [INFO] [stdout] 2019-02-01 01:33:44 INFO [srt::receiver] lr=812901, buffer=[812902] [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Sending packet: 812903 [INFO] [stdout] 2019-02-01 01:33:45 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812903), message_loc: Only, in_order_delivery: false, message_number: 172, timestamp: 9216206, dest_sockid: SocketID(81), payload: b"812903" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::receiver] Releasing SeqNumber(812902) [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812903), lr_ack_acked=SeqNumber(812896) [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Got packet: Control { timestamp: 9284066, dest_sockid: SocketID(13), control_type: Ack(44, AckControlInfo { ack_number: SeqNumber(812903), rtt: Some(132020), rtt_variance: Some(178108), buffer_available: None, packet_recv_rate: Some(7251532), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Sending packet: 812904 [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:45 INFO [tests] Dropping packet: (Control { timestamp: 9654665, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::receiver] lr=812902, buffer=[812904] [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Sending packet: 812905 [INFO] [stdout] 2019-02-01 01:33:45 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812905), message_loc: Only, in_order_delivery: false, message_number: 174, timestamp: 9766124, dest_sockid: SocketID(81), payload: b"812905" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Sending packet: 812906 [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::receiver] Sending NAK for=[SeqNumber(812905)] [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::receiver] lr=812902, buffer=[812906, 812904] [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Got packet: Control { timestamp: 9954619, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812905] }) } [INFO] [stdout] 2019-02-01 01:33:45 INFO [srt::sender] Loss list=[812905] [INFO] [stdout] 2019-02-01 01:33:46 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812905) [INFO] [stdout] 2019-02-01 01:33:46 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903, 812905] [SeqNumber(812903), SeqNumber(812905)] [INFO] [stdout] 2019-02-01 01:33:46 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903), SeqNumber(812905)] [INFO] [stdout] 2019-02-01 01:33:46 INFO [tests] Dropping packet: (Control { timestamp: 10493779, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903, 812905] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:47 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903, 812905] [SeqNumber(812903), SeqNumber(812905)] [INFO] [stdout] 2019-02-01 01:33:47 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903), SeqNumber(812905)] [INFO] [stdout] 2019-02-01 01:33:47 INFO [tests] Dropping packet: (Control { timestamp: 11332802, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903, 812905] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:47 INFO [srt::receiver] lr=812902, buffer=[812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:47 INFO [srt::sender] Sending packet: 812907 [INFO] [stdout] 2019-02-01 01:33:47 INFO [srt::receiver] lr=812902, buffer=[812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:48 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:48 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:48 INFO [tests] Dropping packet: (Control { timestamp: 12171712, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:48 INFO [srt::sender] Sending packet: 812908 [INFO] [stdout] 2019-02-01 01:33:48 INFO [srt::receiver] lr=812902, buffer=[812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:48 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:48 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::sender] Got packet: Control { timestamp: 13010701, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) } [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::sender] Loss list=[812903] [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:33:49 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812903), message_loc: Only, in_order_delivery: false, message_number: 172, timestamp: 9216206, dest_sockid: SocketID(81), payload: b"812903" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::sender] Sending packet: 812909 [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::receiver] lr=812902, buffer=[812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::sender] Sending packet: 812910 [INFO] [stdout] 2019-02-01 01:33:49 INFO [srt::receiver] lr=812902, buffer=[812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet: 812911 [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] lr=812902, buffer=[812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet: 812912 [INFO] [stdout] 2019-02-01 01:33:50 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812912), message_loc: Only, in_order_delivery: false, message_number: 181, timestamp: 14246544, dest_sockid: SocketID(81), payload: b"812912" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] Sending NAK for=[SeqNumber(812912)] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] lr=812902, buffer=[812913, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Got packet: Control { timestamp: 14246665, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812912] }) } [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Loss list=[812912] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812912) [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] lr=812902, buffer=[812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:50 INFO [tests] Dropping packet: (Control { timestamp: 14690459, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet: 812914 [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] lr=812902, buffer=[812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet: 812915 [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] lr=812902, buffer=[812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet: 812916 [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::receiver] lr=812902, buffer=[812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:50 INFO [srt::sender] Sending packet: 812917 [INFO] [stdout] 2019-02-01 01:33:50 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812917), message_loc: Only, in_order_delivery: false, message_number: 186, timestamp: 15102853, dest_sockid: SocketID(81), payload: b"812917" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:51 INFO [srt::sender] Sending packet: 812918 [INFO] [stdout] 2019-02-01 01:33:51 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812918), message_loc: Only, in_order_delivery: false, message_number: 187, timestamp: 15880473, dest_sockid: SocketID(81), payload: b"812918" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:51 INFO [srt::sender] Sending packet: 812919 [INFO] [stdout] 2019-02-01 01:33:51 INFO [srt::receiver] Sending NAK for=[SeqNumber(812917), SeqNumber(812918)] [INFO] [stdout] 2019-02-01 01:33:51 INFO [srt::receiver] lr=812902, buffer=[812919, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:51 INFO [srt::sender] Got packet: Control { timestamp: 16074873, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670731, 812918] }) } [INFO] [stdout] 2019-02-01 01:33:51 INFO [srt::sender] Loss list=[812917, 812918] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812917) [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::receiver] lr=812902, buffer=[812919, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812918) [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::receiver] lr=812902, buffer=[812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Got packet: Control { timestamp: 16367855, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) } [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Loss list=[812903] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:33:52 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812903), message_loc: Only, in_order_delivery: false, message_number: 172, timestamp: 9216206, dest_sockid: SocketID(81), payload: b"812903" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Sending packet: 812920 [INFO] [stdout] 2019-02-01 01:33:52 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812920), message_loc: Only, in_order_delivery: false, message_number: 189, timestamp: 16586499, dest_sockid: SocketID(81), payload: b"812920" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Sending packet: 812921 [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::receiver] Sending NAK for=[SeqNumber(812920)] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::receiver] lr=812902, buffer=[812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Got packet: Control { timestamp: 17112275, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812920] }) } [INFO] [stdout] 2019-02-01 01:33:52 INFO [srt::sender] Loss list=[812920] [INFO] [stdout] 2019-02-01 01:33:53 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812920) [INFO] [stdout] 2019-02-01 01:33:53 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812920), message_loc: Only, in_order_delivery: false, message_number: 189, timestamp: 16586499, dest_sockid: SocketID(81), payload: b"812920" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:53 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903, 812920] [SeqNumber(812903), SeqNumber(812920)] [INFO] [stdout] 2019-02-01 01:33:53 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903), SeqNumber(812920)] [INFO] [stdout] 2019-02-01 01:33:53 INFO [srt::sender] Got packet: Control { timestamp: 18112389, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903, 812920] }) } [INFO] [stdout] 2019-02-01 01:33:53 INFO [srt::sender] Loss list=[812903, 812920] [INFO] [stdout] 2019-02-01 01:33:53 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:33:53 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812903), message_loc: Only, in_order_delivery: false, message_number: 172, timestamp: 9216206, dest_sockid: SocketID(81), payload: b"812903" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812920) [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::receiver] lr=812902, buffer=[812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::sender] Sending packet: 812922 [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::receiver] lr=812902, buffer=[812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::sender] Sending packet: 812923 [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::receiver] lr=812902, buffer=[812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::sender] Sending packet: 812924 [INFO] [stdout] 2019-02-01 01:33:54 INFO [srt::receiver] lr=812902, buffer=[812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Sending packet: 812925 [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] lr=812902, buffer=[812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Sending packet: 812926 [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] lr=812902, buffer=[812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Sending packet: 812927 [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] lr=812902, buffer=[812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Sending packet: 812928 [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] lr=812902, buffer=[812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] lr=812902, buffer=[812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Got packet: Control { timestamp: 19722743, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) } [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Loss list=[812903] [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:33:55 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812903), message_loc: Only, in_order_delivery: false, message_number: 172, timestamp: 9216206, dest_sockid: SocketID(81), payload: b"812903" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::sender] Sending packet: 812930 [INFO] [stdout] 2019-02-01 01:33:55 INFO [srt::receiver] lr=812902, buffer=[812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::sender] Sending packet: 812931 [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::receiver] lr=812902, buffer=[812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::sender] Sending packet: 812932 [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::receiver] lr=812902, buffer=[812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::sender] Sending packet: 812933 [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::receiver] lr=812902, buffer=[812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::sender] Sending packet: 812934 [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::receiver] lr=812902, buffer=[812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:56 INFO [srt::sender] Sending packet: 812935 [INFO] [stdout] 2019-02-01 01:33:56 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812935), message_loc: Only, in_order_delivery: false, message_number: 204, timestamp: 21069723, dest_sockid: SocketID(81), payload: b"812935" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::sender] Sending packet: 812936 [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] Sending NAK for=[SeqNumber(812935)] [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] lr=812902, buffer=[812936, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::sender] Got packet: Control { timestamp: 21271598, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812935] }) } [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::sender] Loss list=[812935] [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903, 812935] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:57 INFO [tests] Dropping packet: (Control { timestamp: 21402380, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812935) [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] lr=812902, buffer=[812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::sender] Sending packet: 812937 [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] lr=812902, buffer=[812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::sender] Sending packet: 812938 [INFO] [stdout] 2019-02-01 01:33:57 INFO [srt::receiver] lr=812902, buffer=[812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::sender] Sending packet: 812939 [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::receiver] lr=812902, buffer=[812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::sender] Sending packet: 812940 [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::receiver] lr=812902, buffer=[812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::sender] Sending packet: 812941 [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::receiver] lr=812902, buffer=[812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::sender] Sending packet: 812942 [INFO] [stdout] 2019-02-01 01:33:58 INFO [srt::receiver] lr=812902, buffer=[812942, 812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::sender] Sending packet: 812943 [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::receiver] lr=812902, buffer=[812943, 812942, 812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::sender] Sending packet: 812944 [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::receiver] lr=812902, buffer=[812944, 812943, 812942, 812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::receiver] lr=812902, buffer=[812945, 812944, 812943, 812942, 812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::sender] Sending packet: 812946 [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::receiver] lr=812902, buffer=[812946, 812945, 812944, 812943, 812942, 812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::receiver] Sending NAK with lost packets: ll=[812903] [SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::receiver] Sending NAK for=[SeqNumber(812903)] [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::sender] Got packet: Control { timestamp: 23918192, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812903] }) } [INFO] [stdout] 2019-02-01 01:33:59 INFO [srt::sender] Loss list=[812903] [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] lr=812902, buffer=[812946, 812945, 812944, 812943, 812942, 812941, 812940, 812939, 812938, 812937, 812936, 812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927, 812926, 812925, 812924, 812923, 812922, 812921, 812920, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908, 812907, 812906, 812905, 812904, 812903] [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812904) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812905) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812906) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812907) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812908) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812909) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812910) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812911) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812912) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812913) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812914) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812915) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812916) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812917) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812918) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812919) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812920) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812921) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812922) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812923) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812924) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812925) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812926) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812927) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812928) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812929) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812930) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812931) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812932) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812933) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812934) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812935) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812936) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812937) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812938) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812939) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812940) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812941) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812942) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812943) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812944) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812945) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Releasing SeqNumber(812946) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::receiver] Sending ACK; ack_num=SeqNumber(812947), lr_ack_acked=SeqNumber(812903) [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::sender] Got packet: Control { timestamp: 24757390, dest_sockid: SocketID(13), control_type: Ack(45, AckControlInfo { ack_number: SeqNumber(812947), rtt: Some(161830), rtt_variance: Some(181680), buffer_available: None, packet_recv_rate: Some(22357191), est_link_cap: Some(0) }) } [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::default_congestion_ctrl::sender] New window size: 1000 [INFO] [stdout] 2019-02-01 01:34:00 INFO [srt::sender] Sending packet: 812947 [INFO] [stdout] 2019-02-01 01:34:00 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812947), message_loc: Only, in_order_delivery: false, message_number: 216, timestamp: 24913688, dest_sockid: SocketID(81), payload: b"812947" }, V4(0.0.0.0:0)) [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::sender] Sending packet: 812948 [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::receiver] Sending NAK for=[SeqNumber(812947)] [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::receiver] lr=812946, buffer=[812948] [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::sender] Got packet: Control { timestamp: 25538569, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812947] }) } [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::sender] Loss list=[812947] [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::sender] Sending packet in loss list, seq=SeqNumber(812947) [INFO] [stdout] 2019-02-01 01:34:01 INFO [tests] Dropping packet: (Data { seq_number: SeqNumber(812947), message_loc: Only, in_order_delivery: false, message_number: 216, timestamp: 24913688, dest_sockid: SocketID(81), payload: b"812947" }, V4(0.0.0.0:0)) [INFO] [stderr] thread '' panicked at 'assertion failed: `(left == right)` [INFO] [stderr] left: `812947`, [INFO] [stderr] right: `1812731`', tests/tests.rs:266:32 [INFO] [stderr] stack backtrace: [INFO] [stdout] 2019-02-01 01:34:01 INFO [srt::sender] Sending packet: 812949 [INFO] [stderr] 0: 0x55b9ef6825a3 - std[698b411d1b74fcff]::sys::unix::backtrace::tracing::imp::unwind_backtrace [INFO] [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:39 [INFO] [stderr] 1: 0x55b9ef67e0eb - std[698b411d1b74fcff]::sys_common::backtrace::_print [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:70 [INFO] [stderr] 2: 0x55b9ef681086 - std[698b411d1b74fcff]::panicking::default_hook::{closure#1} [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:58 [INFO] [stderr] at src/libstd/panicking.rs:200 [INFO] [stderr] 3: 0x55b9ef680e04 - std[698b411d1b74fcff]::panicking::default_hook [INFO] [stderr] at src/libstd/panicking.rs:215 [INFO] [stderr] 4: 0x55b9ef681790 - std[698b411d1b74fcff]::panicking::rust_panic_with_hook [INFO] [stderr] at src/libstd/panicking.rs:478 [INFO] [stderr] 5: 0x55b9ef681311 - std[698b411d1b74fcff]::panicking::continue_panic_fmt [INFO] [stderr] at src/libstd/panicking.rs:385 [INFO] [stderr] 6: 0x55b9ef68125e - std[698b411d1b74fcff]::panicking::begin_panic_fmt [INFO] [stderr] at src/libstd/panicking.rs:340 [INFO] [stderr] 7: 0x55b9ef5c0b72 - tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1} [INFO] [stderr] at tests/tests.rs:266 [INFO] [stderr] 8: 0x55b9ef5a13c3 - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker), ()>>::map::<(), tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libcore/result.rs:458 [INFO] [stderr] 9: 0x55b9ef5a539f - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}> as futures[840e75ead15bc50b]::future::Future>::poll [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/map.rs:35 [INFO] [stderr] 10: 0x55b9ef5d2322 - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_future_notify::>::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:326 [INFO] [stderr] 11: 0x55b9ef5d22df - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::enter::<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>>::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:396 [INFO] [stderr] 12: 0x55b9ef5ad94a - futures[840e75ead15bc50b]::task_impl::std::set::<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::enter<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:78 [INFO] [stderr] 13: 0x55b9ef5d1a3a - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::enter::<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:396 [INFO] [stderr] 14: 0x55b9ef5cfdb3 - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_fn_notify::, , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:288 [INFO] [stderr] 15: 0x55b9ef5d1ae6 - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::poll_future_notify::> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:326 [INFO] [stderr] 16: 0x55b9ef5aea7d - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::wait_future::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:231 [INFO] [stderr] 17: 0x55b9ef5ae666 - ::with_current::<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>>::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:478 [INFO] [stderr] 18: 0x55b9ef5ab116 - >>::try_with::<::with_current<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/local.rs:296 [INFO] [stderr] 19: 0x55b9ef5aaccc - >>::with::<::with_current<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/local.rs:242 [INFO] [stderr] 20: 0x55b9ef5ada26 - ::with_current::<, tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:478 [INFO] [stderr] 21: 0x55b9ef5d322c - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}>>>::wait_future [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:228 [INFO] [stderr] 22: 0x55b9ef5a4d37 - , tests[ad011f131a032267]::test_with_loss::{closure#4}>, tests[ad011f131a032267]::test_with_loss::{closure#5}>>, tests[ad011f131a032267]::CounterChecker>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#7}::{closure#1}> as futures[840e75ead15bc50b]::future::Future>::wait [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/mod.rs:299 [INFO] [stderr] 23: 0x55b9ef5c0c44 - tests[ad011f131a032267]::test_with_loss::{closure#7} [INFO] [stderr] at tests/tests.rs:261 [INFO] [stderr] 24: 0x55b9ef5bbdf4 - std[698b411d1b74fcff]::sys_common::backtrace::__rust_begin_short_backtrace:: [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/sys_common/backtrace.rs:135 [INFO] [stderr] 25: 0x55b9ef5d23a2 - ::spawn_unchecked::::{closure#0}::{closure#0} [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/mod.rs:469 [INFO] [stderr] 26: 0x55b9ef5bdc02 - ::spawn_unchecked::{closure#0}::{closure#0}> as core[b849d6d0cd70c3a8]::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panic.rs:309 [INFO] [stderr] 27: 0x55b9ef5a7cc7 - std[698b411d1b74fcff]::panicking::try::do_call::::spawn_unchecked::{closure#0}::{closure#0}>, ()> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panicking.rs:297 [INFO] [stderr] 28: 0x55b9ef684bc9 - __rust_maybe_catch_panic [INFO] [stderr] at src/libpanic_unwind/lib.rs:92 [INFO] [stderr] 29: 0x55b9ef5a7ea8 - std[698b411d1b74fcff]::panicking::try::<(), std[698b411d1b74fcff]::panic::AssertUnwindSafe<::spawn_unchecked::{closure#0}::{closure#0}>> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panicking.rs:276 [INFO] [stderr] 30: 0x55b9ef5bbb24 - std[698b411d1b74fcff]::panic::catch_unwind::::spawn_unchecked::{closure#0}::{closure#0}>, ()> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panic.rs:388 [INFO] [stderr] 31: 0x55b9ef5d209b - ::spawn_unchecked::::{closure#0} [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/mod.rs:468 [INFO] [stderr] 32: 0x55b9ef5d33f4 - <::spawn_unchecked::{closure#0} as alloc[f4750af829db9b76]::boxed::FnBox<()>>::call_box [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/liballoc/boxed.rs:734 [INFO] [stderr] 33: 0x55b9ef6842bd - ::new::thread_start [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/liballoc/boxed.rs:744 [INFO] [stderr] at src/libstd/sys_common/thread.rs:14 [INFO] [stderr] at src/libstd/sys/unix/thread.rs:81 [INFO] [stderr] 34: 0x7f6ef7851493 - start_thread [INFO] [stderr] 35: 0x7f6ef737cace - __clone [INFO] [stderr] 36: 0x0 - [INFO] [stderr] thread '' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', src/libcore/result.rs:997:5 [INFO] [stderr] stack backtrace: [INFO] [stderr] 0: 0x55b9ef6825a3 - std[698b411d1b74fcff]::sys::unix::backtrace::tracing::imp::unwind_backtrace [INFO] [stderr] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:39 [INFO] [stderr] 1: 0x55b9ef67e0eb - std[698b411d1b74fcff]::sys_common::backtrace::_print [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:70 [INFO] [stderr] 2: 0x55b9ef681086 - std[698b411d1b74fcff]::panicking::default_hook::{closure#1} [INFO] [stderr] at src/libstd/sys_common/backtrace.rs:58 [INFO] [stderr] at src/libstd/panicking.rs:200 [INFO] [stderr] 3: 0x55b9ef680e04 - std[698b411d1b74fcff]::panicking::default_hook [INFO] [stderr] at src/libstd/panicking.rs:215 [INFO] [stderr] 4: 0x55b9ef681790 - std[698b411d1b74fcff]::panicking::rust_panic_with_hook [INFO] [stderr] at src/libstd/panicking.rs:478 [INFO] [stderr] 5: 0x55b9ef681311 - std[698b411d1b74fcff]::panicking::continue_panic_fmt [INFO] [stderr] at src/libstd/panicking.rs:385 [INFO] [stderr] 6: 0x55b9ef6811f5 - rust_begin_unwind [INFO] [stderr] at src/libstd/panicking.rs:312 [INFO] [stderr] 7: 0x55b9ef69778c - core[b849d6d0cd70c3a8]::panicking::panic_fmt [INFO] [stderr] at src/libcore/panicking.rs:85 [INFO] [stderr] 8: 0x55b9ef59ee72 - core[b849d6d0cd70c3a8]::result::unwrap_failed::> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libcore/macros.rs:16 [INFO] [stderr] 9: 0x55b9ef5a2b96 - , futures[840e75ead15bc50b]::sync::mpsc::SendError<(srt[d3851acab05dd45d]::packet::Packet, std[698b411d1b74fcff]::net::addr::SocketAddr)>>>::unwrap [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libcore/result.rs:798 [INFO] [stderr] 10: 0x55b9ef5beaff - as futures[840e75ead15bc50b]::sink::Sink>::poll_complete [INFO] [stderr] at tests/tests.rs:123 [INFO] [stderr] 11: 0x55b9ef5d2652 - , tests[ad011f131a032267]::test_with_loss::{closure#2}> as futures[840e75ead15bc50b]::sink::Sink>::poll_complete [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/stream/map_err.rs:62 [INFO] [stderr] 12: 0x55b9ef5aeef2 - , tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}> as futures[840e75ead15bc50b]::sink::Sink>::poll_complete [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/sink/map_err.rs:49 [INFO] [stderr] 13: 0x55b9ef5cb6d1 - , tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl> as futures[840e75ead15bc50b]::sink::Sink>::poll_complete [INFO] [stderr] at /opt/crater/workdir/src/sender.rs:267 [INFO] [stderr] 14: 0x55b9ef5cfb98 - , tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl> as futures[840e75ead15bc50b]::sink::Sink>::close [INFO] [stderr] at /opt/crater/workdir/src/sender.rs:371 [INFO] [stderr] 15: 0x55b9ef5c8a80 - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>> as futures[840e75ead15bc50b]::future::Future>::poll [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/stream/forward.rs:100 [INFO] [stderr] 16: 0x55b9ef5c7f25 - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}> as futures[840e75ead15bc50b]::future::Future>::poll [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/map_err.rs:30 [INFO] [stderr] 17: 0x55b9ef5a4dda - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}> as futures[840e75ead15bc50b]::future::Future>::poll [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/map.rs:30 [INFO] [stderr] 18: 0x55b9ef5d2302 - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_future_notify::>::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:326 [INFO] [stderr] 19: 0x55b9ef5d22af - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::enter::<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>>::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:396 [INFO] [stderr] 20: 0x55b9ef5ad7fa - futures[840e75ead15bc50b]::task_impl::std::set::<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::enter<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:78 [INFO] [stderr] 21: 0x55b9ef5d191a - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::enter::<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:396 [INFO] [stderr] 22: 0x55b9ef5cfd13 - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_fn_notify::, , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_future_notify>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result, ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:288 [INFO] [stderr] 23: 0x55b9ef5d1aa6 - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::poll_future_notify::> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:326 [INFO] [stderr] 24: 0x55b9ef5ae82d - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::wait_future::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:231 [INFO] [stderr] 25: 0x55b9ef5ae626 - ::with_current::<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>>::{closure#0} [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:478 [INFO] [stderr] 26: 0x55b9ef5aaec6 - >>::try_with::<::with_current<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/local.rs:296 [INFO] [stderr] 27: 0x55b9ef5aac7c - >>::with::<::with_current<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>>::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/local.rs:242 [INFO] [stderr] 28: 0x55b9ef5ad9e6 - ::with_current::<, std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::wait_future::{closure#0}, core[b849d6d0cd70c3a8]::result::Result<(), ()>> [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:478 [INFO] [stderr] 29: 0x55b9ef5d31fc - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}>>>::wait_future [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:228 [INFO] [stderr] 30: 0x55b9ef5a4c97 - , std[698b411d1b74fcff]::io::error::Error>, tests[ad011f131a032267]::test_with_loss::{closure#0}>, futures_timer[55af286a87610db5]::interval::Interval>, tests[ad011f131a032267]::test_with_loss::{closure#1}>, srt[d3851acab05dd45d]::sender::Sender, tests[ad011f131a032267]::test_with_loss::{closure#2}>, tests[ad011f131a032267]::test_with_loss::{closure#3}>, srt[d3851acab05dd45d]::default_congestion_ctrl::sender::DefaultSenderCongestionCtrl>>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#0}>, tests[ad011f131a032267]::test_with_loss::{closure#6}::{closure#1}> as futures[840e75ead15bc50b]::future::Future>::wait [INFO] [stderr] at /opt/crater/cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/mod.rs:299 [INFO] [stderr] 31: 0x55b9ef5c099f - tests[ad011f131a032267]::test_with_loss::{closure#6} [INFO] [stderr] at tests/tests.rs:253 [INFO] [stderr] 32: 0x55b9ef5bbdb4 - std[698b411d1b74fcff]::sys_common::backtrace::__rust_begin_short_backtrace:: [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/sys_common/backtrace.rs:135 [INFO] [stderr] 33: 0x55b9ef5d2362 - ::spawn_unchecked::::{closure#0}::{closure#0} [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/mod.rs:469 [INFO] [stderr] 34: 0x55b9ef5bdbc2 - ::spawn_unchecked::{closure#0}::{closure#0}> as core[b849d6d0cd70c3a8]::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panic.rs:309 [INFO] [stderr] 35: 0x55b9ef5a7c47 - std[698b411d1b74fcff]::panicking::try::do_call::::spawn_unchecked::{closure#0}::{closure#0}>, ()> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panicking.rs:297 [INFO] [stderr] 36: 0x55b9ef684bc9 - __rust_maybe_catch_panic [INFO] [stderr] at src/libpanic_unwind/lib.rs:92 [INFO] [stderr] 37: 0x55b9ef5a7d68 - std[698b411d1b74fcff]::panicking::try::<(), std[698b411d1b74fcff]::panic::AssertUnwindSafe<::spawn_unchecked::{closure#0}::{closure#0}>> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panicking.rs:276 [INFO] [stderr] 38: 0x55b9ef5bbad4 - std[698b411d1b74fcff]::panic::catch_unwind::::spawn_unchecked::{closure#0}::{closure#0}>, ()> [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/panic.rs:388 [INFO] [stderr] 39: 0x55b9ef5d1d3b - ::spawn_unchecked::::{closure#0} [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/libstd/thread/mod.rs:468 [INFO] [stderr] 40: 0x55b9ef5d3364 - <::spawn_unchecked::{closure#0} as alloc[f4750af829db9b76]::boxed::FnBox<()>>::call_box [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/liballoc/boxed.rs:734 [INFO] [stderr] 41: 0x55b9ef6842bd - ::new::thread_start [INFO] [stderr] at /rustc/7fea0229842e79db1159ea58ad9a653b288efee9/src/liballoc/boxed.rs:744 [INFO] [stderr] at src/libstd/sys_common/thread.rs:14 [INFO] [stderr] at src/libstd/sys/unix/thread.rs:81 [INFO] [stderr] 42: 0x7f6ef7851493 - start_thread [INFO] [stderr] 43: 0x7f6ef737cace - __clone [INFO] [stderr] 44: 0x0 - [INFO] [stderr] Doc-tests srt [INFO] [stdout] test test_with_loss ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `"docker" "inspect" "be650755510d6fbb73b2c207a3c3917e01113aa323d4ee70c560c80d611dd440"` [INFO] running `"docker" "rm" "-f" "be650755510d6fbb73b2c207a3c3917e01113aa323d4ee70c560c80d611dd440"` [INFO] [stdout] be650755510d6fbb73b2c207a3c3917e01113aa323d4ee70c560c80d611dd440