[INFO] fetching crate rubin 0.4.0... [INFO] testing rubin-0.4.0 against try#8de4c7234dd9b97c9d76b58671343fdbbc9a433e+target=x86_64-unknown-linux-musl for musl_upgrade_1_2_5_with_libc_patch_0 [INFO] extracting crate rubin 0.4.0 into /workspace/builds/worker-2-tc1/source [INFO] started tweaking crates.io crate rubin 0.4.0 [INFO] finished tweaking crates.io crate rubin 0.4.0 [INFO] tweaked toml for crates.io crate rubin 0.4.0 written to /workspace/builds/worker-2-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate rubin 0.4.0 on toolchain 8de4c7234dd9b97c9d76b58671343fdbbc9a433e [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "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" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Locking 69 packages to latest compatible versions [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded thread_local v1.1.9 [INFO] [stderr] Downloaded valuable v0.1.1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 138281c371fb99e855cf73f343073bb11f1b1b78c1257c5a2edd3534d249bd01 [INFO] running `Command { std: "docker" "start" "-a" "138281c371fb99e855cf73f343073bb11f1b1b78c1257c5a2edd3534d249bd01", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "138281c371fb99e855cf73f343073bb11f1b1b78c1257c5a2edd3534d249bd01", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "138281c371fb99e855cf73f343073bb11f1b1b78c1257c5a2edd3534d249bd01", kill_on_drop: false }` [INFO] [stdout] 138281c371fb99e855cf73f343073bb11f1b1b78c1257c5a2edd3534d249bd01 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "build" "--frozen" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 7492b6d7b6b2ee7a6c10ddb896b7bc6039349b92c59483a5c761111b25d16bce [INFO] running `Command { std: "docker" "start" "-a" "7492b6d7b6b2ee7a6c10ddb896b7bc6039349b92c59483a5c761111b25d16bce", kill_on_drop: false }` [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling syn v2.0.104 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling signal-hook-registry v1.4.5 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling rubin v0.4.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 10.82s [INFO] running `Command { std: "docker" "inspect" "7492b6d7b6b2ee7a6c10ddb896b7bc6039349b92c59483a5c761111b25d16bce", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7492b6d7b6b2ee7a6c10ddb896b7bc6039349b92c59483a5c761111b25d16bce", kill_on_drop: false }` [INFO] [stdout] 7492b6d7b6b2ee7a6c10ddb896b7bc6039349b92c59483a5c761111b25d16bce [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "test" "--frozen" "--no-run" "--message-format=json" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 645352c36e7870b320c10bbf72093176c052e9f061dbcfa7843e58b3a3896ea1 [INFO] running `Command { std: "docker" "start" "-a" "645352c36e7870b320c10bbf72093176c052e9f061dbcfa7843e58b3a3896ea1", kill_on_drop: false }` [INFO] [stderr] Compiling remove_dir_all v0.5.3 [INFO] [stderr] Compiling rand v0.4.6 [INFO] [stderr] Compiling tempdir v0.3.7 [INFO] [stderr] Compiling rubin v0.4.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.45s [INFO] running `Command { std: "docker" "inspect" "645352c36e7870b320c10bbf72093176c052e9f061dbcfa7843e58b3a3896ea1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "645352c36e7870b320c10bbf72093176c052e9f061dbcfa7843e58b3a3896ea1", kill_on_drop: false }` [INFO] [stdout] 645352c36e7870b320c10bbf72093176c052e9f061dbcfa7843e58b3a3896ea1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:90999bfc7ae267e83380e433d8e61a7c072ca6729e92edbae886d3423b3a6f4c" "/opt/rustwide/cargo-home/bin/cargo" "+8de4c7234dd9b97c9d76b58671343fdbbc9a433e" "test" "--frozen" "--target" "x86_64-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] e64578f2839d50e23450ad929e4667ed6ab8ff999920cbecfea392881f8a697b [INFO] running `Command { std: "docker" "start" "-a" "e64578f2839d50e23450ad929e4667ed6ab8ff999920cbecfea392881f8a697b", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/rubin-189ac592c7e67290) [INFO] [stdout] [INFO] [stdout] running 41 tests [INFO] [stdout] test net::parser::tests::create_appropriate_operation ... ok [INFO] [stdout] test net::parser::tests::parse_invalid_requests ... ok [INFO] [stdout] test net::parser::tests::detects_an_invalid_message ... ok [INFO] [stdout] test net::parser::tests::parse_requests_correctly ... ok [INFO] [stdout] test net::parser::tests::create_appropriate_request ... ok [INFO] [stdout] test net::parser::tests::parses_a_valid_response ... ok [INFO] [stdout] test net::parser::tests::parses_an_invalid_response_correctly ... ok [INFO] [stdout] test net::parser::tests::validate_decrement ... ok [INFO] [stdout] test net::parser::tests::validate_increment ... ok [INFO] [stdout] test net::parser::tests::validate_string_remove_message ... ok [INFO] [stdout] test net::parser::tests::validation_dump_message ... ok [INFO] [stdout] test net::parser::tests::validation_noop_message ... ok [INFO] [stdout] test net::parser::tests::validation_string_clear ... ok [INFO] [stdout] test net::parser::tests::validation_string_get_message ... ok [INFO] [stdout] test net::parser::tests::validation_string_set_message ... ok [INFO] [stdout] test store::mem::memstore::decrementer_multiple_keys ... ok [INFO] [stdout] test store::mem::memstore::empty ... ok [INFO] [stdout] test store::mem::memstore::incrementer_multiple_keys ... ok [INFO] [stdout] test store::mem::memstore::string_store_add_entries ... ok [INFO] [stdout] test store::mem::memstore::string_store_get_entries ... ok [INFO] [stdout] test store::mem::memstore::string_store_no_entries ... ok [INFO] [stdout] test store::mem::memstore::dump_store_to_disk ... ok [INFO] [stdout] test store::persistence::file_handling::fh_tests::creating_a_directory ... ok [INFO] [stdout] test store::persistence::file_handling::fh_tests::loading_an_empty_store ... ok [INFO] [stdout] test store::mem::memstore::decrementer ... ok [INFO] [stdout] test store::persistence::file_handling::fh_tests::write_a_store_out_sync ... ok [INFO] [stdout] test store::persistence::persistent_store::add_and_write ... ok [INFO] [stdout] test store::persistence::file_handling::fh_tests::write_a_store_out ... ok [INFO] [stdout] test store::mem::memstore::incrementer ... ok [INFO] [stdout] test store::persistence::persistent_store::empty_store ... ok [INFO] [stdout] test store::persistence::file_handling::fh_tests::loading_an_existing_store ... ok [INFO] [stdout] test store::persistence::persistent_store::setting_write_on_update ... ok [INFO] [stdout] test store::persistence::persistent_store::write_out_store ... ok [INFO] [stdout] test store::persistence::persistent_store::load_from_memstore ... ok [INFO] [stdout] test store::persistence::file_handling::fh_tests::write_a_store_out_and_compare ... ok [INFO] [stdout] test store::mem::memstore::clear_string_store ... ok [INFO] [stdout] test store::persistence::persistent_store::load_existing_store ... ok [INFO] [stdout] test store::mem::memstore::string_store_add_loads_of_entries ... ok [INFO] [stdout] test store::persistence::persistent_store::add_a_load_of_strings ... ok [INFO] [stdout] test store::persistence::persistent_store::add_string_and_decrement_counter ... ok [INFO] [stderr] Running tests/mod.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/mod-1ee3c62111ba36f2) [INFO] [stdout] test store::persistence::persistent_store::add_string_and_increment_counter ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.03s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] 2025-06-29T16:15:59.568322Z  INFO rubin::net::server: Started Rubin server at 127.0.0.1:9877 [INFO] [stdout] 2025-06-29T16:15:59.568645Z  INFO rubin::net::server: Started Rubin server at 127.0.0.1:9876 [INFO] [stdout] 2025-06-29T16:15:59.568790Z  INFO rubin::net::server: Started Rubin server at 127.0.0.1:9878 [INFO] [stdout] 2025-06-29T16:16:00.569727Z  INFO rubin::net::server: 127.0.0.1:56140 -> SET::user:1000 value1 [INFO] [stdout] 2025-06-29T16:16:00.570177Z  INFO rubin::net::server: 127.0.0.1:56140 <- OK [INFO] [stdout] 2025-06-29T16:16:00.570720Z  INFO rubin::net::server: 127.0.0.1:59702 -> SET::user:1000 [INFO] [stdout] 2025-06-29T16:16:00.570779Z ERROR rubin::net::server: failed to parse message - message failed validation [INFO] [stdout] 2025-06-29T16:16:00.570939Z  INFO rubin::net::server: 127.0.0.1:47530 -> SET::user:1000 value1 [INFO] [stdout] test net::net_integration_tests::gets_an_error_from_the_server ... ok [INFO] [stdout] 2025-06-29T16:16:00.571387Z  INFO rubin::net::server: 127.0.0.1:47530 <- OK [INFO] [stdout] test net::net_integration_tests::connects_to_server_and_performs_successful_request ... ok [INFO] [stdout] 2025-06-29T16:16:01.071648Z  INFO rubin::net::server: 127.0.0.1:56152 -> GET::user:1000 [INFO] [stdout] 2025-06-29T16:16:01.071910Z  INFO rubin::net::server: 127.0.0.1:56152 <- value1 [INFO] [stdout] test net::net_integration_tests::gets_value_from_the_server ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.51s [INFO] [stdout] [INFO] [stderr] Running tests/net.rs (/opt/rustwide/target/x86_64-unknown-linux-musl/debug/deps/net-68868ec2ea2e5687) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] 2025-06-29T16:16:01.076933Z  INFO rubin::net::server: Started Rubin server at 127.0.0.1:9877 [INFO] [stdout] 2025-06-29T16:16:01.076940Z  INFO rubin::net::server: Started Rubin server at 127.0.0.1:9876 [INFO] [stdout] 2025-06-29T16:16:01.077044Z  INFO rubin::net::server: Started Rubin server at 127.0.0.1:9878 [INFO] [stdout] 2025-06-29T16:16:02.078652Z  INFO rubin::net::server: 127.0.0.1:47544 -> SET::user:1000 value1 [INFO] [stdout] 2025-06-29T16:16:02.078684Z  INFO rubin::net::server: 127.0.0.1:56154 -> SET::user:1000 value1 [INFO] [stdout] 2025-06-29T16:16:02.078756Z  INFO rubin::net::server: 127.0.0.1:59718 -> SET::user:1000 [INFO] [stdout] 2025-06-29T16:16:02.078761Z  INFO rubin::net::server: 127.0.0.1:47544 <- OK [INFO] [stdout] 2025-06-29T16:16:02.078765Z  INFO rubin::net::server: 127.0.0.1:56154 <- OK [INFO] [stdout] 2025-06-29T16:16:02.078784Z ERROR rubin::net::server: failed to parse message - message failed validation [INFO] [stdout] test net_integration_tests::connects_to_server_and_performs_successful_request ... ok [INFO] [stdout] test net_integration_tests::gets_an_error_from_the_server ... ok [INFO] [stdout] 2025-06-29T16:16:02.580636Z  INFO rubin::net::server: 127.0.0.1:56160 -> GET::user:1000 [INFO] [stdout] 2025-06-29T16:16:02.580728Z  INFO rubin::net::server: 127.0.0.1:56160 <- value1 [INFO] [stdout] test net_integration_tests::gets_value_from_the_server ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.51s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "e64578f2839d50e23450ad929e4667ed6ab8ff999920cbecfea392881f8a697b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e64578f2839d50e23450ad929e4667ed6ab8ff999920cbecfea392881f8a697b", kill_on_drop: false }` [INFO] [stdout] e64578f2839d50e23450ad929e4667ed6ab8ff999920cbecfea392881f8a697b