[INFO] fetching crate schengen 0.2.0...
[INFO] testing schengen-0.2.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate schengen 0.2.0 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate schengen 0.2.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate schengen 0.2.0
[INFO] tweaked toml for crates.io crate schengen 0.2.0 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate schengen 0.2.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate schengen 0.2.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 619eac844c4069d44f2ebf7d7f13f0238b4774e96944736ce02c04d9ff8d0506
[INFO] running `Command { std: "docker" "start" "-a" "619eac844c4069d44f2ebf7d7f13f0238b4774e96944736ce02c04d9ff8d0506", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "619eac844c4069d44f2ebf7d7f13f0238b4774e96944736ce02c04d9ff8d0506", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "619eac844c4069d44f2ebf7d7f13f0238b4774e96944736ce02c04d9ff8d0506", kill_on_drop: false }`
[INFO] [stdout] 619eac844c4069d44f2ebf7d7f13f0238b4774e96944736ce02c04d9ff8d0506
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 66acd92a409aa9de33e5338fba71971eaef31d0f5878c3a64d1f068ed9716bc9
[INFO] running `Command { std: "docker" "start" "-a" "66acd92a409aa9de33e5338fba71971eaef31d0f5878c3a64d1f068ed9716bc9", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling schengen v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.16s
[INFO] running `Command { std: "docker" "inspect" "66acd92a409aa9de33e5338fba71971eaef31d0f5878c3a64d1f068ed9716bc9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "66acd92a409aa9de33e5338fba71971eaef31d0f5878c3a64d1f068ed9716bc9", kill_on_drop: false }`
[INFO] [stdout] 66acd92a409aa9de33e5338fba71971eaef31d0f5878c3a64d1f068ed9716bc9
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d1c7824c3e0f1850b8c00103fea3b4ed8c3b2c88135d446a57ab773529ebaeac
[INFO] running `Command { std: "docker" "start" "-a" "d1c7824c3e0f1850b8c00103fea3b4ed8c3b2c88135d446a57ab773529ebaeac", kill_on_drop: false }`
[INFO] [stderr]    Compiling schengen v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.52s
[INFO] running `Command { std: "docker" "inspect" "d1c7824c3e0f1850b8c00103fea3b4ed8c3b2c88135d446a57ab773529ebaeac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d1c7824c3e0f1850b8c00103fea3b4ed8c3b2c88135d446a57ab773529ebaeac", kill_on_drop: false }`
[INFO] [stdout] d1c7824c3e0f1850b8c00103fea3b4ed8c3b2c88135d446a57ab773529ebaeac
[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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 01ece038c9ee2b1dbd15bf2eddbe18a10e21158f45b6b22a5ab9db650b26c24d
[INFO] running `Command { std: "docker" "start" "-a" "01ece038c9ee2b1dbd15bf2eddbe18a10e21158f45b6b22a5ab9db650b26c24d", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/schengen-2632b82a8364a153)
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test protocol::tests::test_cbye ... ok
[INFO] [stdout] test protocol::tests::test_ciak ... ok
[INFO] [stdout] test protocol::tests::test_cnop ... ok
[INFO] [stdout] test protocol::tests::test_cout ... ok
[INFO] [stdout] test protocol::tests::test_crop ... ok
[INFO] [stdout] test protocol::tests::test_csec ... ok
[INFO] [stdout] test protocol::tests::test_dclp ... ok
[INFO] [stdout] test protocol::tests::test_ddrg ... ok
[INFO] [stdout] test protocol::tests::test_dftr ... ok
[INFO] [stdout] test protocol::tests::test_dinf ... ok
[INFO] [stdout] test protocol::tests::test_dkdl ... ok
[INFO] [stdout] test protocol::tests::test_dkdn ... ok
[INFO] [stdout] test protocol::tests::test_dkrp ... ok
[INFO] [stdout] test protocol::tests::test_dkup ... ok
[INFO] [stdout] test protocol::tests::test_dmdn ... ok
[INFO] [stdout] test protocol::tests::test_dmmv ... ok
[INFO] [stdout] test protocol::tests::test_dmrm ... ok
[INFO] [stdout] test protocol::tests::test_dmup ... ok
[INFO] [stdout] test protocol::tests::test_dmwm ... ok
[INFO] [stdout] test protocol::tests::test_dsop ... ok
[INFO] [stdout] test protocol::tests::test_cclp ... ok
[INFO] [stdout] test protocol::tests::test_ebad ... ok
[INFO] [stdout] test protocol::tests::test_ebsy ... ok
[INFO] [stdout] test protocol::tests::test_eicv ... ok
[INFO] [stdout] test protocol::tests::test_calv ... ok
[INFO] [stdout] test protocol::tests::test_eunk ... ok
[INFO] [stdout] test protocol::tests::test_hello_barrier ... ok
[INFO] [stdout] test protocol::tests::test_hello_synergy ... ok
[INFO] [stdout] test protocol::tests::test_insufficient_data ... ok
[INFO] [stdout] test protocol::tests::test_lsyn ... ok
[INFO] [stdout] test protocol::tests::test_qinf ... ok
[INFO] [stdout] test protocol::tests::test_secn ... ok
[INFO] [stdout] test protocol::tests::test_unknown_message ... ok
[INFO] [stdout] test protocol::tests::test_cinn ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/client_tests.rs (/opt/rustwide/target/debug/deps/client_tests-0fc043cc3e0334e0)
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test test_client_connection_retry_with_max_retries ... ok
[INFO] [stdout] test test_client_dimension_configuration ... ok
[INFO] [stdout] test test_client_builder_requires_server_address ... ok
[INFO] [stdout] test test_client_name_configuration ... ok
[INFO] [stdout] test test_client_parse_host_port_ipv4 ... ok
[INFO] [stdout] test test_client_parse_host_port_hostname ... ok
[INFO] [stdout] test test_client_parse_host_port_ipv4_default ... ok
[INFO] [stdout] test test_client_parse_host_port_ipv6_bare ... ok
[INFO] [stdout] test test_client_parse_host_port_ipv6_bracketed ... ok
[INFO] [stdout] test test_client_retry_interval_configuration ... ok
[INFO] [stdout] test test_client_parse_host_port_ipv6_full ... ok
[INFO] [stdout] test test_client_parse_host_port_ipv6_no_port ... ok
[INFO] [stdout] test test_client_connection_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.31s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-43f2282fc39a37dc)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_server_rejects_unknown_client_name ... ok
[INFO] [stdout] test test_client_reports_dimensions ... ok
[INFO] [stdout] test test_server_message_sending ... ok
[INFO] [stdout] test test_client_can_connect ... ok
[INFO] [stderr]      Running tests/server_tests.rs (/opt/rustwide/target/debug/deps/server_tests-ecd6e7cf4ac48958)
[INFO] [stdout] test test_two_clients_can_connect ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test test_server_accepts_different_positions ... ok
[INFO] [stdout] test test_server_clients_list_empty_on_start ... ok
[INFO] [stdout] test test_server_port_configuration ... ok
[INFO] [stdout] test test_server_rejects_duplicate_positions ... ok
[INFO] [stdout] test test_server_relative_positioning ... ok
[INFO] [stdout] test test_server_rejects_relative_to_nonexistent_client ... ok
[INFO] [stdout] test test_server_rejects_duplicate_relative_positions ... ok
[INFO] [stdout] test test_server_binds_to_port ... ok
[INFO] [stdout] test test_server_multiple_clients_configuration ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests schengen
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test src/client.rs - client (line 11) - compile ... ok
[INFO] [stdout] test src/client.rs - client::Builder (line 158) - compile ... ok
[INFO] [stdout] test src/server.rs - server (line 111) - compile ... ok
[INFO] [stdout] test src/server.rs - server (line 29) - compile ... ok
[INFO] [stdout] test src/server.rs - server::Builder::add_client (line 549) - compile ... ok
[INFO] [stdout] test src/protocol.rs - protocol (line 29) ... ok
[INFO] [stdout] test src/protocol.rs - protocol::parse_message (line 1625) ... ok
[INFO] [stdout] test src/protocol.rs - protocol::Message::to_bytes (line 1560) ... ok
[INFO] [stdout] test src/protocol.rs - protocol::ProtocolMessage (line 252) ... ok
[INFO] [stdout] test src/server.rs - server::ClientBuilder<client_builder_state::Ready>::relative_to (line 297) ... ok
[INFO] [stdout] test src/protocol.rs - protocol::parse_message_with_length (line 1742) ... ok
[INFO] [stdout] test src/server.rs - server::ClientBuilder (line 233) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.40s; merged doctests compilation took 1.38s
[INFO] running `Command { std: "docker" "inspect" "01ece038c9ee2b1dbd15bf2eddbe18a10e21158f45b6b22a5ab9db650b26c24d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "01ece038c9ee2b1dbd15bf2eddbe18a10e21158f45b6b22a5ab9db650b26c24d", kill_on_drop: false }`
[INFO] [stdout] 01ece038c9ee2b1dbd15bf2eddbe18a10e21158f45b6b22a5ab9db650b26c24d