[INFO] fetching crate plctag-async 0.2.2... [INFO] testing plctag-async-0.2.2 against try#015dbcea68b7642e2acc268b5c8f3e9086fc02ff for pr-102570 [INFO] extracting crate plctag-async 0.2.2 into /workspace/builds/worker-6/source [INFO] validating manifest of crates.io crate plctag-async 0.2.2 on toolchain 015dbcea68b7642e2acc268b5c8f3e9086fc02ff [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate plctag-async 0.2.2 [INFO] finished tweaking crates.io crate plctag-async 0.2.2 [INFO] tweaked toml for crates.io crate plctag-async 0.2.2 written to /workspace/builds/worker-6/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 391ffaf8d6b67b3c77ff0b457c8f08ced231b709f02b79ff9a5bd3ecea76677e [INFO] running `Command { std: "docker" "start" "-a" "391ffaf8d6b67b3c77ff0b457c8f08ced231b709f02b79ff9a5bd3ecea76677e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "391ffaf8d6b67b3c77ff0b457c8f08ced231b709f02b79ff9a5bd3ecea76677e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "391ffaf8d6b67b3c77ff0b457c8f08ced231b709f02b79ff9a5bd3ecea76677e", kill_on_drop: false }` [INFO] [stdout] 391ffaf8d6b67b3c77ff0b457c8f08ced231b709f02b79ff9a5bd3ecea76677e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 101bedbc02149a47d6806084f56f95950de571ec58b38ffe547a4ebb724ebb1d [INFO] running `Command { std: "docker" "start" "-a" "101bedbc02149a47d6806084f56f95950de571ec58b38ffe547a4ebb724ebb1d", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.137 [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling proc-macro2 v1.0.47 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling unicode-ident v1.0.5 [INFO] [stderr] Compiling glob v0.3.0 [INFO] [stderr] Compiling log v0.4.17 [INFO] [stderr] Compiling os_str_bytes v6.3.1 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling minimal-lexical v0.2.1 [INFO] [stderr] Compiling regex-syntax v0.6.28 [INFO] [stderr] Compiling hashbrown v0.12.3 [INFO] [stderr] Compiling either v1.8.0 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling libloading v0.7.4 [INFO] [stderr] Compiling humantime v2.1.0 [INFO] [stderr] Compiling clap_lex v0.2.4 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling bindgen v0.60.1 [INFO] [stderr] Compiling textwrap v0.16.0 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling peeking_take_while v0.1.2 [INFO] [stderr] Compiling lazycell v1.3.0 [INFO] [stderr] Compiling rustc-hash v1.1.0 [INFO] [stderr] Compiling shlex v1.1.0 [INFO] [stderr] Compiling syn v1.0.103 [INFO] [stderr] Compiling cc v1.0.76 [INFO] [stderr] Compiling fs_extra v1.2.0 [INFO] [stderr] Compiling parking_lot_core v0.9.4 [INFO] [stderr] Compiling pkg-config v0.3.26 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling indexmap v1.9.1 [INFO] [stderr] Compiling lock_api v0.4.9 [INFO] [stderr] Compiling smallvec v1.10.0 [INFO] [stderr] Compiling tokio v1.21.2 [INFO] [stderr] Compiling clang-sys v1.4.0 [INFO] [stderr] Compiling async-trait v0.1.58 [INFO] [stderr] Compiling paste v1.0.9 [INFO] [stderr] Compiling dyn-clone v1.0.9 [INFO] [stderr] Compiling cmake v0.1.49 [INFO] [stderr] Compiling aho-corasick v0.7.19 [INFO] [stderr] Compiling nom v7.1.1 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling which v4.3.0 [INFO] [stderr] Compiling clap v3.2.23 [INFO] [stderr] Compiling parking_lot v0.12.1 [INFO] [stderr] Compiling num_cpus v1.14.0 [INFO] [stderr] Compiling pin-project-lite v0.2.9 [INFO] [stderr] Compiling once_cell v1.16.0 [INFO] [stderr] Compiling regex v1.7.0 [INFO] [stderr] Compiling cexpr v0.6.0 [INFO] [stderr] Compiling env_logger v0.9.3 [INFO] [stderr] Compiling tokio-macros v1.8.0 [INFO] [stderr] Compiling plctag-sys v0.2.2 [INFO] [stderr] Compiling plctag-core v0.2.2 [INFO] [stderr] Compiling plctag-async v0.2.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 31.44s [INFO] running `Command { std: "docker" "inspect" "101bedbc02149a47d6806084f56f95950de571ec58b38ffe547a4ebb724ebb1d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "101bedbc02149a47d6806084f56f95950de571ec58b38ffe547a4ebb724ebb1d", kill_on_drop: false }` [INFO] [stdout] 101bedbc02149a47d6806084f56f95950de571ec58b38ffe547a4ebb724ebb1d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c19d9a982797870ed7b8904abfc4f3190cd8643cbcbb6144cb9861457644bc0e [INFO] running `Command { std: "docker" "start" "-a" "c19d9a982797870ed7b8904abfc4f3190cd8643cbcbb6144cb9861457644bc0e", kill_on_drop: false }` [INFO] [stderr] Compiling anyhow v1.0.66 [INFO] [stderr] Compiling plctag-sys v0.2.2 [INFO] [stderr] Compiling plctag-core v0.2.2 [INFO] [stderr] Compiling plctag-async v0.2.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 9.78s [INFO] running `Command { std: "docker" "inspect" "c19d9a982797870ed7b8904abfc4f3190cd8643cbcbb6144cb9861457644bc0e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c19d9a982797870ed7b8904abfc4f3190cd8643cbcbb6144cb9861457644bc0e", kill_on_drop: false }` [INFO] [stdout] c19d9a982797870ed7b8904abfc4f3190cd8643cbcbb6144cb9861457644bc0e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "+015dbcea68b7642e2acc268b5c8f3e9086fc02ff" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] b2247a21171bbcc23c99c77c5fce425632f19dc0110805e4ddeebe51a376b515 [INFO] running `Command { std: "docker" "start" "-a" "b2247a21171bbcc23c99c77c5fce425632f19dc0110805e4ddeebe51a376b515", kill_on_drop: false }` [INFO] [stderr] Compiling plctag-sys v0.2.2 [INFO] [stderr] Compiling plctag-core v0.2.2 [INFO] [stderr] Compiling plctag-async v0.2.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 9.38s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/plctag_async-e326fdb4fc065faa) [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stdout] test pool::test::test_remove ... ok [INFO] [stdout] test cell::test::test_cell ... ok [INFO] [stdout] test pool::test::error_at_first ... ok [INFO] [stdout] test pool::test::more_instances ... ok [INFO] [stderr] 2022-11-09 11:20:12.049 thread(1) tag(0) INFO initialize_modules:231 Done. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO find_tag_create_func:107 Matched make=system [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO find_tag_create_func:110 Matched make=system family=library [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO find_tag_create_func:118 Matched make=system family=library model=NULL [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO system_tag_create:98 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL system_tag_create:106 Creating special tag debug [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO rc_alloc_impl:111 Starting, called from system_tag_create:113 [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO rc_alloc_impl:130 Done [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL rc_alloc_impl:135 Returning memory pointer 0x7f5f4c010000 [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO plc_tag_generic_init_tag:462 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL mutex_create:648 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL mutex_create:677 Done creating mutex 0x7f5f4c0100d0. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL mutex_create:648 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL mutex_create:677 Done creating mutex 0x7f5f4c010110. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL cond_create:991 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL cond_create:1028 Done. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO plc_tag_generic_init_tag:493 Done. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO system_tag_create:145 Done [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO set_tag_byte_order:3859 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) INFO set_tag_byte_order:4160 Done. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL add_tag_lookup:4263 Starting. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL add_tag_lookup:4283 Found unused ID 11 [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(0) DETAIL add_tag_lookup:4303 Done. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(11) INFO plc_tag_create_ex:959 Returning mapped tag ID 11 [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(11) DETAIL plc_tag_create_ex:985 Tag status after creation is PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.059 thread(1) tag(11) INFO plc_tag_create_ex:1060 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback:1125 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback_ex:1180 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback_ex:1203 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback:1129 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_read:1540 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL tag_raise_event:232 PLCTAG_EVENT_READ_STARTED raised with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL tag_raise_event:236 Enabled PLCTAG_EVENT_READ_COMPLETE. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:405 Tag read started with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) INFO system_tag_read:194 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:232 PLCTAG_EVENT_READ_STARTED raised with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:236 Enabled PLCTAG_EVENT_READ_COMPLETE. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:215 PLCTAG_EVENT_READ_COMPLETED raised with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:217 Raising synthesized created event on read completed event. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:227 Disabled PLCTAG_EVENT_READ_COMPLETE. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:397 Tag creation complete with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:405 Tag read started with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:429 Tag read completed with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) INFO system_tag_read:222 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) INFO plc_tag_read:1670 Done [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_unregister_callback:1226 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_unregister_callback:1245 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback:1125 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback_ex:1180 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback_ex:1203 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_register_callback:1129 Done. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) INFO plc_tag_write:1749 Starting. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL tag_raise_event:257 PLCTAG_EVENT_WRITE_STARTED raised with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL tag_raise_event:261 Enabled PLCTAG_EVENT_WRITE_COMPLETE. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:413 Tag write started with status PLCTAG_STATUS_OK. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:257 PLCTAG_EVENT_WRITE_STARTED raised with status PLCTAG_STATUS_PENDING. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(0) DETAIL tag_raise_event:261 Enabled PLCTAG_EVENT_WRITE_COMPLETE. [INFO] [stderr] 2022-11-09 11:20:12.060 thread(1) tag(11) DETAIL plc_tag_generic_handle_event_callbacks:413 Tag write started with status PLCTAG_STATUS_PENDING. [INFO] [stderr] 2022-11-09 11:20:12.049 thread(2) tag(0) INFO find_tag_create_func:107 Matched make=system [INFO] [stderr] 2022-11-09 11:20:12.049 thread(3) tag(0) INFO tag_tickler_func:508 Starting. [INFO] [stdout] test test::test_pool ... ok [INFO] [stdout] test pool::test::only_one_instance ... ok [INFO] [stdout] test pool::test::error_at_last ... ok [INFO] [stdout] test pool::test::test_expire_for_normal ... ok [INFO] [stdout] test test::test_entry ... FAILED [INFO] [stdout] test pool::test::test_expire_for_error ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test::test_entry stdout ---- [INFO] [stdout] thread 'test::test_entry' panicked at 'assertion failed: `(left == right)` [INFO] [stdout] left: `1`, [INFO] [stdout] right: `4`', src/lib.rs:195:13 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55a9ccdb8cf0 - std::backtrace_rs::backtrace::libunwind::trace::hecb21857e99d889d [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x55a9ccdb8cf0 - std::backtrace_rs::backtrace::trace_unsynchronized::h390569d291d382e6 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55a9ccdb8cf0 - std::sys_common::backtrace::_print_fmt::he268a904f7419e52 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x55a9ccdb8cf0 - ::fmt::hedbee76c21719161 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55a9ccddc5ee - core::fmt::write::hd7abf800172f9326 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/fmt/mod.rs:1209:17 [INFO] [stdout] 5: 0x55a9ccdb57a5 - std::io::Write::write_fmt::hce65cc88c775e9d3 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x55a9ccdb8ab5 - std::sys_common::backtrace::_print::he1bf0c0215d8eb21 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x55a9ccdb8ab5 - std::sys_common::backtrace::print::h1d63cc8fb6910f4d [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x55a9ccdba95f - std::panicking::default_hook::{{closure}}::h06165285990169e4 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x55a9ccdba61e - std::panicking::default_hook::hc8c52a6a2d770858 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x55a9cccd8537 - as core::ops::function::Fn>::call::h9bfd099cb89cb0bb [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2032:9 [INFO] [stdout] 11: 0x55a9cccd8537 - test::test_main::{{closure}}::h065ae2c9be25dce8 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:135:21 [INFO] [stdout] 12: 0x55a9ccdbb07d - as core::ops::function::Fn>::call::h6aaf80b277f5611a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2032:9 [INFO] [stdout] 13: 0x55a9ccdbb07d - std::panicking::rust_panic_with_hook::h4b7f78aa6e886a78 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:692:13 [INFO] [stdout] 14: 0x55a9ccdbadf7 - std::panicking::begin_panic_handler::{{closure}}::h8c929b88c5c37601 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:579:13 [INFO] [stdout] 15: 0x55a9ccdb919c - std::sys_common::backtrace::__rust_end_short_backtrace::h61d4e5f81197e3cc [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 16: 0x55a9ccdbab12 - rust_begin_unwind [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:575:5 [INFO] [stdout] 17: 0x55a9ccb57bb3 - core::panicking::panic_fmt::hd23e6f8c5d094f51 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panicking.rs:65:14 [INFO] [stdout] 18: 0x55a9ccddb1cb - core::panicking::assert_failed_inner::hb5c0f3f36147bb4f [INFO] [stdout] 19: 0x55a9ccd8a6aa - core::panicking::assert_failed::hb05ac7a61152156f [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panicking.rs:203:5 [INFO] [stdout] 20: 0x55a9ccc02dab - plctag_async::test::test_entry::{{closure}}::hef58a62bfb637764 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:195:13 [INFO] [stdout] 21: 0x55a9ccbb71e1 - as core::future::future::Future>::poll::hc8606eba85b97076 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/future/mod.rs:91:19 [INFO] [stdout] 22: 0x55a9ccc773a0 - tokio::park::thread::CachedParkThread::block_on::{{closure}}::h72545f6bed0e5acd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/park/thread.rs:267:54 [INFO] [stdout] 23: 0x55a9ccbfd0e9 - tokio::coop::with_budget::{{closure}}::hea9066d359d39393 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/coop.rs:102:9 [INFO] [stdout] 24: 0x55a9ccc5f616 - std::thread::local::LocalKey::try_with::he1828e6b519d368b [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:446:16 [INFO] [stdout] 25: 0x55a9ccc5acad - std::thread::local::LocalKey::with::h7a05ef6b725e2e60 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/local.rs:422:9 [INFO] [stdout] 26: 0x55a9ccc74304 - tokio::coop::with_budget::h990dc61710a07e57 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/coop.rs:95:5 [INFO] [stdout] 27: 0x55a9ccc74304 - tokio::coop::budget::h6cafe5c994ba47d2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/coop.rs:72:5 [INFO] [stdout] 28: 0x55a9ccc74304 - tokio::park::thread::CachedParkThread::block_on::h7f6e4ef40bb7a182 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/park/thread.rs:267:31 [INFO] [stdout] 29: 0x55a9ccbdf5e0 - tokio::runtime::enter::Enter::block_on::hddd72d22aba41769 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/enter.rs:152:13 [INFO] [stdout] 30: 0x55a9ccc81043 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::h4f07fcaa7d1c0412 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/multi_thread/mod.rs:79:9 [INFO] [stdout] 31: 0x55a9ccbc0271 - tokio::runtime::Runtime::block_on::hbf55a83e18cd8396 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/mod.rs:492:44 [INFO] [stdout] 32: 0x55a9ccc02018 - plctag_async::test::test_entry::h7762290d1c04f6cb [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:189:9 [INFO] [stdout] 33: 0x55a9ccc01e48 - plctag_async::test::test_entry::{{closure}}::h5e46b4d01ebf0634 [INFO] [stdout] at /opt/rustwide/workdir/src/lib.rs:187:5 [INFO] [stdout] 34: 0x55a9ccc8cbb5 - core::ops::function::FnOnce::call_once::hffc3280fa412aa83 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 35: 0x55a9cccdd8af - core::ops::function::FnOnce::call_once::h2f93ceb28cb0c61c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 36: 0x55a9cccdd8af - test::__rust_begin_short_backtrace::hdd721a0e8ee25a0e [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:637:18 [INFO] [stdout] 37: 0x55a9cccaee3c - test::run_test::{{closure}}::h95fe25ef5b9cf584 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:628:30 [INFO] [stdout] 38: 0x55a9cccaee3c - core::ops::function::FnOnce::call_once{{vtable.shim}}::he3685b1dad984a5a [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 39: 0x55a9cccdc868 - as core::ops::function::FnOnce>::call_once::h301cae15faf66265 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2000:9 [INFO] [stdout] 40: 0x55a9cccdc868 - as core::ops::function::FnOnce<()>>::call_once::hb7be0f46463c4091 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 41: 0x55a9cccdc868 - std::panicking::try::do_call::he80e1dd13c6c4b2c [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483:40 [INFO] [stdout] 42: 0x55a9cccdc868 - std::panicking::try::h88563fd695098a44 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447:19 [INFO] [stdout] 43: 0x55a9cccdc868 - std::panic::catch_unwind::h6a5b06a8b67964f8 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137:14 [INFO] [stdout] 44: 0x55a9cccdc868 - test::run_test_in_process::h3bfa2ac051e33645 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:660:27 [INFO] [stdout] 45: 0x55a9cccdc868 - test::run_test::run_test_inner::{{closure}}::h505cc84ec623ff60 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:554:39 [INFO] [stdout] 46: 0x55a9ccca95c4 - test::run_test::run_test_inner::{{closure}}::h0e58a333959ca1c3 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/test/src/lib.rs:581:37 [INFO] [stdout] 47: 0x55a9ccca95c4 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha224c611b967d9b5 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 48: 0x55a9cccaeec5 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h90e5d573525d2948 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/mod.rs:551:17 [INFO] [stdout] 49: 0x55a9cccaeec5 - as core::ops::function::FnOnce<()>>::call_once::h9d1846f6c9999fe4 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 50: 0x55a9cccaeec5 - std::panicking::try::do_call::h5ffdfcc148e40205 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:483:40 [INFO] [stdout] 51: 0x55a9cccaeec5 - std::panicking::try::hc1b1f6044948b131 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panicking.rs:447:19 [INFO] [stdout] 52: 0x55a9cccaeec5 - std::panic::catch_unwind::he641636e80f82a6d [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/panic.rs:137:14 [INFO] [stdout] 53: 0x55a9cccaeec5 - std::thread::Builder::spawn_unchecked_::{{closure}}::hdbdfe56cb03b1da6 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/thread/mod.rs:550:30 [INFO] [stdout] 54: 0x55a9cccaeec5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hea0273674710a4a3 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/core/src/ops/function.rs:510:5 [INFO] [stdout] 55: 0x55a9ccdbf5a3 - as core::ops::function::FnOnce>::call_once::h5676ec8835469d88 [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2000:9 [INFO] [stdout] 56: 0x55a9ccdbf5a3 - as core::ops::function::FnOnce>::call_once::h8aa650efc9f4a6ec [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/alloc/src/boxed.rs:2000:9 [INFO] [stdout] 57: 0x55a9ccdbf5a3 - std::sys::unix::thread::Thread::new::thread_start::h8e214f29f03a09ce [INFO] [stdout] at /rustc/015dbcea68b7642e2acc268b5c8f3e9086fc02ff/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 58: 0x7f5fb21c3609 - start_thread [INFO] [stdout] 59: 0x7f5fb1f91133 - clone [INFO] [stdout] 60: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test::test_entry [INFO] [stdout] [INFO] [stdout] test result: FAILED. 9 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.39s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "b2247a21171bbcc23c99c77c5fce425632f19dc0110805e4ddeebe51a376b515", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b2247a21171bbcc23c99c77c5fce425632f19dc0110805e4ddeebe51a376b515", kill_on_drop: false }` [INFO] [stdout] b2247a21171bbcc23c99c77c5fce425632f19dc0110805e4ddeebe51a376b515