[INFO] fetching crate libjuice-rs 0.1.0... [INFO] testing libjuice-rs-0.1.0 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1 [INFO] extracting crate libjuice-rs 0.1.0 into /workspace/builds/worker-3-tc1/source [INFO] started tweaking crates.io crate libjuice-rs 0.1.0 [INFO] finished tweaking crates.io crate libjuice-rs 0.1.0 [INFO] tweaked toml for crates.io crate libjuice-rs 0.1.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate libjuice-rs 0.1.0 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 71 packages to latest compatible versions [INFO] [stderr] Adding env_logger v0.9.3 (available: v0.11.8) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded libjuice-sys v0.9.7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 778ca731e5677c07b6e5d2139e807125a707bf16e9d066f96eb90000d8f09623 [INFO] running `Command { std: "docker" "start" "-a" "778ca731e5677c07b6e5d2139e807125a707bf16e9d066f96eb90000d8f09623", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "778ca731e5677c07b6e5d2139e807125a707bf16e9d066f96eb90000d8f09623", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "778ca731e5677c07b6e5d2139e807125a707bf16e9d066f96eb90000d8f09623", kill_on_drop: false }` [INFO] [stdout] 778ca731e5677c07b6e5d2139e807125a707bf16e9d066f96eb90000d8f09623 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 48e011718bcf9b8c4f66456d37e397daf4e7a1d71c8ce6b17e0ba03e6ebd84bb [INFO] running `Command { std: "docker" "start" "-a" "48e011718bcf9b8c4f66456d37e397daf4e7a1d71c8ce6b17e0ba03e6ebd84bb", kill_on_drop: false }` [INFO] [stderr] Compiling glob v0.3.3 [INFO] [stderr] Compiling regex-syntax v0.8.6 [INFO] [stderr] Compiling proc-macro2 v1.0.101 [INFO] [stderr] Compiling unicode-width v0.1.14 [INFO] [stderr] Compiling linux-raw-sys v0.4.15 [INFO] [stderr] Compiling vec_map v0.8.2 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling rustix v0.38.44 [INFO] [stderr] Compiling nom v7.1.3 [INFO] [stderr] Compiling libloading v0.8.8 [INFO] [stderr] Compiling bindgen v0.59.2 [INFO] [stderr] Compiling humantime v2.2.0 [INFO] [stderr] Compiling strsim v0.8.0 [INFO] [stderr] Compiling home v0.5.11 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling termcolor v1.4.1 [INFO] [stderr] Compiling lazycell v1.3.0 [INFO] [stderr] Compiling rustc-hash v1.1.0 [INFO] [stderr] Compiling peeking_take_while v0.1.2 [INFO] [stderr] Compiling cmake v0.1.54 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling clang-sys v1.8.1 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling cexpr v0.6.0 [INFO] [stderr] Compiling regex-automata v0.4.10 [INFO] [stderr] Compiling which v4.4.2 [INFO] [stderr] Compiling regex v1.11.2 [INFO] [stderr] Compiling env_logger v0.9.3 [INFO] [stderr] Compiling libjuice-sys v0.9.7 [INFO] [stderr] Compiling libjuice-rs v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 25.13s [INFO] running `Command { std: "docker" "inspect" "48e011718bcf9b8c4f66456d37e397daf4e7a1d71c8ce6b17e0ba03e6ebd84bb", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "48e011718bcf9b8c4f66456d37e397daf4e7a1d71c8ce6b17e0ba03e6ebd84bb", kill_on_drop: false }` [INFO] [stdout] 48e011718bcf9b8c4f66456d37e397daf4e7a1d71c8ce6b17e0ba03e6ebd84bb [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f3d522c2e0eebd7857c053875ff482a1db39077c06fff117b8b5285da41edd32 [INFO] running `Command { std: "docker" "start" "-a" "f3d522c2e0eebd7857c053875ff482a1db39077c06fff117b8b5285da41edd32", kill_on_drop: false }` [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling regex-automata v0.4.10 [INFO] [stderr] Compiling nom v7.1.3 [INFO] [stderr] Compiling clang-sys v1.8.1 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling env_logger v0.9.3 [INFO] [stderr] Compiling cexpr v0.6.0 [INFO] [stderr] Compiling regex v1.11.2 [INFO] [stderr] Compiling bindgen v0.59.2 [INFO] [stderr] Compiling libjuice-sys v0.9.7 [INFO] [stderr] Compiling libjuice-rs v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 14.83s [INFO] running `Command { std: "docker" "inspect" "f3d522c2e0eebd7857c053875ff482a1db39077c06fff117b8b5285da41edd32", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f3d522c2e0eebd7857c053875ff482a1db39077c06fff117b8b5285da41edd32", kill_on_drop: false }` [INFO] [stdout] f3d522c2e0eebd7857c053875ff482a1db39077c06fff117b8b5285da41edd32 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] ef25d4a52bf87f707e6533067a76df2be49011b8fa8ca904173135d299c894da [INFO] running `Command { std: "docker" "start" "-a" "ef25d4a52bf87f707e6533067a76df2be49011b8fa8ca904173135d299c894da", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/libjuice_rs-8789a77186cb280a) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test agent::tests::build ... ok [INFO] [stderr] [2025-09-01T09:51:28.439513Z DEBUG libjuice_rs::log] server.c:422: Leaving server thread [INFO] [stderr] [2025-09-01T09:51:28.439648Z INFO libjuice_rs::log] agent.c:780: Changing state to connecting [INFO] [stderr] [2025-09-01T09:51:28.439698Z DEBUG libjuice_rs::agent::tests] State changed to: Connecting [INFO] [stdout] test server::tests::build ... ok [INFO] [stderr] [2025-09-01T09:51:28.443170Z WARN libjuice_rs::log] addr.c:274: Address resolution failed for stun.l.google.com:19302 [INFO] [stderr] [2025-09-01T09:51:28.443209Z ERROR libjuice_rs::log] agent.c:615: STUN server address resolution failed [INFO] [stderr] [2025-09-01T09:51:28.443218Z INFO libjuice_rs::log] agent.c:2321: Candidate gathering done [INFO] [stderr] [2025-09-01T09:51:28.443226Z DEBUG libjuice_rs::agent::tests] Gathering finished [INFO] [stderr] [2025-09-01T09:51:28.443704Z DEBUG libjuice_rs::log] agent.c:662: Leaving agent thread [INFO] [stderr] [2025-09-01T09:51:28.443727Z INFO libjuice_rs::log] agent.c:780: Changing state to disconnected [INFO] [stderr] [2025-09-01T09:51:28.443736Z DEBUG libjuice_rs::agent::tests] State changed to: Disconnected [INFO] [stdout] test agent::tests::gather ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/connectivity.rs (/opt/rustwide/target/debug/deps/connectivity-68ad9934358a836f) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stderr] [2025-09-01T09:51:28.448667Z INFO libjuice_rs::log] agent.c:780: Changing state to connecting [INFO] [stderr] [2025-09-01T09:51:28.448697Z INFO connectivity] first changed state to: Connecting [INFO] [stderr] [2025-09-01T09:51:28.448765Z INFO libjuice_rs::log] agent.c:780: Changing state to connecting [INFO] [stderr] [2025-09-01T09:51:28.448825Z INFO connectivity] first changed state to: Connecting [INFO] [stderr] [2025-09-01T09:51:28.449344Z INFO libjuice_rs::log] agent.c:780: Changing state to connecting [INFO] [stderr] [2025-09-01T09:51:28.449371Z INFO connectivity] second changed state to: Connecting [INFO] [stderr] [2025-09-01T09:51:28.449382Z WARN libjuice_rs::log] addr.c:274: Address resolution failed for stun.l.google.com:19302 [INFO] [stderr] [2025-09-01T09:51:28.449400Z ERROR libjuice_rs::log] agent.c:615: STUN server address resolution failed [INFO] [stderr] [2025-09-01T09:51:28.449447Z INFO libjuice_rs::log] agent.c:2321: Candidate gathering done [INFO] [stderr] [2025-09-01T09:51:28.449544Z INFO connectivity] first agent finished gathering [INFO] [stderr] [2025-09-01T09:51:28.449608Z INFO libjuice_rs::log] agent.c:780: Changing state to connecting [INFO] [stderr] [2025-09-01T09:51:28.449642Z INFO connectivity] second changed state to: Connecting [INFO] [stderr] [2025-09-01T09:51:28.449861Z WARN libjuice_rs::log] addr.c:274: Address resolution failed for stun.l.google.com:19302 [INFO] [stderr] [2025-09-01T09:51:28.449895Z ERROR libjuice_rs::log] agent.c:615: STUN server address resolution failed [INFO] [stderr] [2025-09-01T09:51:28.449904Z INFO libjuice_rs::log] agent.c:2321: Candidate gathering done [INFO] [stderr] [2025-09-01T09:51:28.449914Z INFO connectivity] first agent finished gathering [INFO] [stderr] [2025-09-01T09:51:28.449923Z WARN libjuice_rs::log] addr.c:274: Address resolution failed for stun.l.google.com:19302 [INFO] [stderr] [2025-09-01T09:51:28.449947Z ERROR libjuice_rs::log] agent.c:615: STUN server address resolution failed [INFO] [stderr] [2025-09-01T09:51:28.449958Z INFO libjuice_rs::log] agent.c:2321: Candidate gathering done [INFO] [stderr] [2025-09-01T09:51:28.449965Z INFO connectivity] second agent finished gathering [INFO] [stderr] [2025-09-01T09:51:28.449993Z WARN libjuice_rs::log] addr.c:274: Address resolution failed for stun.l.google.com:19302 [INFO] [stderr] [2025-09-01T09:51:28.450005Z ERROR libjuice_rs::log] agent.c:615: STUN server address resolution failed [INFO] [stderr] [2025-09-01T09:51:28.450012Z INFO libjuice_rs::log] agent.c:2321: Candidate gathering done [INFO] [stderr] [2025-09-01T09:51:28.450018Z INFO connectivity] second agent finished gathering [INFO] [stderr] [2025-09-01T09:51:28.450053Z DEBUG libjuice_rs::log] agent.c:806: STUN entry 0: Sending request to 127.0.0.1:34345 (5 retransmissions left) [INFO] [stderr] [2025-09-01T09:51:28.450070Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.450193Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:50805 [INFO] [stderr] [2025-09-01T09:51:28.451053Z DEBUG libjuice_rs::log] agent.c:2151: Obtained a new remote reflexive candidate, priority=1853882367 [INFO] [stderr] [2025-09-01T09:51:28.451333Z DEBUG libjuice_rs::log] agent.c:2410: STUN entry 0 pair matching incoming address [INFO] [stderr] [2025-09-01T09:51:28.451623Z DEBUG libjuice_rs::log] agent.c:1248: Received STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.451717Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding response [INFO] [stderr] [2025-09-01T09:51:28.451982Z DEBUG libjuice_rs::log] agent.c:806: STUN entry 0: Sending request to 127.0.0.1:50805 (5 retransmissions left) [INFO] [stderr] [2025-09-01T09:51:28.452139Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.452311Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:34345 [INFO] [stderr] [2025-09-01T09:51:28.452407Z DEBUG libjuice_rs::log] agent.c:2151: Obtained a new remote reflexive candidate, priority=0 [INFO] [stderr] [2025-09-01T09:51:28.452448Z DEBUG libjuice_rs::log] agent.c:1329: Received STUN Binding success response from peer [INFO] [stderr] [2025-09-01T09:51:28.452481Z DEBUG libjuice_rs::log] agent.c:2115: Gathered reflexive candidate: a=candidate:1 1 UDP 1853882367 127.0.0.1 50805 typ prflx [INFO] [stderr] [2025-09-01T09:51:28.452511Z DEBUG libjuice_rs::log] agent.c:1381: Candidate pair check succeeded [INFO] [stderr] [2025-09-01T09:51:28.452563Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:34345 [INFO] [stderr] [2025-09-01T09:51:28.452610Z DEBUG libjuice_rs::log] agent.c:2410: STUN entry 0 pair matching incoming address [INFO] [stderr] [2025-09-01T09:51:28.452638Z DEBUG libjuice_rs::log] agent.c:1248: Received STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.452669Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding response [INFO] [stderr] [2025-09-01T09:51:28.452736Z DEBUG libjuice_rs::log] agent.c:806: STUN entry 1: Sending request to 127.0.0.1:34345 (5 retransmissions left) [INFO] [stderr] [2025-09-01T09:51:28.452773Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.452832Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:50805 [INFO] [stderr] [2025-09-01T09:51:28.452892Z DEBUG libjuice_rs::log] agent.c:946: New selected pair [INFO] [stderr] [2025-09-01T09:51:28.452904Z INFO libjuice_rs::log] agent.c:780: Changing state to connected [INFO] [stderr] [2025-09-01T09:51:28.452920Z INFO connectivity] second changed state to: Connected [INFO] [stderr] [2025-09-01T09:51:28.452962Z DEBUG libjuice_rs::log] agent.c:1329: Received STUN Binding success response from peer [INFO] [stderr] [2025-09-01T09:51:28.453006Z DEBUG libjuice_rs::log] agent.c:2115: Gathered reflexive candidate: a=candidate:2 1 UDP 1853882111 127.0.0.1 34345 typ prflx [INFO] [stderr] [2025-09-01T09:51:28.453074Z DEBUG libjuice_rs::log] agent.c:1381: Candidate pair check succeeded [INFO] [stderr] [2025-09-01T09:51:28.453092Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:50805 [INFO] [stderr] [2025-09-01T09:51:28.453140Z DEBUG libjuice_rs::log] agent.c:2410: STUN entry 0 pair matching incoming address [INFO] [stderr] [2025-09-01T09:51:28.453148Z DEBUG libjuice_rs::log] agent.c:1248: Received STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.453155Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding response [INFO] [stderr] [2025-09-01T09:51:28.453195Z DEBUG libjuice_rs::log] agent.c:946: New selected pair [INFO] [stderr] [2025-09-01T09:51:28.453204Z INFO libjuice_rs::log] agent.c:780: Changing state to connected [INFO] [stderr] [2025-09-01T09:51:28.453212Z INFO connectivity] first changed state to: Connected [INFO] [stderr] [2025-09-01T09:51:28.453220Z DEBUG libjuice_rs::log] agent.c:1018: Requesting pair nomination (controlling) [INFO] [stderr] [2025-09-01T09:51:28.453229Z DEBUG libjuice_rs::log] agent.c:806: STUN entry 0: Sending request to 127.0.0.1:50805 (5 retransmissions left) [INFO] [stderr] [2025-09-01T09:51:28.453237Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.453311Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:34345 [INFO] [stderr] [2025-09-01T09:51:28.453356Z DEBUG libjuice_rs::log] agent.c:1329: Received STUN Binding success response from peer [INFO] [stderr] [2025-09-01T09:51:28.453392Z DEBUG libjuice_rs::log] agent.c:1381: Candidate pair check succeeded [INFO] [stderr] [2025-09-01T09:51:28.453420Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:34345 [INFO] [stderr] [2025-09-01T09:51:28.453471Z DEBUG libjuice_rs::log] agent.c:2410: STUN entry 0 pair matching incoming address [INFO] [stderr] [2025-09-01T09:51:28.453498Z DEBUG libjuice_rs::log] agent.c:1248: Received STUN Binding request [INFO] [stderr] [2025-09-01T09:51:28.453560Z DEBUG libjuice_rs::log] agent.c:1312: Got a nominated pair (controlled) [INFO] [stderr] [2025-09-01T09:51:28.453588Z DEBUG libjuice_rs::log] agent.c:1470: Sending STUN Binding response [INFO] [stderr] [2025-09-01T09:51:28.453647Z INFO libjuice_rs::log] agent.c:780: Changing state to completed [INFO] [stderr] [2025-09-01T09:51:28.453688Z INFO connectivity] second changed state to: Completed [INFO] [stderr] [2025-09-01T09:51:28.453706Z DEBUG libjuice_rs::log] agent.c:706: Received STUN datagram from 127.0.0.1:50805 [INFO] [stderr] [2025-09-01T09:51:28.453785Z DEBUG libjuice_rs::log] agent.c:1329: Received STUN Binding success response from peer [INFO] [stderr] [2025-09-01T09:51:28.453806Z DEBUG libjuice_rs::log] agent.c:1393: Got a nominated pair (controlling) [INFO] [stderr] [2025-09-01T09:51:28.453815Z INFO libjuice_rs::log] agent.c:780: Changing state to completed [INFO] [stderr] [2025-09-01T09:51:28.453822Z INFO connectivity] first changed state to: Completed [INFO] [stderr] [2025-09-01T09:51:30.450760Z DEBUG libjuice_rs::log] agent.c:725: Received non-STUN datagram from 127.0.0.1:34345 [INFO] [stderr] [2025-09-01T09:51:30.451332Z DEBUG libjuice_rs::log] agent.c:2377: STUN selected entry matching incoming address [INFO] [stderr] [2025-09-01T09:51:30.451492Z DEBUG libjuice_rs::log] agent.c:735: Received application datagram [INFO] [stderr] [2025-09-01T09:51:30.451543Z DEBUG connectivity] second received [104, 101, 108, 108, 111] [INFO] [stderr] [2025-09-01T09:51:30.452046Z DEBUG libjuice_rs::log] agent.c:725: Received non-STUN datagram from 127.0.0.1:50805 [INFO] [stderr] [2025-09-01T09:51:30.452093Z DEBUG libjuice_rs::log] agent.c:2377: STUN selected entry matching incoming address [INFO] [stderr] [2025-09-01T09:51:30.452104Z DEBUG libjuice_rs::log] agent.c:735: Received application datagram [INFO] [stderr] [2025-09-01T09:51:30.452112Z DEBUG connectivity] first received [119, 111, 114, 108, 100] [INFO] [stderr] [2025-09-01T09:51:30.452211Z DEBUG libjuice_rs::log] agent.c:662: Leaving agent thread [INFO] [stderr] [2025-09-01T09:51:30.452241Z INFO libjuice_rs::log] agent.c:780: Changing state to disconnected [INFO] [stderr] [2025-09-01T09:51:30.452275Z INFO connectivity] second changed state to: Disconnected [INFO] [stderr] [2025-09-01T09:51:30.452409Z DEBUG libjuice_rs::log] agent.c:662: Leaving agent thread [INFO] [stderr] [2025-09-01T09:51:30.452420Z INFO libjuice_rs::log] agent.c:780: Changing state to disconnected [INFO] [stderr] [2025-09-01T09:51:30.452427Z INFO connectivity] first changed state to: Disconnected [INFO] [stdout] test connectivity_trickle ... ok [INFO] [stderr] [2025-09-01T09:51:30.476542Z DEBUG libjuice_rs::log] agent.c:662: Leaving agent thread [INFO] [stderr] [2025-09-01T09:51:30.476567Z INFO libjuice_rs::log] agent.c:780: Changing state to disconnected [INFO] [stderr] [2025-09-01T09:51:30.476578Z INFO connectivity] second changed state to: Disconnected [INFO] [stderr] [2025-09-01T09:51:30.476680Z DEBUG libjuice_rs::log] agent.c:662: Leaving agent thread [INFO] [stderr] [2025-09-01T09:51:30.476692Z INFO libjuice_rs::log] agent.c:780: Changing state to disconnected [INFO] [stderr] [2025-09-01T09:51:30.476700Z INFO connectivity] first changed state to: Disconnected [INFO] [stdout] test connectivity_no_trickle ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- connectivity_no_trickle stdout ---- [INFO] [stdout] [2025-09-01T09:51:28.447881Z DEBUG libjuice_rs::log] ice.c:152: Created local description: ufrag="I5PU", pwd="zCKEk1PvZ4OAFhaMYNc6pZ" [INFO] [stdout] [2025-09-01T09:51:28.448080Z DEBUG libjuice_rs::log] ice.c:152: Created local description: ufrag="D03B", pwd="lPq64/cppfTOtOmb2qhJIX" [INFO] [stdout] [2025-09-01T09:51:28.448371Z INFO libjuice_rs::log] agent.c:780: Changing state to gathering [INFO] [stdout] [2025-09-01T09:51:28.448395Z INFO connectivity] first changed state to: Gathering [INFO] [stdout] [2025-09-01T09:51:28.448504Z WARN libjuice_rs::log] agent.c:232: No local host candidates gathered [INFO] [stdout] [2025-09-01T09:51:28.448517Z DEBUG libjuice_rs::log] agent.c:273: Assuming controlling mode [INFO] [stdout] [2025-09-01T09:51:28.448874Z DEBUG libjuice_rs::log] udp.c:159: UDP socket bound to any:5002 [INFO] [stdout] [2025-09-01T09:51:28.448888Z INFO libjuice_rs::log] agent.c:780: Changing state to gathering [INFO] [stdout] [2025-09-01T09:51:28.448896Z INFO connectivity] second changed state to: Gathering [INFO] [stdout] [2025-09-01T09:51:28.448997Z WARN libjuice_rs::log] agent.c:232: No local host candidates gathered [INFO] [stdout] [2025-09-01T09:51:28.449010Z DEBUG libjuice_rs::log] agent.c:273: Assuming controlling mode [INFO] [stdout] [2025-09-01T09:51:28.450552Z DEBUG libjuice_rs::log] ice.c:128: Parsed remote description: ufrag="I5PU", pwd="zCKEk1PvZ4OAFhaMYNc6pZ", candidates=0 [INFO] [stdout] [2025-09-01T09:51:28.450567Z DEBUG libjuice_rs::log] agent.c:326: Unfreezing 0 existing candidate pairs [INFO] [stdout] [2025-09-01T09:51:28.450572Z DEBUG libjuice_rs::log] agent.c:330: Adding 0 candidates from remote description [INFO] [stdout] [2025-09-01T09:51:28.450617Z DEBUG libjuice_rs::log] ice.c:128: Parsed remote description: ufrag="D03B", pwd="lPq64/cppfTOtOmb2qhJIX", candidates=0 [INFO] [stdout] [2025-09-01T09:51:28.450626Z DEBUG libjuice_rs::log] agent.c:326: Unfreezing 0 existing candidate pairs [INFO] [stdout] [2025-09-01T09:51:28.450631Z DEBUG libjuice_rs::log] agent.c:330: Adding 0 candidates from remote description [INFO] [stdout] [INFO] [stdout] thread 'connectivity_no_trickle' (31) panicked at tests/connectivity.rs:65:5: [INFO] [stdout] assertion `left == right` failed [INFO] [stdout] left: Connecting [INFO] [stdout] right: Completed [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x57f63545c462 - std::backtrace_rs::backtrace::libunwind::trace::h7cddb8376417e7cc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x57f63545c462 - std::backtrace_rs::backtrace::trace_unsynchronized::hb4e41acf4b349ff1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x57f63545c462 - std::sys::backtrace::_print_fmt::h1222b80910ba6eb5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x57f63545c462 - ::fmt::h5bb8a979ba5db788 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x57f63546d3ff - core::fmt::rt::Argument::fmt::h2ee2c138a50a7796 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x57f63546d3ff - core::fmt::write::h1e0dbf07fe3990bd [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x57f6354295b3 - std::io::default_write_fmt::h7d7ad5ed6a883d81 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x57f6354295b3 - std::io::Write::write_fmt::hc477d9325b345ece [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x57f6354353b2 - std::sys::backtrace::BacktraceLock::print::h788d486777205086 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x57f63543a1df - std::panicking::default_hook::{{closure}}::ha7bdfeb5949fc0fa [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:301:27 [INFO] [stdout] 10: 0x57f63543a071 - std::panicking::default_hook::h6dad75ec721846f4 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:325:9 [INFO] [stdout] 11: 0x57f6353d6bbe - as core::ops::function::Fn>::call::h33799d04b0b96146 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 12: 0x57f6353d6bbe - test::test_main_with_exit_callback::{{closure}}::h880e31c829a2eb5d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x57f63543a87e - as core::ops::function::Fn>::call::h5e1b8c652ea49180 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] 14: 0x57f63543a87e - std::panicking::panic_with_hook::hba00e869ada17676 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:842:13 [INFO] [stdout] 15: 0x57f63543a64a - std::panicking::panic_handler::{{closure}}::h3d21149c258e5ceb [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:707:13 [INFO] [stdout] 16: 0x57f6354354e9 - std::sys::backtrace::__rust_end_short_backtrace::h4f5d9b9dfb3e6ec1 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x57f63541d7bd - __rustc[9a7a9f9af7564de1]::rust_begin_unwind [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:698:5 [INFO] [stdout] 18: 0x57f635476900 - core::panicking::panic_fmt::h78e817a90331d98b [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x57f6354766b3 - core::panicking::assert_failed_inner::he0a3934689115f42 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:439:17 [INFO] [stdout] 20: 0x57f6350f19d8 - core::panicking::assert_failed::h31b6c472df605d7d [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panicking.rs:394:5 [INFO] [stdout] 21: 0x57f635104af2 - connectivity::connectivity_no_trickle::h7d8a54b318f83b5a [INFO] [stdout] at /opt/rustwide/workdir/tests/connectivity.rs:65:5 [INFO] [stdout] 22: 0x57f635105d17 - connectivity::connectivity_no_trickle::{{closure}}::h90e36acd620e1bcd [INFO] [stdout] at /opt/rustwide/workdir/tests/connectivity.rs:11:29 [INFO] [stdout] 23: 0x57f6350f5046 - core::ops::function::FnOnce::call_once::hf0829e7e90127906 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x57f6353d69bb - core::ops::function::FnOnce::call_once::h7f4b4fba903e39d5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 25: 0x57f6353d69bb - test::__rust_begin_short_backtrace::h9277cb6a2ccfc000 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:663:18 [INFO] [stdout] 26: 0x57f6353ec465 - test::run_test_in_process::{{closure}}::h9aea5ca90d1f4423 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:74 [INFO] [stdout] 27: 0x57f6353ec465 - as core::ops::function::FnOnce<()>>::call_once::h364f5fe6cc8afa85 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: 0x57f6353ec465 - std::panicking::catch_unwind::do_call::h2b2bec3317fe53ec [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 29: 0x57f6353ec465 - std::panicking::catch_unwind::hc3763734156da4af [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 30: 0x57f6353ec465 - std::panic::catch_unwind::h28038391e867eabc [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x57f6353ec465 - test::run_test_in_process::had0273166695a036 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:686:27 [INFO] [stdout] 32: 0x57f6353ec465 - test::run_test::{{closure}}::h743d09d4bb476605 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:607:43 [INFO] [stdout] 33: 0x57f6353c2e84 - test::run_test::{{closure}}::he950b8f9118d37e2 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/test/src/lib.rs:637:41 [INFO] [stderr] error: test failed, to rerun pass `--test connectivity` [INFO] [stdout] 34: 0x57f6353c2e84 - std::sys::backtrace::__rust_begin_short_backtrace::hbfaffa6539f6abb7 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 35: 0x57f6353c676a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcce3c2c65b9c3b20 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 36: 0x57f6353c676a - as core::ops::function::FnOnce<()>>::call_once::hb66b30b6d37985a5 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 37: 0x57f6353c676a - std::panicking::catch_unwind::do_call::h992bbe2c32dc1d79 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:590:40 [INFO] [stdout] 38: 0x57f6353c676a - std::panicking::catch_unwind::h412991d5237de610 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panicking.rs:553:19 [INFO] [stdout] 39: 0x57f6353c676a - std::panic::catch_unwind::ha82b139b3eb5840a [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x57f6353c676a - std::thread::Builder::spawn_unchecked_::{{closure}}::ha823b36f5114938e [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: 0x57f6353c676a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf120e7b1db22ac07 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 42: 0x57f63542f76f - as core::ops::function::FnOnce>::call_once::h3e049222c99298ac [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 43: 0x57f63542f76f - std::sys::pal::unix::thread::Thread::new::thread_start::h942e336943ad5963 [INFO] [stdout] at /rustc/cdb45c87e2cd43495379f7e867e3cc15dcee9f93/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 44: 0x7ff55edaaaa4 - [INFO] [stdout] 45: 0x7ff55ee37a34 - clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [2025-09-01T09:51:30.476456Z DEBUG libjuice_rs::log] agent.c:189: Waiting for agent thread [INFO] [stdout] [2025-09-01T09:51:30.476613Z DEBUG libjuice_rs::log] agent.c:151: Destroying agent [INFO] [stdout] [2025-09-01T09:51:30.476637Z DEBUG libjuice_rs::log] agent.c:189: Waiting for agent thread [INFO] [stdout] [2025-09-01T09:51:30.476744Z DEBUG libjuice_rs::log] agent.c:151: Destroying agent [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] connectivity_no_trickle [INFO] [stdout] [INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.03s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "ef25d4a52bf87f707e6533067a76df2be49011b8fa8ca904173135d299c894da", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ef25d4a52bf87f707e6533067a76df2be49011b8fa8ca904173135d299c894da", kill_on_drop: false }` [INFO] [stdout] ef25d4a52bf87f707e6533067a76df2be49011b8fa8ca904173135d299c894da