[INFO] fetching crate emit 2.23.0...
[INFO] testing emit-2.23.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate emit 2.23.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate emit 2.23.0
[INFO] finished tweaking crates.io crate emit 2.23.0
[INFO] tweaked toml for crates.io crate emit 2.23.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate emit 2.23.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate emit 2.23.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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962
[INFO] running `Command { std: "docker" "start" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", 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" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", 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" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling cpufeatures v0.3.1
[INFO] [stderr]    Compiling value-bag v1.14.1
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling chacha20 v0.10.2
[INFO] [stderr]    Compiling emit_core v1.23.0
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling rand v0.10.2
[INFO] [stderr]    Compiling fv-template v0.7.0
[INFO] [stderr]    Compiling emit_macros v2.23.0
[INFO] [stderr]    Compiling emit v2.23.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 15.25s
[INFO] running `Command { std: "docker" "inspect" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", 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" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling sval v2.22.0
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling sval_fmt v2.22.0
[INFO] [stderr]    Compiling sval_test v2.22.0
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling serde_test v1.0.177
[INFO] [stderr]    Compiling emit v2.23.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 23.88s
[INFO] running `Command { std: "docker" "inspect" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", 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" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/emit/338afc41fbc0eb90/out/emit-338afc41fbc0eb90)
[INFO] [stdout] 
[INFO] [stdout] running 137 tests
[INFO] [stdout] test frame::tests::frame_filtered ... ok
[INFO] [stdout] test frame::tests::frame_exec ... ok
[INFO] [stdout] test frame::tests::frame_in_future ... ok
[INFO] [stdout] test frame::tests::frame_manual ... ok
[INFO] [stdout] test kind::tests::kind_filter ... ok
[INFO] [stdout] test level::alloc_support::tests::min_level_integer ... ok
[INFO] [stdout] test kind::tests::parse ... ok
[INFO] [stdout] test kind::tests::roundtrip ... ok
[INFO] [stdout] test level::alloc_support::tests::min_level_child ... ok
[INFO] [stdout] test frame::tests::frame_in_fn ... ok
[INFO] [stdout] test kind::tests::to_from_value ... ok
[INFO] [stdout] test level::alloc_support::tests::min_level_default ... ok
[INFO] [stdout] test level::alloc_support::tests::min_level_default_unmatched ... ok
[INFO] [stdout] test level::alloc_support::tests::min_level_unmatched ... ok
[INFO] [stdout] test level::tests::roundtrip ... ok
[INFO] [stdout] test level::tests::min_level_filter_integer ... ok
[INFO] [stdout] test level::tests::to_from_value ... ok
[INFO] [stdout] test metric::alloc_support::tests::reporter_is_send_sync ... ok
[INFO] [stdout] test level::tests::min_level_filter_with_default ... ok
[INFO] [stdout] test metric::alloc_support::tests::reporter_normalize_empty_extent ... ok
[INFO] [stdout] test level::tests::parse ... ok
[INFO] [stdout] test level::tests::min_level_filter ... ok
[INFO] [stdout] test level::alloc_support::tests::min_level ... ok
[INFO] [stdout] test metric::alloc_support::tests::reporter_normalize_point_extent ... ok
[INFO] [stdout] test metric::alloc_support::tests::reporter_normalize_range_extent ... ok
[INFO] [stdout] test metric::alloc_support::tests::reporter_sample ... ok
[INFO] [stdout] test metric::delta::tests::delta_advance ... ok
[INFO] [stdout] test metric::exp::tests::point_cmp ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_to_from_value ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::err_bucket_set_invalid ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_from_value_structured ... ok
[INFO] [stdout] test metric::exp::tests::point_is_bucket ... ok
[INFO] [stdout] test metric::exp::tests::point_is_indexable ... ok
[INFO] [stdout] test metric::exp::tests::point_roundtrip ... ok
[INFO] [stdout] test metric::exp::tests::point_to_from_value ... ok
[INFO] [stdout] test metric::sampler::tests::erased_sampler ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_from_iter ... ok
[INFO] [stdout] test metric::sampler::tests::from_fn_sampler ... ok
[INFO] [stdout] test metric::sampler::tests::from_runtime_sampler ... ok
[INFO] [stdout] test metric::source::tests::and_sample ... ok
[INFO] [stdout] test metric::source::tests::erased_source ... ok
[INFO] [stdout] test metric::source::tests::from_fn_source ... ok
[INFO] [stdout] test metric::source::tests::metric_as_source ... ok
[INFO] [stdout] test metric::source::tests::source_sample_emit ... ok
[INFO] [stdout] test metric::tests::metric_new ... ok
[INFO] [stdout] test metric::tests::metric_to_event ... ok
[INFO] [stdout] test metric::tests::metric_to_event_uses_tpl ... ok
[INFO] [stdout] test metric::tests::metric_to_extent ... ok
[INFO] [stdout] test platform::libm::ceil::tests::sanity_check ... ok
[INFO] [stdout] test platform::libm::ceil::tests::spec_test ... ok
[INFO] [stdout] test platform::libm::log::tests::cases ... ok
[INFO] [stdout] test platform::libm::pow::tests::infinity_as_base ... ok
[INFO] [stdout] test platform::libm::pow::tests::infinity_as_exponent ... ok
[INFO] [stdout] test platform::libm::pow::tests::nan_inputs ... ok
[INFO] [stdout] test platform::libm::pow::tests::normal_cases ... ok
[INFO] [stdout] test platform::libm::pow::tests::one_as_base ... ok
[INFO] [stdout] test platform::libm::pow::tests::special_cases ... ok
[INFO] [stdout] test platform::libm::pow::tests::zero_as_base ... ok
[INFO] [stdout] test platform::libm::pow::tests::zero_as_exponent ... ok
[INFO] [stdout] test platform::libm::scalbn::tests::spec_test ... ok
[INFO] [stdout] test platform::libm::sqrt::tests::conformance_tests ... ok
[INFO] [stdout] test platform::libm::sqrt::tests::sanity_check ... ok
[INFO] [stdout] test platform::libm::sqrt::tests::spec_test ... ok
[INFO] [stdout] test metric::alloc_support::tests::reporter_normalize_std ... ok
[INFO] [stdout] test platform::rand_rng::tests::rng_gen ... ok
[INFO] [stdout] test platform::system_clock::tests::now ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::can_inline ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::dedup ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::inline_props ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_from_value_string ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::isolation ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::out_of_order_enter_exit ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_observe ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_parse_exotic ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_remap ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_roundtrip ... ok
[INFO] [stdout] test metric::exp::alloc_support::bucket_set::tests::bucket_set_parse ... ok
[INFO] [stdout] test metric::exp::alloc_support::tests::distribution_observe ... ok
[INFO] [stdout] test metric::exp::alloc_support::tests::distribution_rescale ... ok
[INFO] [stdout] test metric::exp::tests::compute_midpoints ... ok
[INFO] [stdout] test setup::tests::try_init ... ok
[INFO] [stdout] test span::completion::tests::default_completion ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set_from_iter ... ok
[INFO] [stdout] test span::completion::tests::default_completion_from_runtime ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::push_props ... ok
[INFO] [stdout] test span::completion::tests::default_completion_uses_lvl ... ok
[INFO] [stdout] test span::completion::tests::default_completion_uses_tpl ... ok
[INFO] [stdout] test span::completion::tests::erased_completion ... ok
[INFO] [stdout] test span::completion::tests::from_fn_completion ... ok
[INFO] [stdout] test span::span_link_set::tests::err_span_link_set_invalid ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set_from_value_string ... ok
[INFO] [stdout] test span::tests::span_ctxt ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set_from_value_structured ... ok
[INFO] [stdout] test span::tests::span_guard_with_props ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set_to_from_value ... ok
[INFO] [stdout] test span::tests::span_ctxt_new ... ok
[INFO] [stdout] test span::tests::span_guard_custom_complete ... ok
[INFO] [stdout] test span::tests::span_guard_from_runtime ... ok
[INFO] [stdout] test span::tests::span_guard_unstarted_complete ... ok
[INFO] [stdout] test span::tests::span_id_fmt ... ok
[INFO] [stdout] test span::tests::span_id_from_value_string ... ok
[INFO] [stdout] test platform::thread_local_ctxt::tests::frame_thread_propagation ... ok
[INFO] [stdout] test span::tests::span_id_from_value_u64 ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set_parse ... ok
[INFO] [stdout] test span::tests::span_id_to_from_value ... ok
[INFO] [stdout] test span::tests::span_kind_parse ... ok
[INFO] [stdout] test span::tests::span_kind_roundtrip ... ok
[INFO] [stdout] test span::tests::span_link_fmt ... ok
[INFO] [stdout] test span::tests::span_id_parse ... ok
[INFO] [stdout] test span::tests::span_guard_new ... ok
[INFO] [stdout] test span::tests::span_id_roundtrip ... ok
[INFO] [stdout] test span::tests::span_link_from_value_string ... ok
[INFO] [stdout] test span::tests::span_guard_new_disabled ... ok
[INFO] [stdout] test span::tests::span_link_parse ... ok
[INFO] [stdout] test span::tests::span_link_roundtrip ... ok
[INFO] [stdout] test span::tests::span_link_to_from_value ... ok
[INFO] [stdout] test span::tests::span_new ... ok
[INFO] [stdout] test span::tests::span_to_event ... ok
[INFO] [stdout] test span::tests::span_to_event_uses_tpl ... ok
[INFO] [stdout] test span::tests::span_to_extent ... ok
[INFO] [stdout] test span::tests::trace_id_fmt ... ok
[INFO] [stdout] test span::tests::trace_id_from_value_string ... ok
[INFO] [stdout] test span::tests::trace_id_from_value_u128 ... ok
[INFO] [stdout] test span::tests::trace_id_parse ... ok
[INFO] [stdout] test span::tests::trace_id_random_empty ... ok
[INFO] [stdout] test span::tests::trace_id_random_rand ... ok
[INFO] [stdout] test span::tests::trace_id_roundtrip ... ok
[INFO] [stdout] test span::tests::trace_id_to_from_value ... ok
[INFO] [stdout] test timer::tests::timer_backwards ... ok
[INFO] [stdout] test timer::tests::timer_empty ... ok
[INFO] [stdout] test timer::tests::timer_forwards ... ok
[INFO] [stdout] test span::tests::span_id_random_empty ... ok
[INFO] [stdout] test span::span_link_set::tests::span_link_set_roundtrip ... ok
[INFO] [stdout] test span::tests::span_id_random_rand ... ok
[INFO] [stdout] test span::completion::tests::default_completion_uses_panic_lvl ... ok
[INFO] [stdout] test span::completion::tests::default_completion_detects_panics ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 137 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests emit
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test src/lib.rs - (line 106) ... ok
[INFO] [stdout] test src/kind.rs - kind::Kind (line 19) ... ok
[INFO] [stdout] test src/lib.rs - (line 17) ... ok
[INFO] [stdout] test src/setup.rs - setup (line 18) ... ok
[INFO] [stdout] test src/level.rs - level::Level (line 21) ... ok
[INFO] [stdout] test src/setup.rs - setup (line 40) ... ok
[INFO] [stdout] test src/setup.rs - setup::Init<'a,TEmitter,TCtxt>::flush_on_drop (line 583) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.05s; merged doctests compilation took 0.99s
[INFO] running `Command { std: "docker" "inspect" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962", kill_on_drop: false }`
[INFO] [stdout] c63eb82f76b2122ed0282745ce43c7c394f48f7b285570bbbc26cd886bfc4962
