[INFO] fetching crate tp-allocator 2.1.2...
[INFO] testing tp-allocator-2.1.2 against try#ddc023471205c186b40f4bf8ffb598008dbf4885 for pr-87041-1
[INFO] extracting crate tp-allocator 2.1.2 into /workspace/builds/worker-89/source
[INFO] validating manifest of crates.io crate tp-allocator 2.1.2 on toolchain ddc023471205c186b40f4bf8ffb598008dbf4885
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate tp-allocator 2.1.2
[INFO] finished tweaking crates.io crate tp-allocator 2.1.2
[INFO] tweaked toml for crates.io crate tp-allocator 2.1.2 written to /workspace/builds/worker-89/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0918e00a2b53967f9bab692962640693a263614352d14c4fbd9ad80b501d7351
[INFO] running `Command { std: "docker" "start" "-a" "0918e00a2b53967f9bab692962640693a263614352d14c4fbd9ad80b501d7351", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0918e00a2b53967f9bab692962640693a263614352d14c4fbd9ad80b501d7351", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0918e00a2b53967f9bab692962640693a263614352d14c4fbd9ad80b501d7351", kill_on_drop: false }`
[INFO] [stdout] 0918e00a2b53967f9bab692962640693a263614352d14c4fbd9ad80b501d7351
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/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" "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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 165b16720c48425e11e70d3331ac273010f37d45ced83971f1d1efae8f297072
[INFO] running `Command { std: "docker" "start" "-a" "165b16720c48425e11e70d3331ac273010f37d45ced83971f1d1efae8f297072", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling tetsy-util-mem v0.9.1
[INFO] [stderr]    Compiling tetsy-hash-db v0.15.3
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling tetsy-hash256-std-hasher v0.15.3
[INFO] [stderr]    Compiling serde_derive v1.0.131
[INFO] [stderr]    Compiling impl-trait-for-tuples v0.2.1
[INFO] [stderr]    Compiling ref-cast v1.0.6
[INFO] [stderr]    Compiling futures-util v0.3.17
[INFO] [stderr]    Compiling thiserror v1.0.30
[INFO] [stderr]    Compiling dyn-clonable v0.9.0
[INFO] [stderr]    Compiling zeroize v1.4.3
[INFO] [stderr]    Compiling libsecp256k1 v0.3.5
[INFO] [stderr]    Compiling curve25519-dalek v2.1.3
[INFO] [stderr]    Compiling merlin v2.0.1
[INFO] [stderr]    Compiling curve25519-dalek v3.2.0
[INFO] [stderr]    Compiling secrecy v0.7.0
[INFO] [stderr]    Compiling tiny-bip39 v0.8.2
[INFO] [stderr]    Compiling futures-executor v0.3.17
[INFO] [stderr]    Compiling futures v0.3.17
[INFO] [stderr]    Compiling serde v1.0.131
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling twasmi v0.6.2
[INFO] [stderr]    Compiling toml v0.5.8
[INFO] [stderr]    Compiling impl-serde v0.3.2
[INFO] [stderr]    Compiling tracing-serde v0.1.2
[INFO] [stderr]    Compiling serde_json v1.0.73
[INFO] [stderr]    Compiling tetsy-impl-serde v0.3.2
[INFO] [stderr]    Compiling schnorrkel v0.9.1
[INFO] [stderr]    Compiling ed25519-dalek v1.0.1
[INFO] [stderr]    Compiling tracing-subscriber v0.2.25
[INFO] [stderr]    Compiling tetcore-bip39 v0.4.2
[INFO] [stderr]    Compiling proc-macro-crate v0.1.5
[INFO] [stderr]    Compiling tetsy-scale-codec-derive v2.0.1
[INFO] [stderr]    Compiling tp-runtime-interface-proc-macro v2.1.2
[INFO] [stderr]    Compiling tetsy-scale-codec v2.0.1
[INFO] [stderr]    Compiling tetsy-impl-codec v0.5.1
[INFO] [stderr]    Compiling tetcore-storage v2.1.2
[INFO] [stderr]    Compiling tetcore-wasm-interface v2.1.2
[INFO] [stderr]    Compiling tetcore-tracing v2.1.2
[INFO] [stderr]    Compiling tetsy-primitive-types v0.9.1
[INFO] [stderr]    Compiling externalities v2.1.2
[INFO] [stderr]    Compiling tp-runtime-interface v2.1.2
[INFO] [stderr]    Compiling tet-core v2.1.2
[INFO] [stderr]    Compiling tp-allocator v2.1.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused borrow that must be used
[INFO] [stdout]    --> src/freeing_bump.rs:476:3
[INFO] [stdout]     |
[INFO] [stdout] 476 |         &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the borrow produces a value
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout] help: use `let _ = ...` to ignore the resulting value
[INFO] [stdout]     |
[INFO] [stdout] 476 |         let _ = &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stdout]     |         +++++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 41.78s
[INFO] running `Command { std: "docker" "inspect" "165b16720c48425e11e70d3331ac273010f37d45ced83971f1d1efae8f297072", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "165b16720c48425e11e70d3331ac273010f37d45ced83971f1d1efae8f297072", kill_on_drop: false }`
[INFO] [stdout] 165b16720c48425e11e70d3331ac273010f37d45ced83971f1d1efae8f297072
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/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" "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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 474b090331fccbb1013c74b6234dfae21bc1e6590e781bda59ac6aeeac810d49
[INFO] running `Command { std: "docker" "start" "-a" "474b090331fccbb1013c74b6234dfae21bc1e6590e781bda59ac6aeeac810d49", kill_on_drop: false }`
[INFO] [stderr]    Compiling tp-allocator v2.1.2 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused borrow that must be used
[INFO] [stdout]    --> src/freeing_bump.rs:476:3
[INFO] [stdout]     |
[INFO] [stdout] 476 |         &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the borrow produces a value
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout] help: use `let _ = ...` to ignore the resulting value
[INFO] [stdout]     |
[INFO] [stdout] 476 |         let _ = &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stdout]     |         +++++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused borrow that must be used
[INFO] [stdout]    --> src/freeing_bump.rs:476:3
[INFO] [stdout]     |
[INFO] [stdout] 476 |         &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the borrow produces a value
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout] help: use `let _ = ...` to ignore the resulting value
[INFO] [stdout]     |
[INFO] [stdout] 476 |         let _ = &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stdout]     |         +++++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.19s
[INFO] running `Command { std: "docker" "inspect" "474b090331fccbb1013c74b6234dfae21bc1e6590e781bda59ac6aeeac810d49", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "474b090331fccbb1013c74b6234dfae21bc1e6590e781bda59ac6aeeac810d49", kill_on_drop: false }`
[INFO] [stdout] 474b090331fccbb1013c74b6234dfae21bc1e6590e781bda59ac6aeeac810d49
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-89/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" "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:a429bb59b7a3b4e7270a4b4c243bb115fc561d8f62ec34ef63a0a36cabf65dfd" "/opt/rustwide/cargo-home/bin/cargo" "+ddc023471205c186b40f4bf8ffb598008dbf4885" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 854b7cba6f038b2c8577e3523039b16621ec4de99cef82eb7bac20e0bacd558a
[INFO] running `Command { std: "docker" "start" "-a" "854b7cba6f038b2c8577e3523039b16621ec4de99cef82eb7bac20e0bacd558a", kill_on_drop: false }`
[INFO] [stderr] warning: unused borrow that must be used
[INFO] [stderr]    --> src/freeing_bump.rs:476:3
[INFO] [stderr]     |
[INFO] [stderr] 476 |         &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ the borrow produces a value
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr] help: use `let _ = ...` to ignore the resulting value
[INFO] [stderr]     |
[INFO] [stderr] 476 |         let _ = &mut self[range].copy_from_slice(&bytes[..]);
[INFO] [stderr]     |         +++++++
[INFO] [stderr] 
[INFO] [stderr] warning: `tp-allocator` (lib) generated 1 warning
[INFO] [stderr] warning: `tp-allocator` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.18s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/tp_allocator-ad3874d138a83d4f)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test freeing_bump::tests::poison_oom ... ok
[INFO] [stdout] test freeing_bump::tests::should_build_linked_list_of_free_areas_properly ... ok
[INFO] [stdout] test freeing_bump::tests::header_read_write ... ok
[INFO] [stdout] test freeing_bump::tests::should_always_align_pointers_to_multiples_of_8 ... ok
[INFO] [stdout] test freeing_bump::tests::should_allocate_properly ... ok
[INFO] [stdout] test freeing_bump::tests::deallocate_needs_to_maintain_linked_list ... ok
[INFO] [stdout] test freeing_bump::tests::should_allocate_max_possible_allocation_size ... ok
[INFO] [stdout] test freeing_bump::tests::should_calculate_total_heap_size_to_zero ... ok
[INFO] [stdout] test freeing_bump::tests::should_free_properly ... ok
[INFO] [stdout] test freeing_bump::tests::should_get_item_size_from_order ... ok
[INFO] [stdout] test freeing_bump::tests::should_deallocate_and_reallocate_properly ... ok
[INFO] [stdout] test freeing_bump::tests::should_calculate_total_size_of_zero ... ok
[INFO] [stdout] test freeing_bump::tests::should_get_max_item_size_from_index ... ok
[INFO] [stdout] test freeing_bump::tests::should_include_prefixes_in_total_heap_size ... ok
[INFO] [stdout] test freeing_bump::tests::should_increment_pointers_properly ... ok
[INFO] [stdout] test freeing_bump::tests::should_not_allocate_if_requested_size_too_large ... ok
[INFO] [stdout] test freeing_bump::tests::should_not_allocate_if_full ... ok
[INFO] [stdout] test freeing_bump::tests::should_not_allocate_if_too_large ... ok
[INFO] [stdout] test freeing_bump::tests::should_read_and_write_u64_correctly ... ok
[INFO] [stdout] test freeing_bump::tests::should_return_error_when_bumper_greater_than_heap_size ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests tp-allocator
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/freeing_bump.rs - freeing_bump (line 22) ... ignored
[INFO] [stdout] test src/freeing_bump.rs - freeing_bump::Header (line 196) ... ignored
[INFO] [stdout] test src/freeing_bump.rs - freeing_bump::Header (line 204) ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 3 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "854b7cba6f038b2c8577e3523039b16621ec4de99cef82eb7bac20e0bacd558a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "854b7cba6f038b2c8577e3523039b16621ec4de99cef82eb7bac20e0bacd558a", kill_on_drop: false }`
[INFO] [stdout] 854b7cba6f038b2c8577e3523039b16621ec4de99cef82eb7bac20e0bacd558a