[INFO] fetching crate taskchampion 0.3.0... [INFO] testing taskchampion-0.3.0 against beta-2021-09-08 for beta-1.56-1 [INFO] extracting crate taskchampion 0.3.0 into /workspace/builds/worker-2/source [INFO] validating manifest of crates.io crate taskchampion 0.3.0 on toolchain beta-2021-09-08 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate taskchampion 0.3.0 [INFO] finished tweaking crates.io crate taskchampion 0.3.0 [INFO] tweaked toml for crates.io crate taskchampion 0.3.0 written to /workspace/builds/worker-2/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] cc308981ede91384db1282a9b78dabac1c8f3e4c7e5e4743129ab349e03b5eef [INFO] running `Command { std: "docker" "start" "-a" "cc308981ede91384db1282a9b78dabac1c8f3e4c7e5e4743129ab349e03b5eef", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "cc308981ede91384db1282a9b78dabac1c8f3e4c7e5e4743129ab349e03b5eef", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cc308981ede91384db1282a9b78dabac1c8f3e4c7e5e4743129ab349e03b5eef", kill_on_drop: false }` [INFO] [stdout] cc308981ede91384db1282a9b78dabac1c8f3e4c7e5e4743129ab349e03b5eef [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] edda74a83f7470e8792a63192b2dac17b599690be9e66b375558a97611b6c96a [INFO] running `Command { std: "docker" "start" "-a" "edda74a83f7470e8792a63192b2dac17b599690be9e66b375558a97611b6c96a", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling const_fn v0.4.8 [INFO] [stderr] Compiling tindercrypt v0.2.2 [INFO] [stderr] Compiling chunked_transfer v1.4.0 [INFO] [stderr] Compiling time v0.2.27 [INFO] [stderr] Compiling cookie v0.14.4 [INFO] [stderr] Compiling lmdb-rkv-sys v0.9.6 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling rmp v0.8.10 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling time-macros-impl v0.1.2 [INFO] [stderr] Compiling thiserror-impl v1.0.29 [INFO] [stderr] Compiling serde v1.0.130 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling webpki v0.21.4 [INFO] [stderr] Compiling sct v0.6.1 [INFO] [stderr] Compiling rustls v0.19.1 [INFO] [stderr] Compiling webpki-roots v0.21.1 [INFO] [stderr] Compiling publicsuffix v1.5.6 [INFO] [stderr] Compiling time-macros v0.1.1 [INFO] [stderr] Compiling lmdb-rkv v0.12.3 [INFO] [stderr] Compiling thiserror v1.0.29 [INFO] [stderr] Compiling serde_json v1.0.67 [INFO] [stderr] Compiling rmp-serde v0.14.4 [INFO] [stderr] Compiling toml v0.5.8 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling uuid v0.8.2 [INFO] [stderr] Compiling cookie_store v0.12.0 [INFO] [stderr] Compiling kv v0.10.0 [INFO] [stderr] Compiling ureq v1.5.5 [INFO] [stderr] Compiling taskchampion v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 21.28s [INFO] running `Command { std: "docker" "inspect" "edda74a83f7470e8792a63192b2dac17b599690be9e66b375558a97611b6c96a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "edda74a83f7470e8792a63192b2dac17b599690be9e66b375558a97611b6c96a", kill_on_drop: false }` [INFO] [stdout] edda74a83f7470e8792a63192b2dac17b599690be9e66b375558a97611b6c96a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f02e8e5ee091534263212f5728d0f0dc24cdd20d29c9f09735985bb77acd222e [INFO] running `Command { std: "docker" "start" "-a" "f02e8e5ee091534263212f5728d0f0dc24cdd20d29c9f09735985bb77acd222e", kill_on_drop: false }` [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling tempdir v0.3.7 [INFO] [stderr] Compiling proptest v0.9.6 [INFO] [stderr] Compiling taskchampion v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 19.46s [INFO] running `Command { std: "docker" "inspect" "f02e8e5ee091534263212f5728d0f0dc24cdd20d29c9f09735985bb77acd222e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f02e8e5ee091534263212f5728d0f0dc24cdd20d29c9f09735985bb77acd222e", kill_on_drop: false }` [INFO] [stdout] f02e8e5ee091534263212f5728d0f0dc24cdd20d29c9f09735985bb77acd222e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-09-08" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 5da19a5b95596f87a7bc0e805c8fa37026d6c5829e2d15772bfc6d93436dc6c3 [INFO] running `Command { std: "docker" "start" "-a" "5da19a5b95596f87a7bc0e805c8fa37026d6c5829e2d15772bfc6d93436dc6c3", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/taskchampion-84838fc3a2d70b41) [INFO] [stdout] [INFO] [stdout] running 68 tests [INFO] [stdout] test replica::tests::get_does_not_exist ... ok [INFO] [stdout] test replica::tests::new_pending_adds_to_working_set ... ok [INFO] [stdout] test replica::tests::delete_task ... ok [INFO] [stdout] test replica::tests::new_task ... ok [INFO] [stdout] test replica::tests::modify_task ... ok [INFO] [stdout] test replica::tests::get_and_modify ... ok [INFO] [stdout] test storage::inmemory::test::add_to_working_set ... ok [INFO] [stdout] test storage::inmemory::test::get_working_set_empty ... ok [INFO] [stdout] test storage::inmemory::test::clear_working_set ... ok [INFO] [stdout] test storage::operation::test::test_related_updates_different_props ... ok [INFO] [stdout] test storage::operation::test::test_related_updates_same_prop ... ok [INFO] [stdout] test storage::operation::test::test_unrelated_create ... ok [INFO] [stdout] test task::test::test_get_tags ... ok [INFO] [stdout] test task::test::test_is_active ... ok [INFO] [stdout] test task::test::test_add_tags ... ok [INFO] [stdout] test task::test::test_get_tags_invalid_tags ... ok [INFO] [stdout] test task::test::test_has_tag ... ok [INFO] [stdout] test task::test::test_is_active_never_started ... ok [INFO] [stdout] test task::test::test_is_active_stopped ... ok [INFO] [stdout] test task::test::test_status ... ok [INFO] [stdout] test task::test::test_start ... ok [INFO] [stdout] test storage::operation::test::test_related_updates_same_prop_same_time ... ok [INFO] [stdout] test task::test::test_remove_tags ... ok [INFO] [stdout] test task::test::test_priority ... ok [INFO] [stdout] test task::test::test_stop ... ok [INFO] [stdout] test task::test::test_stop_multiple ... ok [INFO] [stdout] test task::test::test_tag_from_str ... ok [INFO] [stdout] test server::local::test::test_add_nonzero_base_forbidden ... ok [INFO] [stdout] test taskdb::tests::test_apply_create ... ok [INFO] [stdout] test taskdb::tests::test_apply_create_update ... ok [INFO] [stdout] test server::local::test::test_add_zero_base ... ok [INFO] [stdout] test taskdb::tests::test_apply_create_delete ... ok [INFO] [stdout] test server::local::test::test_add_nonzero_base ... ok [INFO] [stdout] test taskdb::tests::test_apply_create_update_delete_prop ... ok [INFO] [stdout] test taskdb::tests::test_apply_delete_not_present ... ok [INFO] [stdout] test taskdb::tests::rebuild_working_set_no_renumber ... ok [INFO] [stdout] test taskdb::tests::rebuild_working_set_renumber ... ok [INFO] [stdout] test taskdb::tests::test_apply_create_exists ... ok [INFO] [stdout] test taskdb::tests::test_apply_update_does_not_exist ... ok [INFO] [stdout] test utils::test::test_from_bytes ... ok [INFO] [stdout] test workingset::test::test_by_index ... ok [INFO] [stdout] test server::local::test::test_empty ... ok [INFO] [stdout] test taskdb::tests::test_sync_create_delete ... ok [INFO] [stdout] test workingset::test::test_new ... ok [INFO] [stdout] test taskdb::tests::test_sync ... ok [INFO] [stdout] test workingset::test::test_len_and_is_empty ... ok [INFO] [stdout] test workingset::test::test_by_uuid ... ok [INFO] [stdout] test workingset::test::test_iter ... ok [INFO] [stdout] test storage::kv::test::test_base_version_default ... ok [INFO] [stdout] test storage::kv::test::test_base_version_setting ... ok [INFO] [stdout] test storage::kv::test::test_set_task ... ok [INFO] [stdout] test storage::kv::test::test_all_tasks_empty ... ok [INFO] [stdout] test storage::kv::test::test_create_exists ... ok [INFO] [stdout] test storage::kv::test::clear_working_set ... ok [INFO] [stdout] test storage::kv::test::add_to_working_set ... ok [INFO] [stdout] test storage::kv::test::get_working_set_empty ... ok [INFO] [stdout] test storage::kv::test::test_all_tasks_and_uuids ... ok [INFO] [stdout] test storage::kv::test::test_create ... ok [INFO] [stdout] test storage::kv::test::test_delete_task_exists ... ok [INFO] [stdout] test storage::kv::test::test_get_missing ... ok [INFO] [stdout] test storage::kv::test::test_delete_task_missing ... ok [INFO] [stdout] test storage::kv::test::test_operations ... ok [INFO] [stdout] test utils::test::test_from_bytes_bad_len - should panic ... ok [INFO] [stdout] test server::remote::crypto::test::round_trip_bad_key ... ok [INFO] [stdout] test server::remote::crypto::test::round_trip ... ok [INFO] [stdout] test server::remote::crypto::test::round_trip_bad_pvid ... ok [INFO] [stdout] test storage::operation::test::transform_invariant_holds ... ok [INFO] [stdout] test taskdb::tests::transform_sequences_of_operations ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 68 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.66s [INFO] [stdout] [INFO] [stderr] Doc-tests taskchampion [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; finished in 0.00s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "5da19a5b95596f87a7bc0e805c8fa37026d6c5829e2d15772bfc6d93436dc6c3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5da19a5b95596f87a7bc0e805c8fa37026d6c5829e2d15772bfc6d93436dc6c3", kill_on_drop: false }` [INFO] [stdout] 5da19a5b95596f87a7bc0e805c8fa37026d6c5829e2d15772bfc6d93436dc6c3