[INFO] fetching crate ic_mple_log 0.16.1... [INFO] testing ic_mple_log-0.16.1 against master#c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 for pr-146098-7 [INFO] extracting crate ic_mple_log 0.16.1 into /workspace/builds/worker-7-tc1/source [INFO] started tweaking crates.io crate ic_mple_log 0.16.1 [INFO] finished tweaking crates.io crate ic_mple_log 0.16.1 [INFO] tweaked toml for crates.io crate ic_mple_log 0.16.1 written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate ic_mple_log 0.16.1 on toolchain c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate ic_mple_log 0.16.1 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" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded ic-cdk-executor v1.0.2 [INFO] [stderr] Downloaded psm v0.1.27 [INFO] [stderr] Downloaded ic-management-canister-types v0.3.3 [INFO] [stderr] Downloaded ic-cdk-macros v0.18.7 [INFO] [stderr] Downloaded ic-cdk-timers v0.12.2 [INFO] [stderr] Downloaded ic_mple_utils v0.16.1 [INFO] [stderr] Downloaded ringbuffer v0.16.0 [INFO] [stderr] Downloaded ic-cdk v0.18.7 [INFO] [stderr] Downloaded ic-stable-structures v0.7.2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 18c04bf12d9612bbfcb8706c645bedeac40b1ad373776c923b2e9a26a514f465 [INFO] running `Command { std: "docker" "start" "-a" "18c04bf12d9612bbfcb8706c645bedeac40b1ad373776c923b2e9a26a514f465", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "18c04bf12d9612bbfcb8706c645bedeac40b1ad373776c923b2e9a26a514f465", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "18c04bf12d9612bbfcb8706c645bedeac40b1ad373776c923b2e9a26a514f465", kill_on_drop: false }` [INFO] [stdout] 18c04bf12d9612bbfcb8706c645bedeac40b1ad373776c923b2e9a26a514f465 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 23823d17bd2d3df9a02dd2b5d27c61dd5bce938a3ede46d69a7d68d1f3d51d63 [INFO] running `Command { std: "docker" "start" "-a" "23823d17bd2d3df9a02dd2b5d27c61dd5bce938a3ede46d69a7d68d1f3d51d63", kill_on_drop: false }` [INFO] [stderr] Compiling find-msvc-tools v0.1.3 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling crc32fast v1.5.0 [INFO] [stderr] Compiling arrayvec v0.5.2 [INFO] [stderr] Compiling typed-arena v2.0.2 [INFO] [stderr] Compiling leb128 v0.2.5 [INFO] [stderr] Compiling ic-cdk-executor v1.0.2 [INFO] [stderr] Compiling cc v1.2.40 [INFO] [stderr] Compiling ic0 v1.0.1 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling regex-syntax v0.8.6 [INFO] [stderr] Compiling pretty v0.12.5 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling arc-swap v1.7.1 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling ringbuffer v0.16.0 [INFO] [stderr] Compiling sha2 v0.10.9 [INFO] [stderr] Compiling psm v0.1.27 [INFO] [stderr] Compiling stacker v0.1.22 [INFO] [stderr] Compiling regex-automata v0.4.11 [INFO] [stderr] Compiling regex v1.11.3 [INFO] [stderr] Compiling binread_derive v2.1.0 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling candid_derive v0.10.19 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling strum_macros v0.26.4 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling binread v2.2.0 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling strum v0.26.3 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling ic_principal v0.1.1 [INFO] [stderr] Compiling num-bigint v0.4.6 [INFO] [stderr] Compiling ic-error-types v0.2.0 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling candid v0.10.19 [INFO] [stderr] Compiling ic-management-canister-types v0.3.3 [INFO] [stderr] Compiling ic-cdk-macros v0.18.7 [INFO] [stderr] Compiling ic-cdk v0.18.7 [INFO] [stderr] Compiling ic-cdk-timers v0.12.2 [INFO] [stderr] Compiling ic_mple_utils v0.16.1 [INFO] [stderr] Compiling ic_mple_log v0.16.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 05s [INFO] running `Command { std: "docker" "inspect" "23823d17bd2d3df9a02dd2b5d27c61dd5bce938a3ede46d69a7d68d1f3d51d63", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "23823d17bd2d3df9a02dd2b5d27c61dd5bce938a3ede46d69a7d68d1f3d51d63", kill_on_drop: false }` [INFO] [stdout] 23823d17bd2d3df9a02dd2b5d27c61dd5bce938a3ede46d69a7d68d1f3d51d63 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0e82f1979873f5f10e4dd4fe24115f78482bdac14b77dc598aeee76aa3dd3d13 [INFO] running `Command { std: "docker" "start" "-a" "0e82f1979873f5f10e4dd4fe24115f78482bdac14b77dc598aeee76aa3dd3d13", kill_on_drop: false }` [INFO] [stderr] Compiling ic_mple_log v0.16.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.61s [INFO] running `Command { std: "docker" "inspect" "0e82f1979873f5f10e4dd4fe24115f78482bdac14b77dc598aeee76aa3dd3d13", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0e82f1979873f5f10e4dd4fe24115f78482bdac14b77dc598aeee76aa3dd3d13", kill_on_drop: false }` [INFO] [stdout] 0e82f1979873f5f10e4dd4fe24115f78482bdac14b77dc598aeee76aa3dd3d13 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c90bcb9571b7aab0d8beaa2ce8a998ffaf079d38" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 9d68a6fa616af6c1b4bfbb804638aa3ed94ea598a616ba12b02b0a822b2bb2e3 [INFO] running `Command { std: "docker" "start" "-a" "9d68a6fa616af6c1b4bfbb804638aa3ed94ea598a616ba12b02b0a822b2bb2e3", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/ic_mple_log-9f16aa0e1241cb5f) [INFO] [stdout] [INFO] [stdout] running 25 tests [INFO] [stdout] test formatter::tests::format_empty_target ... ok [INFO] [stdout] test formatter::tests::format_indent_spaces ... ok [INFO] [stdout] test formatter::tests::format_no_header ... ok [INFO] [stdout] test formatter::tests::format_no_target ... ok [INFO] [stdout] test formatter::tests::format_indent_spaces_no_header ... ok [INFO] [stdout] test formatter::tests::format_suffix ... ok [INFO] [stdout] test formatter::tests::format_with_header ... ok [INFO] [stdout] test tests::update_filter_at_runtime ... ok [INFO] [stdout] test writer::tests::change_capacity_disable_with_zero_non_empty ... ok [INFO] [stdout] test formatter::tests::format_target ... ok [INFO] [stdout] test writer::tests::change_capacity_extend_preserves_entries ... ok [INFO] [stdout] test writer::tests::change_capacity_preserves_offsets ... ok [INFO] [stdout] test writer::tests::change_capacity_disable_with_zero ... ok [INFO] [stdout] test writer::tests::change_capacity_shrink_preserves_entries ... ok [INFO] [stdout] test formatter::tests::format_indent_zero_spaces ... ok [INFO] [stdout] test writer::tests::max_record_length_is_respected ... ok [INFO] [stdout] test writer::tests::test_memory_writer_take_data_with_full_buffer ... ok [INFO] [stdout] test writer::tests::test_memory_writer_take_data_with_one_entry_in_buffer ... ok [INFO] [stdout] test writer::tests::test_circular_overwrite ... ok [INFO] [stdout] test writer::tests::test_memory_writer_append ... ok [INFO] [stdout] test writer::tests::test_memory_writer_take_data_with_empty_buffer ... ok [INFO] [stdout] test writer::tests::test_memory_writer_take_data_with_overridden_buffer ... ok [INFO] [stdout] test writer::tests::test_memory_writer_take_data_with_overridden_buffer_not_multiple_of_size ... ok [INFO] [stdout] test writer::tests::test_memory_writer_take_data_with_two_entries_in_buffer ... ok [INFO] [stderr] Doc-tests ic_mple_log [INFO] [stdout] test formatter::tests::format_suffix_with_indent ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test src/formatter/mod.rs - formatter (line 15) ... ok [INFO] [stdout] test src/lib.rs - Builder::filter_level (line 145) ... ok [INFO] [stdout] test src/lib.rs - Builder::filter (line 167) ... ok [INFO] [stdout] test src/lib.rs - Builder::filter_module (line 126) ... ok [INFO] [stdout] test src/lib.rs - Builder (line 61) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.59s [INFO] [stdout] [INFO] [stdout] all doctests ran in 1.75s; merged doctests compilation took 0.16s [INFO] running `Command { std: "docker" "inspect" "9d68a6fa616af6c1b4bfbb804638aa3ed94ea598a616ba12b02b0a822b2bb2e3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9d68a6fa616af6c1b4bfbb804638aa3ed94ea598a616ba12b02b0a822b2bb2e3", kill_on_drop: false }` [INFO] [stdout] 9d68a6fa616af6c1b4bfbb804638aa3ed94ea598a616ba12b02b0a822b2bb2e3