[INFO] fetching crate emit_macros 2.23.0...
[INFO] testing emit_macros-2.23.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate emit_macros 2.23.0 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate emit_macros 2.23.0
[INFO] finished tweaking crates.io crate emit_macros 2.23.0
[INFO] tweaked toml for crates.io crate emit_macros 2.23.0 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate emit_macros 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_macros 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-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af
[INFO] running `Command { std: "docker" "start" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", 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" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af" "/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" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", 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" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling value-bag v1.14.1
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling emit_core v1.23.0
[INFO] [stderr]    Compiling fv-template v0.7.0
[INFO] [stderr]    Compiling emit_macros v2.23.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 33.97s
[INFO] running `Command { std: "docker" "inspect" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", 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" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af" "/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 emit_macros v2.23.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 2.42s
[INFO] running `Command { std: "docker" "inspect" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", 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" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/emit_macros/70c897c318a5ef54/out/emit_macros-70c897c318a5ef54)
[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 emit_macros
[INFO] [stdout] 
[INFO] [stdout] running 62 tests
[INFO] [stdout] test src/lib.rs - dbg (line 494) ... ignored
[INFO] [stdout] test src/lib.rs - debug (line 454) ... ignored
[INFO] [stdout] test src/lib.rs - debug (line 463) ... ignored
[INFO] [stdout] test src/lib.rs - debug (line 472) ... ignored
[INFO] [stdout] test src/lib.rs - debug_evt (line 239) ... ignored
[INFO] [stdout] test src/lib.rs - debug_evt (line 248) ... ignored
[INFO] [stdout] test src/lib.rs - debug_evt (line 257) ... ignored
[INFO] [stdout] test src/lib.rs - debug_span_guard (line 385) ... ignored
[INFO] [stdout] test src/lib.rs - debug_span_guard (line 394) ... ignored
[INFO] [stdout] test src/lib.rs - debug_span_guard (line 403) ... ignored
[INFO] [stdout] test src/lib.rs - debug_span_guard (line 411) ... ignored
[INFO] [stdout] test src/lib.rs - emit (line 442) ... ignored
[INFO] [stdout] test src/lib.rs - emit (line 451) ... ignored
[INFO] [stdout] test src/lib.rs - emit (line 460) ... ignored
[INFO] [stdout] test src/lib.rs - error (line 490) ... ignored
[INFO] [stdout] test src/lib.rs - error (line 499) ... ignored
[INFO] [stdout] test src/lib.rs - error (line 508) ... ignored
[INFO] [stdout] test src/lib.rs - error_evt (line 275) ... ignored
[INFO] [stdout] test src/lib.rs - error_evt (line 284) ... ignored
[INFO] [stdout] test src/lib.rs - error_evt (line 293) ... ignored
[INFO] [stdout] test src/lib.rs - error_span_guard (line 421) ... ignored
[INFO] [stdout] test src/lib.rs - error_span_guard (line 430) ... ignored
[INFO] [stdout] test src/lib.rs - error_span_guard (line 439) ... ignored
[INFO] [stdout] test src/lib.rs - error_span_guard (line 447) ... ignored
[INFO] [stdout] test src/lib.rs - evt (line 227) ... ignored
[INFO] [stdout] test src/lib.rs - evt (line 236) ... ignored
[INFO] [stdout] test src/lib.rs - evt (line 245) ... ignored
[INFO] [stdout] test src/lib.rs - format (line 216) ... ignored
[INFO] [stdout] test src/lib.rs - format (line 225) ... ignored
[INFO] [stdout] test src/lib.rs - info (line 466) ... ignored
[INFO] [stdout] test src/lib.rs - info (line 475) ... ignored
[INFO] [stdout] test src/lib.rs - info (line 484) ... ignored
[INFO] [stdout] test src/lib.rs - info_evt (line 251) ... ignored
[INFO] [stdout] test src/lib.rs - info_evt (line 260) ... ignored
[INFO] [stdout] test src/lib.rs - info_evt (line 269) ... ignored
[INFO] [stdout] test src/lib.rs - info_span_guard (line 397) ... ignored
[INFO] [stdout] test src/lib.rs - info_span_guard (line 406) ... ignored
[INFO] [stdout] test src/lib.rs - info_span_guard (line 415) ... ignored
[INFO] [stdout] test src/lib.rs - info_span_guard (line 423) ... ignored
[INFO] [stdout] test src/lib.rs - metric (line 657) ... ignored
[INFO] [stdout] test src/lib.rs - metric (line 667) ... ignored
[INFO] [stdout] test src/lib.rs - metric (line 673) ... ignored
[INFO] [stdout] test src/lib.rs - props (line 846) ... ignored
[INFO] [stdout] test src/lib.rs - sample (line 507) ... ignored
[INFO] [stdout] test src/lib.rs - sample (line 517) ... ignored
[INFO] [stdout] test src/lib.rs - sample (line 523) ... ignored
[INFO] [stdout] test src/lib.rs - span_guard (line 373) ... ignored
[INFO] [stdout] test src/lib.rs - span_guard (line 382) ... ignored
[INFO] [stdout] test src/lib.rs - span_guard (line 391) ... ignored
[INFO] [stdout] test src/lib.rs - span_guard (line 399) ... ignored
[INFO] [stdout] test src/lib.rs - tpl (line 427) ... ignored
[INFO] [stdout] test src/lib.rs - tpl (line 436) ... ignored
[INFO] [stdout] test src/lib.rs - warn (line 478) ... ignored
[INFO] [stdout] test src/lib.rs - warn (line 487) ... ignored
[INFO] [stdout] test src/lib.rs - warn (line 496) ... ignored
[INFO] [stdout] test src/lib.rs - warn_evt (line 263) ... ignored
[INFO] [stdout] test src/lib.rs - warn_evt (line 272) ... ignored
[INFO] [stdout] test src/lib.rs - warn_evt (line 281) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span_guard (line 409) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span_guard (line 418) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span_guard (line 427) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span_guard (line 435) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 62 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test src/lib.rs - debug_span (line 305) ... ignored
[INFO] [stdout] test src/lib.rs - debug_span (line 314) ... ignored
[INFO] [stdout] test src/lib.rs - debug_span (line 323) ... ignored
[INFO] [stdout] test src/lib.rs - error_span (line 353) ... ignored
[INFO] [stdout] test src/lib.rs - error_span (line 362) ... ignored
[INFO] [stdout] test src/lib.rs - error_span (line 371) ... ignored
[INFO] [stdout] test src/lib.rs - info_span (line 321) ... ignored
[INFO] [stdout] test src/lib.rs - info_span (line 330) ... ignored
[INFO] [stdout] test src/lib.rs - info_span (line 339) ... ignored
[INFO] [stdout] test src/lib.rs - span (line 289) ... ignored
[INFO] [stdout] test src/lib.rs - span (line 298) ... ignored
[INFO] [stdout] test src/lib.rs - span (line 307) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span (line 337) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span (line 346) ... ignored
[INFO] [stdout] test src/lib.rs - warn_span (line 355) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 15 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.41s; merged doctests compilation took 0.40s
[INFO] running `Command { std: "docker" "inspect" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af", kill_on_drop: false }`
[INFO] [stdout] 48a15903808159a496ccdc945622221e300c3ab2c2db03b0cdbbf3ddce0b37af
