[INFO] fetching crate sexprs-formatter 0.0.5...
[INFO] testing sexprs-formatter-0.0.5 against try#2440211fe03bc45c89b6dc1a3df18382ce91e32b for pr-146098-1
[INFO] extracting crate sexprs-formatter 0.0.5 into /workspace/builds/worker-4-tc2/source
[INFO] started tweaking crates.io crate sexprs-formatter 0.0.5
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sexprs-formatter 0.0.5
[INFO] tweaked toml for crates.io crate sexprs-formatter 0.0.5 written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sexprs-formatter 0.0.5 on toolchain 2440211fe03bc45c89b6dc1a3df18382ce91e32b
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sexprs-formatter 0.0.5 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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 87c06f7c5a4c17b5250443a340c84e2d3a75c62de7e2257d80cba08fdc9a2ef8
[INFO] running `Command { std: "docker" "start" "-a" "87c06f7c5a4c17b5250443a340c84e2d3a75c62de7e2257d80cba08fdc9a2ef8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "87c06f7c5a4c17b5250443a340c84e2d3a75c62de7e2257d80cba08fdc9a2ef8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "87c06f7c5a4c17b5250443a340c84e2d3a75c62de7e2257d80cba08fdc9a2ef8", kill_on_drop: false }`
[INFO] [stdout] 87c06f7c5a4c17b5250443a340c84e2d3a75c62de7e2257d80cba08fdc9a2ef8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9f1d1f3e215f23b600c0368506f37c0b606367c60e1415ea297a0a3dce36732b
[INFO] running `Command { std: "docker" "start" "-a" "9f1d1f3e215f23b600c0368506f37c0b606367c60e1415ea297a0a3dce36732b", kill_on_drop: false }`
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling libc v0.2.173
[INFO] [stderr]    Compiling time-core v0.1.4
[INFO] [stderr]    Compiling hashbrown v0.15.4
[INFO] [stderr]    Compiling proc-macro2 v1.0.95
[INFO] [stderr]    Compiling deranged v0.4.0
[INFO] [stderr]    Compiling quick-xml v0.37.5
[INFO] [stderr]    Compiling prettyplease v0.2.33
[INFO] [stderr]    Compiling flate2 v1.1.2
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling thread-groups v0.1.2
[INFO] [stderr]    Compiling trilobyte v0.1.2
[INFO] [stderr]    Compiling sanitation v1.0.3
[INFO] [stderr]    Compiling sexprs-util v0.0.5
[INFO] [stderr]    Compiling cc v1.2.27
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.103
[INFO] [stderr]    Compiling time v0.3.41
[INFO] [stderr]    Compiling indexmap v2.9.0
[INFO] [stderr]    Compiling onig_sys v69.9.1
[INFO] [stderr]    Compiling filetime v0.2.25
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling dumbeq v0.1.2
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling onig v6.5.1
[INFO] [stderr]    Compiling plist v1.7.2
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling syntect v5.2.0
[INFO] [stderr]    Compiling iocore v3.0.3
[INFO] [stderr]    Compiling sexprs-formatter v0.0.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 23.34s
[INFO] running `Command { std: "docker" "inspect" "9f1d1f3e215f23b600c0368506f37c0b606367c60e1415ea297a0a3dce36732b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9f1d1f3e215f23b600c0368506f37c0b606367c60e1415ea297a0a3dce36732b", kill_on_drop: false }`
[INFO] [stdout] 9f1d1f3e215f23b600c0368506f37c0b606367c60e1415ea297a0a3dce36732b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1d400d0e0ff2fca03daeb6a3423831a9c86906b994885fdd8ff619aa912794cc
[INFO] running `Command { std: "docker" "start" "-a" "1d400d0e0ff2fca03daeb6a3423831a9c86906b994885fdd8ff619aa912794cc", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.173
[INFO] [stderr]    Compiling io-lifetimes v1.0.11
[INFO] [stderr]    Compiling rustix v0.37.28
[INFO] [stderr]    Compiling syn v2.0.103
[INFO] [stderr]    Compiling colored v2.2.0
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling filetime v0.2.25
[INFO] [stderr]    Compiling iocore v3.0.3
[INFO] [stderr]    Compiling terminal_size v0.2.6
[INFO] [stderr]    Compiling prettyplease v0.2.33
[INFO] [stderr]    Compiling k9 v0.12.0
[INFO] [stderr]    Compiling sexprs-formatter v0.0.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.63s
[INFO] running `Command { std: "docker" "inspect" "1d400d0e0ff2fca03daeb6a3423831a9c86906b994885fdd8ff619aa912794cc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1d400d0e0ff2fca03daeb6a3423831a9c86906b994885fdd8ff619aa912794cc", kill_on_drop: false }`
[INFO] [stdout] 1d400d0e0ff2fca03daeb6a3423831a9c86906b994885fdd8ff619aa912794cc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "+2440211fe03bc45c89b6dc1a3df18382ce91e32b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 378648fe9d2744e56510bfff63a2e183a3cfc1cbaa084bc9173d691087c22760
[INFO] running `Command { std: "docker" "start" "-a" "378648fe9d2744e56510bfff63a2e183a3cfc1cbaa084bc9173d691087c22760", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sexprs_formatter-f124110b1150bed6)
[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]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/sexprs_formatter-2b6f25852ea5f39e)
[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]      Running tests/test_format_data_structures.rs (/opt/rustwide/target/debug/deps/test_format_data_structures-ecd4a14a8f8039b7)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_format_reference_type ... ok
[INFO] [stdout] test test_format_enum_unnamed_fields ... ok
[INFO] [stdout] test test_format_struct ... 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] [stderr]      Running tests/test_format_token_stream.rs (/opt/rustwide/target/debug/deps/test_format_token_stream-94793b54a29eaad8)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_format_token_stream_enum_variant ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_format_token_tree.rs (/opt/rustwide/target/debug/deps/test_format_token_tree-93b486976e8b3d9a)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_enum_variant ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sexprs_formatter
[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" "378648fe9d2744e56510bfff63a2e183a3cfc1cbaa084bc9173d691087c22760", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "378648fe9d2744e56510bfff63a2e183a3cfc1cbaa084bc9173d691087c22760", kill_on_drop: false }`
[INFO] [stdout] 378648fe9d2744e56510bfff63a2e183a3cfc1cbaa084bc9173d691087c22760