[INFO] fetching crate timecard 0.3.0...
[INFO] testing timecard-0.3.0 against master#ec6f9a5b4413f74386267ef8efc93712c2ce6db6 for pr-155739-1
[INFO] extracting crate timecard 0.3.0 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate timecard 0.3.0
[INFO] finished tweaking crates.io crate timecard 0.3.0
[INFO] tweaked toml for crates.io crate timecard 0.3.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate timecard 0.3.0 on toolchain ec6f9a5b4413f74386267ef8efc93712c2ce6db6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate timecard 0.3.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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.114
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.114
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.114
[INFO] [stderr]   Downloaded iana-time-zone v0.1.65
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.114
[INFO] [stderr]   Downloaded chrono v0.4.44
[INFO] [stderr]   Downloaded js-sys v0.3.91
[INFO] [stderr]   Downloaded cc v1.2.56
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] bcf87e1c747a584904f97bca616ad3f02f838bbe87750ed16f6cef087b9ca10a
[INFO] running `Command { std: "docker" "start" "-a" "bcf87e1c747a584904f97bca616ad3f02f838bbe87750ed16f6cef087b9ca10a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "bcf87e1c747a584904f97bca616ad3f02f838bbe87750ed16f6cef087b9ca10a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bcf87e1c747a584904f97bca616ad3f02f838bbe87750ed16f6cef087b9ca10a", kill_on_drop: false }`
[INFO] [stdout] bcf87e1c747a584904f97bca616ad3f02f838bbe87750ed16f6cef087b9ca10a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3b7d26197d7f91244d33c497a654190297cf203fc960f92d5e6de29578517979
[INFO] running `Command { std: "docker" "start" "-a" "3b7d26197d7f91244d33c497a654190297cf203fc960f92d5e6de29578517979", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling iana-time-zone v0.1.65
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling timecard v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.38s
[INFO] running `Command { std: "docker" "inspect" "3b7d26197d7f91244d33c497a654190297cf203fc960f92d5e6de29578517979", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3b7d26197d7f91244d33c497a654190297cf203fc960f92d5e6de29578517979", kill_on_drop: false }`
[INFO] [stdout] 3b7d26197d7f91244d33c497a654190297cf203fc960f92d5e6de29578517979
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6c8f3d70c05ea1fd4c7ec31d6337c3115446ec409aa787820eb52a4c52e39119
[INFO] running `Command { std: "docker" "start" "-a" "6c8f3d70c05ea1fd4c7ec31d6337c3115446ec409aa787820eb52a4c52e39119", kill_on_drop: false }`
[INFO] [stderr]    Compiling timecard v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.16s
[INFO] running `Command { std: "docker" "inspect" "6c8f3d70c05ea1fd4c7ec31d6337c3115446ec409aa787820eb52a4c52e39119", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6c8f3d70c05ea1fd4c7ec31d6337c3115446ec409aa787820eb52a4c52e39119", kill_on_drop: false }`
[INFO] [stdout] 6c8f3d70c05ea1fd4c7ec31d6337c3115446ec409aa787820eb52a4c52e39119
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8b5025eafbd3b830e034020b05ee8a071f9a6ad93b8143e045f79a776297cf6a
[INFO] running `Command { std: "docker" "start" "-a" "8b5025eafbd3b830e034020b05ee8a071f9a6ad93b8143e045f79a776297cf6a", 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/timecard-861f9e5047bddfc0)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test timecard::tests::clears_nonempty_timecard ... ok
[INFO] [stdout] test timecard::tests::clears_empty_timecard ... ok
[INFO] [stdout] test timecard::tests::clock_in_errors_when_already_clocked_in ... ok
[INFO] [stdout] test timecard::tests::clock_in_errors_when_time_in_future ... ok
[INFO] [stdout] test timecard::tests::clock_out_errors_when_not_clocked_in ... ok
[INFO] [stdout] test timecard::tests::clock_out_errors_with_invalid_times ... ok
[INFO] [stdout] test timecard::tests::clocks_in_when_clocked_out_with_entries ... ok
[INFO] [stdout] test timecard::tests::clocks_in_when_empty ... ok
[INFO] [stdout] test timecard::tests::clocks_out_when_clocked_in ... ok
[INFO] [stdout] test timecard::tests::clocks_out_with_multiple_entries ... ok
[INFO] [stdout] test timecard::tests::creates_empty_timecard ... ok
[INFO] [stdout] test timecard::tests::creates_timecard_with_closed_and_open_entries ... ok
[INFO] [stdout] test timecard::tests::creates_timecard_with_consecutive_entries ... ok
[INFO] [stdout] test timecard::tests::creates_timecard_with_single_closed_entry ... ok
[INFO] [stdout] test timecard::tests::creates_timecard_with_zero_duration_entry ... ok
[INFO] [stdout] test timecard::tests::filters_by_date_range_open_entry ... ok
[INFO] [stdout] test timecard::tests::filters_by_date_range_multiple_closed_entries ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_empty_timecard ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_multi_day_entry_with_separate_open_entry ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_entry_spanning_two_days ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_multiple_entries_on_different_days ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_open_entry_spanning_multiple_days ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_single_closed_past_entry ... ok
[INFO] [stdout] test timecard::tests::filters_by_day_single_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_multi_day_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_multi_day_multi_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_current_day_all_closed ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_current_day_all_closed ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_current_day_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_current_day_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_empty_timecard ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_two_closed_entries_with_break ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_multi_day_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_multi_day_multi_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_multi_day_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_single_closed_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_two_closed_entries_with_break ... ok
[INFO] [stdout] test timecard::tests::gets_duration_worked_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_entries ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_current_day_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_current_day_all_closed ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_multi_day_multi_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_multi_day_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_multi_day_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::creates_timecard_with_single_open_entry ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_multi_day_entry ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_two_closed_entries_with_break ... ok
[INFO] [stdout] test timecard::tests::rejects_bad_order ... ok
[INFO] [stdout] test timecard::tests::rejects_bad_entry ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::rejects_bad_none_end_entry_order ... ok
[INFO] [stdout] test timecard::tests::gets_expected_end_time_single_closed_entry ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_single_closed_entry ... ok
[INFO] [stdout] test timecard::tests::reports_clocked_in_with_open_entry ... ok
[INFO] [stdout] test timecard::tests::reports_clocked_out_when_empty ... ok
[INFO] [stdout] test timecard::tests::reports_clocked_out_with_closed_entries ... ok
[INFO] [stdout] test timecard::tests::undo_errors_when_empty ... ok
[INFO] [stdout] test timecard::tests::undo_removes_single_open_entry ... ok
[INFO] [stdout] test timecard::tests::undo_reopens_single_closed_entry ... ok
[INFO] [stdout] test timecard::tests::undo_removes_open_entry_with_prior_closed ... ok
[INFO] [stdout] test timecard::tests::undo_reopens_last_closed_entry ... ok
[INFO] [stdout] test timecard::tests::rejects_overlapping_entries_contained ... ok
[INFO] [stdout] test timecard::tests::rejects_overlapping_entries_out_of_order ... ok
[INFO] [stdout] test timecard::tests::reports_clocked_in_with_closed_and_open_entries ... ok
[INFO] [stdout] test timecard::tests::gets_duration_on_break_empty_timecard ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests timecard
[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" "8b5025eafbd3b830e034020b05ee8a071f9a6ad93b8143e045f79a776297cf6a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8b5025eafbd3b830e034020b05ee8a071f9a6ad93b8143e045f79a776297cf6a", kill_on_drop: false }`
[INFO] [stdout] 8b5025eafbd3b830e034020b05ee8a071f9a6ad93b8143e045f79a776297cf6a
