[INFO] fetching crate si_trace_print 0.3.12...
[INFO] testing si_trace_print-0.3.12 against master#350d0ef0ec0493e6d21cfb265cb8211a0e74d766 for pr-145330-1
[INFO] extracting crate si_trace_print 0.3.12 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate si_trace_print 0.3.12
[INFO] finished tweaking crates.io crate si_trace_print 0.3.12
[INFO] tweaked toml for crates.io crate si_trace_print 0.3.12 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate si_trace_print 0.3.12 on toolchain 350d0ef0ec0493e6d21cfb265cb8211a0e74d766
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 20 packages to latest compatible versions
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: `edition` is set on library `si_trace_print` which is deprecated
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded error-chain v0.10.0
[INFO] [stderr]   Downloaded mut_static v5.0.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a149035a2cdfd31140a854490690b000c10a348c8e1732a2e85d819c3df3be32
[INFO] running `Command { std: "docker" "start" "-a" "a149035a2cdfd31140a854490690b000c10a348c8e1732a2e85d819c3df3be32", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a149035a2cdfd31140a854490690b000c10a348c8e1732a2e85d819c3df3be32", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a149035a2cdfd31140a854490690b000c10a348c8e1732a2e85d819c3df3be32", kill_on_drop: false }`
[INFO] [stdout] a149035a2cdfd31140a854490690b000c10a348c8e1732a2e85d819c3df3be32
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 917b177ef2fdb06cc464e6bdce0272a37f4fac355ddac6884cdae4d6eb13c636
[INFO] running `Command { std: "docker" "start" "-a" "917b177ef2fdb06cc464e6bdce0272a37f4fac355ddac6884cdae4d6eb13c636", kill_on_drop: false }`
[INFO] [stderr] warning: `edition` is set on library `si_trace_print` which is deprecated
[INFO] [stderr]    Compiling object v0.37.3
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling gimli v0.32.3
[INFO] [stderr]    Compiling error-chain v0.10.0
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling const_format_proc_macros v0.2.34
[INFO] [stderr]    Compiling mut_static v5.0.0
[INFO] [stderr]    Compiling const_format v0.2.34
[INFO] [stderr]    Compiling addr2line v0.25.1
[INFO] [stderr]    Compiling backtrace v0.3.76
[INFO] [stderr]    Compiling si_trace_print v0.3.12 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.47s
[INFO] running `Command { std: "docker" "inspect" "917b177ef2fdb06cc464e6bdce0272a37f4fac355ddac6884cdae4d6eb13c636", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "917b177ef2fdb06cc464e6bdce0272a37f4fac355ddac6884cdae4d6eb13c636", kill_on_drop: false }`
[INFO] [stdout] 917b177ef2fdb06cc464e6bdce0272a37f4fac355ddac6884cdae4d6eb13c636
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fce9e07356d0ddcbcc1ecd5ab1e5848801473cc1f8100119f4faaa5197d6cb08
[INFO] running `Command { std: "docker" "start" "-a" "fce9e07356d0ddcbcc1ecd5ab1e5848801473cc1f8100119f4faaa5197d6cb08", kill_on_drop: false }`
[INFO] [stderr] warning: `edition` is set on library `si_trace_print` which is deprecated
[INFO] [stderr]    Compiling si_trace_print v0.3.12 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.98s
[INFO] running `Command { std: "docker" "inspect" "fce9e07356d0ddcbcc1ecd5ab1e5848801473cc1f8100119f4faaa5197d6cb08", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fce9e07356d0ddcbcc1ecd5ab1e5848801473cc1f8100119f4faaa5197d6cb08", kill_on_drop: false }`
[INFO] [stdout] fce9e07356d0ddcbcc1ecd5ab1e5848801473cc1f8100119f4faaa5197d6cb08
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+350d0ef0ec0493e6d21cfb265cb8211a0e74d766" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4a8f955904d3e0ec8fa7ef0b9018caae22c10b8a288b8d6fe380c9c9564201c0
[INFO] running `Command { std: "docker" "start" "-a" "4a8f955904d3e0ec8fa7ef0b9018caae22c10b8a288b8d6fe380c9c9564201c0", kill_on_drop: false }`
[INFO] [stderr] warning: `edition` is set on library `si_trace_print` which is deprecated
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.33s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/si_trace_print-d392e0750a612900)
[INFO] [stdout] 
[INFO] [stdout] running 41 tests
[INFO] [stdout] test function_name::tests::more_tests::test_function_name_plus1 ... ok
[INFO] [stdout] test function_name::tests::more_tests::test_function_name_plus2 ... ok
[INFO] [stdout] test function_name::tests::test_function_name_plus2 ... ok
[INFO] [stdout] test function_name::tests::test_function_name_function_name_plus0 ... ok
[INFO] [stdout] test printers::tests::test_de ... ok
[INFO] [stdout] test printers::tests::test_def1onxñ ... ok
[INFO] [stdout] test printers::tests::test_def2onxñ ... ok
[INFO] [stdout] test printers::tests::test_defonxñ ... ok
[INFO] [stdout] test printers::tests::test_dpf2onxñ ... ok
[INFO] [stdout] test printers::tests::test_dp ... ok
[INFO] [stdout] test printers::tests::test_dpfonxñ ... ok
[INFO] [stdout] test printers::tests::test_dponxñ ... ok
[INFO] [stdout] test printers::tests::test_e ... ok
[INFO] [stdout] test printers::tests::test_ef1onxñ ... ok
[INFO] [stdout] test printers::tests::test_ef2onxñ ... ok
[INFO] [stdout] test printers::tests::test_efonxñ ... ok
[INFO] [stdout] test printers::tests::test_eonxñ ... ok
[INFO] [stdout] test printers::tests::test_deonxñ ... ok
[INFO] [stdout] test printers::tests::test_dpf1onxñ ... ok
[INFO] [stdout] test function_name::tests::test_function_name_plus0 ... ok
[INFO] [stdout] test function_name::tests::test_function_name_full ... ok
[INFO] [stdout] test function_name::tests::test_function_name_plus1 ... ok
[INFO] [stdout] test printers::tests::test_pf2onxñ ... ok
[INFO] [stdout] test printers::tests::test_p ... ok
[INFO] [stdout] test printers::tests::test_ponxñ ... ok
[INFO] [stdout] test printers::tests::test_pfonxñ ... ok
[INFO] [stdout] test stack::tests::test_sn ... ok
[INFO] [stdout] test stack::tests::test_so ... ok
[INFO] [stdout] test function_name::tests::more_tests::test_function_name_plus0 ... ok
[INFO] [stdout] test stack::tests::test_stack_depth ... ok
[INFO] [stdout] test stack::tests::test_stack_offset_a_b ... ok
[INFO] [stdout] test stack::tests::test_stack_offset_a_b_c ... ok
[INFO] [stdout] test stack::tests::test_stack_offset_set_10 ... ok
[INFO] [stdout] test stack::tests::test_stack_offset_set_none ... ok
[INFO] [stdout] test function_name::tests::test_function_name ... ok
[INFO] [stdout] test stack::tests::test_sx ... ok
[INFO] [stdout] test stack::tests::test_stack_offset_set_none_999 ... ok
[INFO] [stdout] test printers::tests::test_pf1onxñ ... ok
[INFO] [stdout] test stack::tests::test_sñ ... ok
[INFO] [stdout] test stack::tests::test_stack_offset_set_multiple_threads ... ok
[INFO] [stdout] test printers::tests::test_multithreaded ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests si_trace_print
[INFO] [stdout] 
[INFO] [stdout] running 72 tests
[INFO] [stdout] test src/printers.rs - printers::defx (line 2819) ... ok
[INFO] [stdout] test src/printers.rs - printers::def2n (line 3184) ... ok
[INFO] [stdout] test src/printers.rs - printers::def1o (line 2924) ... ok
[INFO] [stdout] test src/lib.rs - (line 46) ... ok
[INFO] [stdout] test src/printers.rs - printers::def2ñ (line 3287) ... ok
[INFO] [stdout] test src/printers.rs - printers::def2x (line 3235) ... ok
[INFO] [stdout] test src/printers.rs - printers::defn (line 2770) ... ok
[INFO] [stdout] test src/printers.rs - printers::de (line 2475) ... ok
[INFO] [stdout] test src/printers.rs - printers::def1n (line 2975) ... ok
[INFO] [stdout] test src/printers.rs - printers::def2o (line 3133) ... ok
[INFO] [stdout] test src/lib.rs - (line 10) ... ok
[INFO] [stdout] test src/printers.rs - printers::def1x (line 3026) ... ok
[INFO] [stdout] test src/printers.rs - printers::den (line 2568) ... ok
[INFO] [stdout] test src/printers.rs - printers::defñ (line 2869) ... ok
[INFO] [stdout] test src/printers.rs - printers::defo (line 2720) ... ok
[INFO] [stdout] test src/lib.rs - (line 156) ... ok
[INFO] [stdout] test src/printers.rs - printers::def1ñ (line 3078) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf1ñ (line 2213) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf1n (line 2110) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf1o (line 2059) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf2x (line 2370) ... ok
[INFO] [stdout] test src/printers.rs - printers::deñ (line 2667) ... ok
[INFO] [stdout] test src/lib.rs - (line 125) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpfn (line 1905) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf1x (line 2161) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf2n (line 2319) ... ok
[INFO] [stdout] test src/printers.rs - printers::dp (line 1608) ... ok
[INFO] [stdout] test src/printers.rs - printers::dex (line 2617) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf2o (line 2268) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpfo (line 1851) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpfx (line 1954) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef1ñ (line 1378) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef1x (line 1332) ... ok
[INFO] [stdout] test src/printers.rs - printers::deo (line 2519) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpn (line 1703) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpx (line 1752) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef1n (line 1287) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpfñ (line 2004) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef2n (line 1472) ... ok
[INFO] [stdout] test src/printers.rs - printers::e (line 839) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef2x (line 1517) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpo (line 1654) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpñ (line 1802) ... ok
[INFO] [stdout] test src/printers.rs - printers::p (line 76) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef2o (line 1427) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef1o (line 1242) ... ok
[INFO] [stdout] test src/printers.rs - printers::efo (line 1062) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf1x (line 561) ... ok
[INFO] [stdout] test src/printers.rs - printers::efñ (line 1193) ... ok
[INFO] [stdout] test src/printers.rs - printers::eo (line 881) ... ok
[INFO] [stdout] test src/printers.rs - printers::ef2ñ (line 1563) ... ok
[INFO] [stdout] test src/printers.rs - printers::eñ (line 1015) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf1ñ (line 607) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf1n (line 516) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf1o (line 471) ... ok
[INFO] [stdout] test src/printers.rs - printers::efx (line 1149) ... ok
[INFO] [stdout] test src/printers.rs - printers::efn (line 1106) ... ok
[INFO] [stdout] test src/printers.rs - printers::ex (line 971) ... ok
[INFO] [stdout] test src/printers.rs - printers::dpf2ñ (line 2422) ... ok
[INFO] [stdout] test src/printers.rs - printers::en (line 928) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf2n (line 701) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf2x (line 746) ... ok
[INFO] [stdout] test src/printers.rs - printers::pfo (line 291) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf2o (line 656) ... ok
[INFO] [stdout] test src/printers.rs - printers::pn (line 161) ... ok
[INFO] [stdout] test src/printers.rs - printers::po (line 118) ... ok
[INFO] [stdout] test src/printers.rs - printers::px (line 204) ... ok
[INFO] [stdout] test src/printers.rs - printers::pfñ (line 422) ... ok
[INFO] [stdout] test src/printers.rs - printers::pfx (line 378) ... ok
[INFO] [stdout] test src/printers.rs - printers::pfn (line 335) ... ok
[INFO] [stdout] test src/printers.rs - printers::pf2ñ (line 792) ... ok
[INFO] [stdout] test src/printers.rs - printers::pñ (line 248) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 72 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.78s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4a8f955904d3e0ec8fa7ef0b9018caae22c10b8a288b8d6fe380c9c9564201c0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4a8f955904d3e0ec8fa7ef0b9018caae22c10b8a288b8d6fe380c9c9564201c0", kill_on_drop: false }`
[INFO] [stdout] 4a8f955904d3e0ec8fa7ef0b9018caae22c10b8a288b8d6fe380c9c9564201c0