[INFO] fetching crate micromegas-tracing 0.13.0...
[INFO] testing micromegas-tracing-0.13.0 against try#b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b for pr-145330-1
[INFO] extracting crate micromegas-tracing 0.13.0 into /workspace/builds/worker-6-tc2/source
[INFO] started tweaking crates.io crate micromegas-tracing 0.13.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate micromegas-tracing 0.13.0
[INFO] tweaked toml for crates.io crate micromegas-tracing 0.13.0 written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate micromegas-tracing 0.13.0 on toolchain b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate micromegas-tracing 0.13.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" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 259ab026e4e7968649621a1a1821bb94a44dec8db89a926c1b4875fb524d41ae
[INFO] running `Command { std: "docker" "start" "-a" "259ab026e4e7968649621a1a1821bb94a44dec8db89a926c1b4875fb524d41ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "259ab026e4e7968649621a1a1821bb94a44dec8db89a926c1b4875fb524d41ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "259ab026e4e7968649621a1a1821bb94a44dec8db89a926c1b4875fb524d41ae", kill_on_drop: false }`
[INFO] [stdout] 259ab026e4e7968649621a1a1821bb94a44dec8db89a926c1b4875fb524d41ae
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] eb6992c4a8e15343e7a631571731c9b80d146b421147a1bab7f2d1fee8067ca0
[INFO] running `Command { std: "docker" "start" "-a" "eb6992c4a8e15343e7a631571731c9b80d146b421147a1bab7f2d1fee8067ca0", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling unicode-ident v1.0.18
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling libc v0.2.175
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling cfg-if v1.0.3
[INFO] [stderr]    Compiling anyhow v1.0.99
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling bitflags v2.9.2
[INFO] [stderr]    Compiling log v0.4.27
[INFO] [stderr]    Compiling iana-time-zone v0.1.63
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling whoami v1.6.1
[INFO] [stderr]    Compiling raw-cpuid v11.5.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling memoffset v0.9.1
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling thread-id v4.2.2
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling internment v0.8.6
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling micromegas-derive-transit v0.13.0
[INFO] [stderr]    Compiling micromegas-tracing-proc-macros v0.13.0
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling uuid v1.18.0
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling micromegas-transit v0.13.0
[INFO] [stderr]    Compiling micromegas-tracing v0.13.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 21.69s
[INFO] running `Command { std: "docker" "inspect" "eb6992c4a8e15343e7a631571731c9b80d146b421147a1bab7f2d1fee8067ca0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "eb6992c4a8e15343e7a631571731c9b80d146b421147a1bab7f2d1fee8067ca0", kill_on_drop: false }`
[INFO] [stdout] eb6992c4a8e15343e7a631571731c9b80d146b421147a1bab7f2d1fee8067ca0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c1b05864f970be371f7cf9582be91bfbdda574965da24f7c09e0267632bcb51c
[INFO] running `Command { std: "docker" "start" "-a" "c1b05864f970be371f7cf9582be91bfbdda574965da24f7c09e0267632bcb51c", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling lock_api v0.4.13
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling memchr v2.7.5
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling sdd v3.0.10
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling serial_test_derive v3.2.0
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling scc v2.4.0
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling micromegas-tracing v0.13.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling serial_test v3.2.0
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.75s
[INFO] running `Command { std: "docker" "inspect" "c1b05864f970be371f7cf9582be91bfbdda574965da24f7c09e0267632bcb51c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c1b05864f970be371f7cf9582be91bfbdda574965da24f7c09e0267632bcb51c", kill_on_drop: false }`
[INFO] [stdout] c1b05864f970be371f7cf9582be91bfbdda574965da24f7c09e0267632bcb51c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+b6ae10aa7319b7ebb6c7b3331dd71a9d6c9c5b4b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 98c705c78eba1fd449cb36c5cf44884db44bf1c2f55db860a2f43740e3d2657f
[INFO] running `Command { std: "docker" "start" "-a" "98c705c78eba1fd449cb36c5cf44884db44bf1c2f55db860a2f43740e3d2657f", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/micromegas_tracing-1d62064eb5e476ca)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test string_id::test::test_string_id ... ok
[INFO] [stdout] test time::tests::test_frequency ... ok
[INFO] [stderr]      Running tests/async_depth_tracking_tests.rs (/opt/rustwide/target/debug/deps/async_depth_tracking_tests-3624718cd65106bb)
[INFO] [stdout] test logs::events::test::test_filter_levels ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_error_handling_with_instrumentation ... ok
[INFO] [stdout] test test_basic_async_instrumentation ... ok
[INFO] [stdout] test test_deeply_nested_async ... ok
[INFO] [stderr]      Running tests/flush_monitor_safety.rs (/opt/rustwide/target/debug/deps/flush_monitor_safety-2e416bcfdfc2b116)
[INFO] [stdout] test test_parallel_async_operations ... ok
[INFO] [stdout] test test_nested_async_instrumentation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.44s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/test_macros.rs (/opt/rustwide/target/debug/deps/test_macros-9ce458b939d08d2f)
[INFO] [stdout] test test_flush_monitor_concurrent_thread_safety ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_log ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_panic.rs (/opt/rustwide/target/debug/deps/test_panic-6bcdb558bc13d860)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/thread_park_test.rs (/opt/rustwide/target/debug/deps/thread_park_test-7a045d7404a3bf92)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_thread_park_flush ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/utils.rs (/opt/rustwide/target/debug/deps/utils-24e6555693deb44a)
[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] [stderr]    Doc-tests micromegas_tracing
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test src/logs/events.rs - logs::events::LogMetadata<'_>::level_filter (line 41) ... ignored
[INFO] [stdout] test src/levels.rs - levels::Level::iter (line 221) ... ok
[INFO] [stdout] test src/levels.rs - levels::LodFilter::iter (line 689) ... ok
[INFO] [stdout] test src/levels.rs - levels::LevelFilter::iter (line 362) ... ok
[INFO] [stdout] test src/macros.rs - macros::error (line 286) ... ok
[INFO] [stdout] test src/lib.rs - (line 15) ... ok
[INFO] [stdout] test src/levels.rs - levels::Verbosity::iter (line 541) ... ok
[INFO] [stdout] test src/macros.rs - macros::imetric (line 154) ... ok
[INFO] [stdout] test src/macros.rs - macros::debug (line 371) ... ok
[INFO] [stdout] test src/macros.rs - macros::fmetric (line 194) ... ok
[INFO] [stdout] test src/macros.rs - macros::log_enabled (line 427) ... ok
[INFO] [stdout] test src/runtime.rs - runtime::TracingRuntimeExt::with_tracing_callbacks_and_custom_start (line 53) - compile ... ok
[INFO] [stdout] test src/macros.rs - macros::info (line 345) ... ok
[INFO] [stdout] test src/macros.rs - macros::log (line 238) ... ok
[INFO] [stdout] test src/macros.rs - macros::span_scope (line 23) ... ok
[INFO] [stdout] test src/macros.rs - macros::span_scope_named (line 46) ... ok
[INFO] [stdout] test src/test_utils.rs - test_utils::init_in_memory_tracing (line 49) ... ok
[INFO] [stdout] test src/macros.rs - macros::static_span_location (line 79) ... ok
[INFO] [stdout] test src/runtime.rs - runtime::TracingRuntimeExt (line 22) - compile ... ok
[INFO] [stdout] test src/macros.rs - macros::trace (line 396) ... ok
[INFO] [stdout] test src/macros.rs - macros::warn (line 321) ... ok
[INFO] [stdout] test src/macros.rs - macros::span_async_named (line 107) ... ok
[INFO] [stdout] test src/macros.rs - macros::instrument_named (line 132) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "98c705c78eba1fd449cb36c5cf44884db44bf1c2f55db860a2f43740e3d2657f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "98c705c78eba1fd449cb36c5cf44884db44bf1c2f55db860a2f43740e3d2657f", kill_on_drop: false }`
[INFO] [stdout] 98c705c78eba1fd449cb36c5cf44884db44bf1c2f55db860a2f43740e3d2657f