[INFO] fetching crate autolog 0.1.0... [INFO] testing autolog-0.1.0 against try#fc4ba03ef77ca5018fb78a097d3b924d30f15bfc+target=i686-unknown-linux-musl for musl_upgrade_1_2_take3 [INFO] extracting crate autolog 0.1.0 into /workspace/builds/worker-1/source [INFO] validating manifest of crates.io crate autolog 0.1.0 on toolchain fc4ba03ef77ca5018fb78a097d3b924d30f15bfc [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate autolog 0.1.0 [INFO] finished tweaking crates.io crate autolog 0.1.0 [INFO] tweaked toml for crates.io crate autolog 0.1.0 written to /workspace/builds/worker-1/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded wepoll-ffi v0.1.2 [INFO] [stderr] Downloaded async-fs v1.6.0 [INFO] [stderr] Downloaded async-net v1.7.0 [INFO] [stderr] Downloaded async-executor v1.5.0 [INFO] [stderr] Downloaded litrs v0.2.3 [INFO] [stderr] Downloaded async-process v1.6.0 [INFO] [stderr] Downloaded polling v2.5.1 [INFO] [stderr] Downloaded blocking v1.3.0 [INFO] [stderr] Downloaded async-channel v1.8.0 [INFO] [stderr] Downloaded async-task v4.3.0 [INFO] [stderr] Downloaded async-lock v2.6.0 [INFO] [stderr] Downloaded async-io v1.12.0 [INFO] [stderr] Downloaded concurrent-queue v2.0.0 [INFO] [stderr] Downloaded event-listener v2.5.3 [INFO] [stderr] Downloaded futures-lite v1.12.0 [INFO] [stderr] Downloaded parking v2.0.0 [INFO] [stderr] Downloaded atomic-waker v1.0.0 [INFO] [stderr] Downloaded waker-fn v1.1.0 [INFO] [stderr] Downloaded smol v1.3.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 4e00dbb2529e26078bdf732ba273914ad7392551a91b46de94a7c4f276963a9f [INFO] running `Command { std: "docker" "start" "-a" "4e00dbb2529e26078bdf732ba273914ad7392551a91b46de94a7c4f276963a9f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "4e00dbb2529e26078bdf732ba273914ad7392551a91b46de94a7c4f276963a9f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4e00dbb2529e26078bdf732ba273914ad7392551a91b46de94a7c4f276963a9f", kill_on_drop: false }` [INFO] [stdout] 4e00dbb2529e26078bdf732ba273914ad7392551a91b46de94a7c4f276963a9f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "build" "--frozen" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] a6a07c45020e9d3b937abc9c2e9c6cc4dace6ee1de2fbfa5f710daeecaeb9722 [INFO] running `Command { std: "docker" "start" "-a" "a6a07c45020e9d3b937abc9c2e9c6cc4dace6ee1de2fbfa5f710daeecaeb9722", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.47 [INFO] [stderr] Compiling unicode-ident v1.0.5 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling syn v1.0.105 [INFO] [stderr] Compiling once_cell v1.16.0 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling pin-project-lite v0.2.9 [INFO] [stderr] Compiling tracing-core v0.1.30 [INFO] [stderr] Compiling litrs v0.2.3 [INFO] [stderr] Compiling tracing-attributes v0.1.23 [INFO] [stderr] Compiling tracing v0.1.37 [INFO] [stderr] Compiling autolog v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 12.38s [INFO] running `Command { std: "docker" "inspect" "a6a07c45020e9d3b937abc9c2e9c6cc4dace6ee1de2fbfa5f710daeecaeb9722", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a6a07c45020e9d3b937abc9c2e9c6cc4dace6ee1de2fbfa5f710daeecaeb9722", kill_on_drop: false }` [INFO] [stdout] a6a07c45020e9d3b937abc9c2e9c6cc4dace6ee1de2fbfa5f710daeecaeb9722 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "test" "--frozen" "--no-run" "--message-format=json" "--target" "i686-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 9a9551efe5b1d8255416b64a13ee0e5559d40141001f7ed6ceaf9e72e2b6f133 [INFO] running `Command { std: "docker" "start" "-a" "9a9551efe5b1d8255416b64a13ee0e5559d40141001f7ed6ceaf9e72e2b6f133", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling futures-core v0.3.25 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling crossbeam-utils v0.8.14 [INFO] [stderr] Compiling log v0.4.17 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling event-listener v2.5.3 [INFO] [stderr] Compiling parking v2.0.0 [INFO] [stderr] Compiling signal-hook v0.3.14 [INFO] [stderr] Compiling futures-io v0.3.25 [INFO] [stderr] Compiling pin-project-lite v0.2.9 [INFO] [stderr] Compiling waker-fn v1.1.0 [INFO] [stderr] Compiling fastrand v1.8.0 [INFO] [stderr] Compiling parking_lot_core v0.9.5 [INFO] [stderr] Compiling async-task v4.3.0 [INFO] [stderr] Compiling socket2 v0.4.7 [INFO] [stderr] Compiling once_cell v1.16.0 [INFO] [stderr] Compiling atomic-waker v1.0.0 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling proc-macro2 v1.0.47 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling unicode-ident v1.0.5 [INFO] [stderr] Compiling smallvec v1.10.0 [INFO] [stderr] Compiling tracing-core v0.1.30 [INFO] [stderr] Compiling concurrent-queue v2.0.0 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling slab v0.4.7 [INFO] [stderr] Compiling polling v2.5.1 [INFO] [stderr] Compiling async-io v1.12.0 [INFO] [stderr] Compiling async-net v1.7.0 [INFO] [stderr] Compiling async-fs v1.6.0 [INFO] [stderr] Compiling futures-lite v1.12.0 [INFO] [stderr] Compiling lock_api v0.4.9 [INFO] [stderr] Compiling async-process v1.6.0 [INFO] [stderr] Compiling async-channel v1.8.0 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling tracing-log v0.1.3 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling parking_lot v0.12.1 [INFO] [stderr] Compiling syn v1.0.105 [INFO] [stderr] Compiling litrs v0.2.3 [INFO] [stderr] Compiling tracing v0.1.37 [INFO] [stderr] Compiling async-lock v2.6.0 [INFO] [stderr] Compiling blocking v1.3.0 [INFO] [stderr] Compiling async-executor v1.5.0 [INFO] [stderr] Compiling tracing-subscriber v0.3.16 [INFO] [stderr] Compiling smol v1.3.0 [INFO] [stderr] Compiling autolog v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 15.45s [INFO] running `Command { std: "docker" "inspect" "9a9551efe5b1d8255416b64a13ee0e5559d40141001f7ed6ceaf9e72e2b6f133", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9a9551efe5b1d8255416b64a13ee0e5559d40141001f7ed6ceaf9e72e2b6f133", kill_on_drop: false }` [INFO] [stdout] 9a9551efe5b1d8255416b64a13ee0e5559d40141001f7ed6ceaf9e72e2b6f133 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+fc4ba03ef77ca5018fb78a097d3b924d30f15bfc" "test" "--frozen" "--target" "i686-unknown-linux-musl", kill_on_drop: false }` [INFO] [stdout] 678c8f47201d050e9c8877c9a984abee2d9a3b0cedb4c4c7cec21c5c017216db [INFO] running `Command { std: "docker" "start" "-a" "678c8f47201d050e9c8877c9a984abee2d9a3b0cedb4c4c7cec21c5c017216db", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/autolog-c2c46627d517626a) [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/mod.rs (/opt/rustwide/target/i686-unknown-linux-musl/debug/deps/mod-4dca168e9483d52f) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] 2022-12-08T00:18:02.515286Z TRACE r#mod: Function was called without fn name as an arg, but with args: first arg, second arg [INFO] [stdout] test test_no_fn_name_args ... ok [INFO] [stdout] 2022-12-08T00:18:02.515428Z TRACE r#mod: "with_args" was called with variable "arg1 = first arg" [INFO] [stdout] test test_with_args ... ok [INFO] [stdout] 2022-12-08T00:18:02.515686Z TRACE r#mod: "default_message" was called [INFO] [stdout] 2022-12-08T00:18:02.515836Z TRACE r#mod: "fn_name_no_args" was called without args [INFO] [stdout] 2022-12-08T00:18:02.516134Z TRACE r#mod: "async" fn was called with variable "arg1 = first arg" [INFO] [stdout] test test_default_message ... ok [INFO] [stdout] test test_fn_name_no_args ... ok [INFO] [stdout] test test_async ... ok [INFO] [stdout] 2022-12-08T00:18:02.518381Z TRACE r#mod: Function was called without fn name as an arg [INFO] [stdout] test test_no_fn_name ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Doc-tests autolog [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test src/lib.rs - autolog (line 35) ... ok [INFO] [stdout] test src/lib.rs - (line 27) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.35s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "678c8f47201d050e9c8877c9a984abee2d9a3b0cedb4c4c7cec21c5c017216db", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "678c8f47201d050e9c8877c9a984abee2d9a3b0cedb4c4c7cec21c5c017216db", kill_on_drop: false }` [INFO] [stdout] 678c8f47201d050e9c8877c9a984abee2d9a3b0cedb4c4c7cec21c5c017216db