[INFO] fetching crate vce-engine 0.1.1...
[INFO] testing vce-engine-0.1.1 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate vce-engine 0.1.1 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate vce-engine 0.1.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate vce-engine 0.1.1
[INFO] tweaked toml for crates.io crate vce-engine 0.1.1 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vce-engine 0.1.1 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] crate crates.io crate vce-engine 0.1.1 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" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a
[INFO] running `Command { std: "docker" "start" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", 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" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a" "/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" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", 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" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling vce-engine v0.1.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/core/time.rs:73:14
[INFO] [stdout]    |
[INFO] [stdout] 73 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |              ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 73 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 73 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/core/time.rs:84:14
[INFO] [stdout]    |
[INFO] [stdout] 84 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |              ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 84 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 84 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.02s
[INFO] running `Command { std: "docker" "inspect" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", 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" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/core/time.rs:73:14
[INFO] [stdout]    |
[INFO] [stdout] 73 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |              ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 73 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 73 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/core/time.rs:84:14
[INFO] [stdout]    |
[INFO] [stdout] 84 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |              ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 84 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 84 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling vce-engine v0.1.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/core/time.rs:73:14
[INFO] [stdout]    |
[INFO] [stdout] 73 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |              ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(deprecated)]` on by default
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 73 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 73 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stdout]   --> src/core/time.rs:84:14
[INFO] [stdout]    |
[INFO] [stdout] 84 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |              ^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout] help: replace the use of the deprecated method
[INFO] [stdout]    |
[INFO] [stdout] 84 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout] 84 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.56s
[INFO] running `Command { std: "docker" "inspect" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", 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" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stderr]   --> src/core/time.rs:73:14
[INFO] [stderr]    |
[INFO] [stderr] 73 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr]    |              ^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(deprecated)]` on by default
[INFO] [stderr] help: replace the use of the deprecated method
[INFO] [stderr]    |
[INFO] [stderr] 73 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr] 73 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr]    |
[INFO] [stderr] 
[INFO] [stderr] warning: use of deprecated method `std::sync::atomic::Atomic::<u64>::fetch_update`: renamed to `try_update` for consistency
[INFO] [stderr]   --> src/core/time.rs:84:14
[INFO] [stderr]    |
[INFO] [stderr] 84 |             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr]    |              ^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: replace the use of the deprecated method
[INFO] [stderr]    |
[INFO] [stderr] 84 -             .fetch_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr] 84 +             .try_update(Ordering::Relaxed, Ordering::Relaxed, |current| {
[INFO] [stderr]    |
[INFO] [stderr] 
[INFO] [stderr] warning: `vce-engine` (lib) generated 2 warnings (run `cargo fix --lib -p vce-engine` to apply 2 suggestions)
[INFO] [stderr] warning: `vce-engine` (lib test) generated 2 warnings (2 duplicates)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/engine-eeb51e6e1309c482)
[INFO] [stdout] 
[INFO] [stdout] running 27 tests
[INFO] [stdout] test core::metadata::tests::invalid_names_are_rejected ... ok
[INFO] [stdout] test filesystem::path::tests::dot_components_are_resolved ... ok
[INFO] [stdout] test core::metadata::tests::valid_names_are_accepted ... ok
[INFO] [stdout] test filesystem::path::tests::join_appends_a_component ... ok
[INFO] [stdout] test filesystem::path::tests::repeated_separators_are_collapsed ... ok
[INFO] [stdout] test filesystem::path::tests::root_parses ... ok
[INFO] [stdout] test filesystem::path::tests::starts_with_is_prefix_aware ... ok
[INFO] [stdout] test core::metadata::tests::patch_removes_extensions_with_none ... ok
[INFO] [stdout] test core::metadata::tests::patch_applies_only_present_fields ... ok
[INFO] [stdout] test io::pipe::tests::pipe_transfers_data_and_reports_eof ... ok
[INFO] [stdout] test filesystem::path::tests::dotdot_clamps_at_root ... ok
[INFO] [stdout] test io::terminal::tests::character_events_carry_the_character ... ok
[INFO] [stdout] test io::input::tests::read_line_returns_final_line_without_newline ... ok
[INFO] [stdout] test filesystem::path::tests::nul_bytes_are_rejected ... ok
[INFO] [stdout] test io::pipe::tests::pipe_carries_empty_reader_after_eof ... ok
[INFO] [stdout] test io::terminal::tests::ctrl_shortcuts_are_detectable ... ok
[INFO] [stdout] test io::terminal::tests::special_keys_are_created_without_modifiers ... ok
[INFO] [stdout] test io::terminal::tests::size_holds_rows_and_columns ... ok
[INFO] [stdout] test io::tests::bytes_input_replays_its_data ... ok
[INFO] [stdout] test filesystem::path::tests::dotdot_components_are_resolved ... ok
[INFO] [stdout] test io::tests::empty_input_reports_eof ... ok
[INFO] [stdout] test io::tests::bytes_round_trip_through_a_pipe ... ok
[INFO] [stdout] test io::tests::bytes_output_accumulates ... ok
[INFO] [stdout] test filesystem::path::tests::simple_path_parses ... ok
[INFO] [stdout] test filesystem::path::tests::relative_paths_are_rejected ... ok
[INFO] [stdout] test io::input::tests::read_line_preserves_multibyte_characters ... ok
[INFO] [stdout] test io::tests::sink_output_discards ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/engine.rs (/opt/rustwide/target/debug/deps/engine-0bc5bc147a18024e)
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test change_owner_is_a_distinct_operation ... ok
[INFO] [stdout] test deleting_a_symlink_removes_the_link_not_the_target ... ok
[INFO] [stdout] test copy_events_carry_the_source ... ok
[INFO] [stdout] test create_and_lookup_roundtrip ... ok
[INFO] [stdout] test copy_into_own_subtree_is_rejected ... ok
[INFO] [stdout] test delete_removes_subtree_deepest_first ... ok
[INFO] [stdout] test detach_and_attach_move_objects_between_tree_and_store ... ok
[INFO] [stdout] test copy_is_deep_and_independent ... ok
[INFO] [stdout] test duplicate_names_are_rejected ... ok
[INFO] [stdout] test create_with_options_is_authorization_checked_like_create ... ok
[INFO] [stdout] test create_with_options_sets_owner_and_authorization_atomically ... ok
[INFO] [stdout] test identity_survives_move ... ok
[INFO] [stdout] test lookup_authorizes_traversal_before_the_final_object ... ok
[INFO] [stdout] test denied_operations_fail_without_mutating_state ... ok
[INFO] [stdout] test read_does_not_update_access_time ... ok
[INFO] [stdout] test read_emits_object_read_events ... ok
[INFO] [stdout] test lookup_follows_symlinks_to_their_targets ... ok
[INFO] [stdout] test relative_symlinks_resolve_against_the_link_parent ... ok
[INFO] [stdout] test metadata_events_fire_for_patches ... ok
[INFO] [stdout] test move_rejects_cycles ... ok
[INFO] [stdout] test resolve_links_walks_a_chain_of_links ... ok
[INFO] [stdout] test root_cannot_be_moved_or_deleted ... ok
[INFO] [stdout] test suspended_time_advances_locally_but_not_the_shared_timeline ... ok
[INFO] [stdout] test root_is_a_directory ... ok
[INFO] [stdout] test attach_rejects_an_attached_object ... ok
[INFO] [stdout] test symlink_loops_fail_with_the_depth_error ... ok
[INFO] [stdout] test unsubscribing_during_dispatch_takes_effect_afterwards ... ok
[INFO] [stdout] test subscribing_during_dispatch_does_not_deadlock ... ok
[INFO] [stdout] test virtual_clock_is_frozen_until_advanced ... ok
[INFO] [stdout] test unsubscribing_stops_events ... ok
[INFO] [stdout] test attach_rejects_cycles ... ok
[INFO] [stdout] test symlink_operations_follow_stat_semantics ... ok
[INFO] [stdout] test metadata_only_files_report_declared_size ... ok
[INFO] [stdout] test nested_suspensions_each_report_their_own_consumed_time ... ok
[INFO] [stdout] test an_invalid_extension_key_applies_nothing ... ok
[INFO] [stdout] test detached_namespaces_are_invisible_from_the_root ... ok
[INFO] [stdout] test moving_onto_itself_is_a_noop ... ok
[INFO] [stdout] test move_rejects_name_conflicts ... ok
[INFO] [stdout] test events_fire_synchronously_before_the_mutation_returns ... ok
[INFO] [stdout] test replace_file_with_options_commits_content_and_metadata_together ... ok
[INFO] [stdout] test a_panicking_listener_is_contained ... ok
[INFO] [stdout] test suspension_does_not_leak_when_the_closure_panics ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests engine
[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" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a", kill_on_drop: false }`
[INFO] [stdout] 2246eaeb2f2303726871b2214fc8ff6ddb198ddb3675e5ccc5942234a97d3c7a
