[INFO] fetching crate rubin 0.4.0...
[INFO] testing rubin-0.4.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate rubin 0.4.0 into /workspace/builds/worker-0-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-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rubin 0.4.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "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" "+1.99.0-beta.8" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 54 packages to highest compatible versions
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tempdir v0.3.7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343
[INFO] running `Command { std: "docker" "start" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling smallvec v1.16.2
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling lazy_static v1.5.1
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling syn v3.0.6
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling tokio v1.53.2
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling rubin v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 13.37s
[INFO] running `Command { std: "docker" "inspect" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[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.16s
[INFO] running `Command { std: "docker" "inspect" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rubin-044f73b740845576)
[INFO] [stdout] 
[INFO] [stdout] running 41 tests
[INFO] [stdout] test net::parser::tests::validate_increment ... ok
[INFO] [stdout] test net::parser::tests::create_appropriate_request ... 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::parses_a_valid_response ... ok
[INFO] [stdout] test net::parser::tests::create_appropriate_operation ... ok
[INFO] [stdout] test net::parser::tests::validation_string_set_message ... ok
[INFO] [stdout] test net::parser::tests::parses_an_invalid_response_correctly ... ok
[INFO] [stdout] test store::mem::memstore::incrementer_multiple_keys ... ok
[INFO] [stdout] test store::mem::memstore::dump_store_to_disk ... ok
[INFO] [stdout] test store::mem::memstore::decrementer_multiple_keys ... ok
[INFO] [stdout] test net::parser::tests::validation_string_get_message ... ok
[INFO] [stdout] test store::mem::memstore::string_store_no_entries ... ok
[INFO] [stdout] test store::mem::memstore::decrementer ... ok
[INFO] [stdout] test net::parser::tests::parse_invalid_requests ... ok
[INFO] [stdout] test store::mem::memstore::empty ... ok
[INFO] [stdout] test store::mem::memstore::incrementer ... ok
[INFO] [stdout] test store::mem::memstore::string_store_get_entries ... ok
[INFO] [stdout] test net::parser::tests::validate_decrement ... ok
[INFO] [stdout] test store::persistence::file_handling::fh_tests::creating_a_directory ... ok
[INFO] [stdout] test net::parser::tests::parse_requests_correctly ... ok
[INFO] [stdout] test store::persistence::file_handling::fh_tests::loading_an_empty_store ... ok
[INFO] [stdout] test net::parser::tests::detects_an_invalid_message ... ok
[INFO] [stdout] test store::mem::memstore::clear_string_store ... ok
[INFO] [stdout] test store::mem::memstore::string_store_add_entries ... ok
[INFO] [stdout] test store::persistence::persistent_store::add_and_write ... 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_sync ... 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::load_existing_store ... ok
[INFO] [stdout] test store::persistence::file_handling::fh_tests::write_a_store_out_and_compare ... ok
[INFO] [stdout] test store::persistence::file_handling::fh_tests::write_a_store_out ... ok
[INFO] [stdout] test store::persistence::persistent_store::write_out_store ... ok
[INFO] [stdout] test store::persistence::persistent_store::setting_write_on_update ... 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_increment_counter ... ok
[INFO] [stdout] test store::persistence::persistent_store::add_string_and_decrement_counter ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.55s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mod.rs (/opt/rustwide/target/debug/deps/mod-62bc312109bf76c5)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] [2m2026-10-06T17:50:18.276880Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m Started Rubin server at 127.0.0.1:9876
[INFO] [stdout] [2m2026-10-06T17:50:18.276880Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m Started Rubin server at 127.0.0.1:9878
[INFO] [stdout] [2m2026-10-06T17:50:18.276925Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m Started Rubin server at 127.0.0.1:9877
[INFO] [stdout] [2m2026-10-06T17:50:19.278573Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:40512 -> SET::user:1000
[INFO] [stdout] [2m2026-10-06T17:50:19.278621Z[0m [31mERROR[0m [2mrubin::net::server[0m[2m:[0m failed to parse message - message failed validation
[INFO] [stdout] [2m2026-10-06T17:50:19.278617Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:53666 -> SET::user:1000 value1
[INFO] [stdout] [2m2026-10-06T17:50:19.278617Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:33190 -> SET::user:1000 value1
[INFO] [stdout] [2m2026-10-06T17:50:19.278716Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:53666 <- OK
[INFO] [stdout] [2m2026-10-06T17:50:19.278740Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:33190 <- OK
[INFO] [stdout] test net::net_integration_tests::gets_an_error_from_the_server ... ok
[INFO] [stdout] test net::net_integration_tests::connects_to_server_and_performs_successful_request ... ok
[INFO] [stdout] [2m2026-10-06T17:50:19.780598Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:37816 -> GET::user:1000
[INFO] [stdout] [2m2026-10-06T17:50:19.780692Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:37816 <- 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/debug/deps/net-55ea79be83deb857)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] [2m2026-10-06T17:50:19.784838Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m Started Rubin server at 127.0.0.1:9877
[INFO] [stdout] [2m2026-10-06T17:50:19.784838Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m Started Rubin server at 127.0.0.1:9878
[INFO] [stdout] [2m2026-10-06T17:50:19.784838Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m Started Rubin server at 127.0.0.1:9876
[INFO] [stdout] [2m2026-10-06T17:50:20.786538Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:47986 -> SET::user:1000
[INFO] [stdout] [2m2026-10-06T17:50:20.786539Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:37824 -> SET::user:1000 value1
[INFO] [stdout] [2m2026-10-06T17:50:20.786601Z[0m [31mERROR[0m [2mrubin::net::server[0m[2m:[0m failed to parse message - message failed validation
[INFO] [stdout] [2m2026-10-06T17:50:20.786650Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:37824 <- OK
[INFO] [stdout] [2m2026-10-06T17:50:20.786656Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:60488 -> SET::user:1000 value1
[INFO] [stdout] [2m2026-10-06T17:50:20.786751Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:60488 <- OK
[INFO] [stdout] test net_integration_tests::gets_an_error_from_the_server ... ok
[INFO] [stdout] test net_integration_tests::connects_to_server_and_performs_successful_request ... ok
[INFO] [stdout] [2m2026-10-06T17:50:21.289179Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:37838 -> GET::user:1000
[INFO] [stdout] [2m2026-10-06T17:50:21.289281Z[0m [32m INFO[0m [2mrubin::net::server[0m[2m:[0m 127.0.0.1:37838 <- 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] [stderr]    Doc-tests rubin
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test src/net/client/mod.rs - net::client (line 8) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 47) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::insert_string (line 66) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::remove_string (line 111) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::new (line 47) - compile ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::dump_store (line 247) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::decr (line 153) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::clear_strings (line 174) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::get_string (line 90) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 97) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::dump_store (line 196) - compile ... ok
[INFO] [stdout] test src/net/server/mod.rs - net::server::start (line 163) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 120) - compile ... ok
[INFO] [stdout] test src/net/client/mod.rs - net::client::RubinClient::incr (line 132) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence (line 53) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence (line 19) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence (line 36) - compile ... ok
[INFO] [stdout] test src/net/parser.rs - net::parser::parse_response (line 177) ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::get_string (line 211) - compile ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem (line 19) ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::from_store (line 156) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 22) ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::insert_string (line 77) ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::decr (line 325) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::incr (line 296) - compile ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::incr (line 184) ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::get_string (line 95) ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::get_string_store_ref (line 359) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::remove_string (line 235) - compile ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::remove_string (line 120) ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::clear_strings (line 265) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::from_existing (line 135) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::insert_string (line 186) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::new (line 101) - compile ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::new (line 58) ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::set_write_on_update (line 385) - compile ... ok
[INFO] [stdout] test src/store/persistence/mod.rs - store::persistence::PersistentStore::write (line 428) - compile ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::decr (line 212) ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::get_string_store_ref (line 163) ... ok
[INFO] [stdout] test src/store/mem/mod.rs - store::mem::MemStore::clear_strings (line 139) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.41s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343", kill_on_drop: false }`
[INFO] [stdout] f6829915b664bfe4a2e5b1256a9accc45c7e7a6be082f882fbd59078ca843343
