[INFO] fetching crate kind2 0.2.79... [INFO] testing kind2-0.2.79 against try#613d1972a772987ed4ee06faccbab0e2fc839871+rustflags=-Copt-level=3 for pr-107051 [INFO] extracting crate kind2 0.2.79 into /workspace/builds/worker-0-tc2/source [INFO] validating manifest of crates.io crate kind2 0.2.79 on toolchain 613d1972a772987ed4ee06faccbab0e2fc839871 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking crates.io crate kind2 0.2.79 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate kind2 0.2.79 [INFO] tweaked toml for crates.io crate kind2 0.2.79 written to /workspace/builds/worker-0-tc2/source/Cargo.toml [INFO] crate crates.io crate kind2 0.2.79 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" "+613d1972a772987ed4ee06faccbab0e2fc839871" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] c89d4d78dbe3560136e6b786fd5e2ebd9b0bff6f4b6c914034ef479fc8f32e7f [INFO] running `Command { std: "docker" "start" "-a" "c89d4d78dbe3560136e6b786fd5e2ebd9b0bff6f4b6c914034ef479fc8f32e7f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "c89d4d78dbe3560136e6b786fd5e2ebd9b0bff6f4b6c914034ef479fc8f32e7f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c89d4d78dbe3560136e6b786fd5e2ebd9b0bff6f4b6c914034ef479fc8f32e7f", kill_on_drop: false }` [INFO] [stdout] c89d4d78dbe3560136e6b786fd5e2ebd9b0bff6f4b6c914034ef479fc8f32e7f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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 -Copt-level=3" "-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ca01c4565582e66d9712d9f4143902a662dff42f2cbb1e1bb8fade79bc9d2e3f [INFO] running `Command { std: "docker" "start" "-a" "ca01c4565582e66d9712d9f4143902a662dff42f2cbb1e1bb8fade79bc9d2e3f", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling libc v0.2.132 [INFO] [stderr] Compiling proc-macro2 v1.0.43 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling unicode-ident v1.0.4 [INFO] [stderr] Compiling once_cell v1.14.0 [INFO] [stderr] Compiling syn v1.0.99 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling pkg-config v0.3.25 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling pin-project-lite v0.2.9 [INFO] [stderr] Compiling futures-core v0.3.24 [INFO] [stderr] Compiling bytes v1.2.1 [INFO] [stderr] Compiling wasm-bindgen-shared v0.2.83 [INFO] [stderr] Compiling itoa v1.0.3 [INFO] [stderr] Compiling futures-task v0.3.24 [INFO] [stderr] Compiling futures-util v0.3.24 [INFO] [stderr] Compiling bumpalo v3.11.0 [INFO] [stderr] Compiling hashbrown v0.12.3 [INFO] [stderr] Compiling tracing-core v0.1.29 [INFO] [stderr] Compiling openssl v0.10.41 [INFO] [stderr] Compiling http v0.2.8 [INFO] [stderr] Compiling futures-sink v0.3.24 [INFO] [stderr] Compiling futures-channel v0.3.24 [INFO] [stderr] Compiling futures-io v0.3.24 [INFO] [stderr] Compiling native-tls v0.2.10 [INFO] [stderr] Compiling httparse v1.8.0 [INFO] [stderr] Compiling tinyvec v1.6.0 [INFO] [stderr] Compiling tracing v0.1.36 [INFO] [stderr] Compiling wasm-bindgen v0.2.83 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling percent-encoding v2.2.0 [INFO] [stderr] Compiling serde v1.0.144 [INFO] [stderr] Compiling form_urlencoded v1.1.0 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling tower-service v0.3.2 [INFO] [stderr] Compiling unicode-bidi v0.3.8 [INFO] [stderr] Compiling encoding_rs v0.8.31 [INFO] [stderr] Compiling socket2 v0.4.7 [INFO] [stderr] Compiling mio v0.8.4 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling http-body v0.4.5 [INFO] [stderr] Compiling tokio v1.21.1 [INFO] [stderr] Compiling indexmap v1.9.1 [INFO] [stderr] Compiling slab v0.4.7 [INFO] [stderr] Compiling getrandom v0.2.7 [INFO] [stderr] Compiling os_str_bytes v6.3.0 [INFO] [stderr] Compiling ryu v1.0.11 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling clap_lex v0.2.4 [INFO] [stderr] Compiling unicode-normalization v0.1.22 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling aho-corasick v0.7.19 [INFO] [stderr] Compiling either v1.8.0 [INFO] [stderr] Compiling termcolor v1.1.3 [INFO] [stderr] Compiling textwrap v0.15.1 [INFO] [stderr] Compiling ipnet v2.5.0 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling regex-syntax v0.6.27 [INFO] [stderr] Compiling itertools v0.10.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling idna v0.3.0 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling highlight_error v0.1.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling wasm-bindgen-backend v0.2.83 [INFO] [stderr] Compiling proc-macro-error-attr v1.0.4 [INFO] [stderr] Compiling url v2.3.1 [INFO] [stderr] Compiling wasm-bindgen-macro-support v0.2.83 [INFO] [stderr] Compiling regex v1.6.0 [INFO] [stderr] Compiling openssl-sys v0.9.75 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling tokio-util v0.7.4 [INFO] [stderr] Compiling h2 v0.3.14 [INFO] [stderr] Compiling hyper v0.14.20 [INFO] [stderr] Compiling openssl-macros v0.1.0 [INFO] [stderr] Compiling wasm-bindgen-macro v0.2.83 [INFO] [stderr] Compiling clap_derive v3.2.18 [INFO] [stderr] Compiling js-sys v0.3.60 [INFO] [stderr] Compiling web-sys v0.3.60 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling clap v3.2.22 [INFO] [stderr] Compiling reqwest v0.11.11 [INFO] [stderr] Compiling hvm v0.1.88 [INFO] [stderr] Compiling kind2 v0.2.79 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 4m 11s [INFO] running `Command { std: "docker" "inspect" "ca01c4565582e66d9712d9f4143902a662dff42f2cbb1e1bb8fade79bc9d2e3f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ca01c4565582e66d9712d9f4143902a662dff42f2cbb1e1bb8fade79bc9d2e3f", kill_on_drop: false }` [INFO] [stdout] ca01c4565582e66d9712d9f4143902a662dff42f2cbb1e1bb8fade79bc9d2e3f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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 -Copt-level=3" "-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 1467517ee48b2776f94d2440f2aeff9ba5defdf742a476ead412b6e310db5d8c [INFO] running `Command { std: "docker" "start" "-a" "1467517ee48b2776f94d2440f2aeff9ba5defdf742a476ead412b6e310db5d8c", kill_on_drop: false }` [INFO] [stderr] Compiling thiserror v1.0.36 [INFO] [stderr] Compiling thiserror-impl v1.0.36 [INFO] [stderr] Compiling toml v0.5.9 [INFO] [stderr] Compiling ntest_proc_macro_helper v0.8.0 [INFO] [stderr] Compiling ntest_test_cases v0.8.0 [INFO] [stderr] Compiling diff v0.1.13 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling yansi v0.5.1 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling pretty_assertions v1.3.0 [INFO] [stderr] Compiling proc-macro-crate v1.2.1 [INFO] [stderr] Compiling ntest_timeout v0.8.1 [INFO] [stderr] Compiling ntest v0.8.1 [INFO] [stderr] Compiling kind2 v0.2.79 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 40.94s [INFO] running `Command { std: "docker" "inspect" "1467517ee48b2776f94d2440f2aeff9ba5defdf742a476ead412b6e310db5d8c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1467517ee48b2776f94d2440f2aeff9ba5defdf742a476ead412b6e310db5d8c", kill_on_drop: false }` [INFO] [stdout] 1467517ee48b2776f94d2440f2aeff9ba5defdf742a476ead412b6e310db5d8c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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 -Copt-level=3" "-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:7c93e6f9a94679f34221d1005849d4c83862f85b14a264fa0883942f4d043a66" "/opt/rustwide/cargo-home/bin/cargo" "+613d1972a772987ed4ee06faccbab0e2fc839871" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 345dcde7ec972efbb770a1ab8c8951dea2c156d956e47487ed3312d6587ae8a8 [INFO] running `Command { std: "docker" "start" "-a" "345dcde7ec972efbb770a1ab8c8951dea2c156d956e47487ed3312d6587ae8a8", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.25s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kind2-c4efd16f205917a7) [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/kind2-5a7db75c981c1c2d) [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] [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/debug/deps/kind2_tests-2b0fc89f1f537aed) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test test_to_kdl ... ok [INFO] [stdout] test test_to_hvm ... ok [INFO] [stdout] test test_run_hvm ... ok [INFO] [stdout] test test_checker ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test_checker stdout ---- [INFO] [stdout] thread '' panicked at 'Invalid output: 3 ', src/driver.rs:52:9 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55c38bbda54a - std::backtrace_rs::backtrace::libunwind::trace::he494f4174c7b193d [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x55c38bbda54a - std::backtrace_rs::backtrace::trace_unsynchronized::h576ffc8be8cf606d [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55c38bbda54a - std::sys_common::backtrace::_print_fmt::h785643da434656fb [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x55c38bbda54a - ::fmt::h9b4bec46148aa515 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55c38bbffc2e - core::fmt::write::h9b2447b2a0bad8cd [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/fmt/mod.rs:1213:17 [INFO] [stdout] 5: 0x55c38bbd4a25 - std::io::Write::write_fmt::h01b310af65ec4db4 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x55c38bbda315 - std::sys_common::backtrace::_print::hb761e44df96032bf [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x55c38bbda315 - std::sys_common::backtrace::print::h38eae7c422ff66f6 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x55c38bbdc10f - std::panicking::default_hook::{{closure}}::h54755764573e4b37 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x55c38bbdbdcf - std::panicking::default_hook::h978c28978503d61b [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x55c38b996547 - as core::ops::function::Fn>::call::hc75c380c644eb7dc [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 11: 0x55c38b996547 - test::test_main::{{closure}}::hf8df6ffaec03f459 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:135:21 [INFO] [stdout] 12: 0x55c38bbdc83a - as core::ops::function::Fn>::call::hf7b5b3aa2a34e491 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 13: 0x55c38bbdc83a - std::panicking::rust_panic_with_hook::h334ed9b3cc817783 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:692:13 [INFO] [stdout] 14: 0x55c38bbdc5b9 - std::panicking::begin_panic_handler::{{closure}}::h2ab7fcc83540cf23 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:579:13 [INFO] [stdout] 15: 0x55c38bbda9fc - std::sys_common::backtrace::__rust_end_short_backtrace::h65758a09a74021a8 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 16: 0x55c38bbdc2c2 - rust_begin_unwind [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:575:5 [INFO] [stdout] 17: 0x55c38b9575e3 - core::panicking::panic_fmt::h157cca2ea8c38d2f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/panicking.rs:64:14 [INFO] [stdout] 18: 0x55c38b9a20a9 - kind2::driver::readback_string::h8e73ae12fced473b [INFO] [stdout] at /opt/rustwide/workdir/src/driver.rs:52:9 [INFO] [stdout] 19: 0x55c38b9a2329 - kind2::driver::run_with_hvm::h0f680b8de33f1ea8 [INFO] [stdout] at /opt/rustwide/workdir/src/driver.rs:106:34 [INFO] [stdout] 20: 0x55c38b963ca1 - kind2_tests::test_checker::ntest_callback::{{closure}}::{{closure}}::h499f3ba67f524d0d [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:56:42 [INFO] [stdout] 21: 0x55c38b963ca1 - core::result::Result::and_then::h8ca9770f7492725f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/result.rs:1371:22 [INFO] [stdout] 22: 0x55c38b963ca1 - kind2_tests::test_checker::ntest_callback::{{closure}}::he1bdba4ec0210489 [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:56:22 [INFO] [stdout] 23: 0x55c38b963ca1 - core::ops::function::FnOnce::call_once::h8f1958e6596a009b [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x55c38b964c39 - kind2_tests::golden_test::haa048d7e03c11b02 [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:15:18 [INFO] [stdout] 25: 0x55c38b964c39 - kind2_tests::test_kind2::h8b6bd9c3dd6a1c2f [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:31:13 [INFO] [stdout] 26: 0x55c38b962cb3 - kind2_tests::test_checker::ntest_callback::h1f8d6b24a243312f [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:48:5 [INFO] [stdout] 27: 0x55c38b962cb3 - core::ops::function::Fn::call::h2cbe7e1b0c8d60b2 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:79:5 [INFO] [stdout] 28: 0x55c38b959c40 - ntest_proc_macro_helper::execute_with_timeout::{{closure}}::h3323e5835c5415d6 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/ntest_proc_macro_helper-0.8.0/src/lib.rs:14:37 [INFO] [stdout] 29: 0x55c38b959c40 - std::sys_common::backtrace::__rust_begin_short_backtrace::h422cc8df10039c10 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 30: 0x55c38b95e73b - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hde3169c5754017a3 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/thread/mod.rs:558:17 [INFO] [stdout] 31: 0x55c38b95e73b - as core::ops::function::FnOnce<()>>::call_once::hfb925e6cedfd2d19 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 32: 0x55c38b95e73b - std::panicking::try::do_call::h623874de28502279 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:483:40 [INFO] [stdout] 33: 0x55c38b95e73b - std::panicking::try::h9f53ca96c3b1c2fc [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:447:19 [INFO] [stdout] 34: 0x55c38b95e73b - std::panic::catch_unwind::h89962e53bcbd0469 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panic.rs:140:14 [INFO] [stdout] 35: 0x55c38b95e73b - std::thread::Builder::spawn_unchecked_::{{closure}}::hc53dd1bfbe2c8ac3 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 36: 0x55c38b95e73b - core::ops::function::FnOnce::call_once{{vtable.shim}}::h20d7b1e0bb9c197f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 37: 0x55c38bbe1733 - as core::ops::function::FnOnce>::call_once::hb2ad3b54544c79ff [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 38: 0x55c38bbe1733 - as core::ops::function::FnOnce>::call_once::hb64cd5ea55c83f79 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 39: 0x55c38bbe1733 - std::sys::unix::thread::Thread::new::thread_start::ha585169903024f8f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 40: 0x7fd1c06d9b43 - [INFO] [stdout] 41: 0x7fd1c076abb4 - __clone [INFO] [stdout] 42: 0x0 - [INFO] [stdout] thread 'test_checker' panicked at 'timeout: the function call took 1013 ms. Max time 15000 ms', tests/mod.rs:46:1 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55c38bbda54a - std::backtrace_rs::backtrace::libunwind::trace::he494f4174c7b193d [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 [INFO] [stdout] 1: 0x55c38bbda54a - std::backtrace_rs::backtrace::trace_unsynchronized::h576ffc8be8cf606d [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55c38bbda54a - std::sys_common::backtrace::_print_fmt::h785643da434656fb [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:65:5 [INFO] [stdout] 3: 0x55c38bbda54a - ::fmt::h9b4bec46148aa515 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:44:22 [INFO] [stdout] 4: 0x55c38bbffc2e - core::fmt::write::h9b2447b2a0bad8cd [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/fmt/mod.rs:1213:17 [INFO] [stdout] 5: 0x55c38bbd4a25 - std::io::Write::write_fmt::h01b310af65ec4db4 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/io/mod.rs:1682:15 [INFO] [stdout] 6: 0x55c38bbda315 - std::sys_common::backtrace::_print::hb761e44df96032bf [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:47:5 [INFO] [stdout] 7: 0x55c38bbda315 - std::sys_common::backtrace::print::h38eae7c422ff66f6 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:34:9 [INFO] [stdout] 8: 0x55c38bbdc10f - std::panicking::default_hook::{{closure}}::h54755764573e4b37 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:267:22 [INFO] [stdout] 9: 0x55c38bbdbdcf - std::panicking::default_hook::h978c28978503d61b [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:283:9 [INFO] [stdout] 10: 0x55c38b996547 - as core::ops::function::Fn>::call::hc75c380c644eb7dc [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 11: 0x55c38b996547 - test::test_main::{{closure}}::hf8df6ffaec03f459 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:135:21 [INFO] [stdout] 12: 0x55c38bbdc83a - as core::ops::function::Fn>::call::hf7b5b3aa2a34e491 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:2002:9 [INFO] [stdout] 13: 0x55c38bbdc83a - std::panicking::rust_panic_with_hook::h334ed9b3cc817783 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:692:13 [INFO] [stdout] 14: 0x55c38bbdc5b9 - std::panicking::begin_panic_handler::{{closure}}::h2ab7fcc83540cf23 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:579:13 [INFO] [stdout] 15: 0x55c38bbda9fc - std::sys_common::backtrace::__rust_end_short_backtrace::h65758a09a74021a8 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:137:18 [INFO] [stdout] 16: 0x55c38bbdc2c2 - rust_begin_unwind [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:575:5 [INFO] [stdout] 17: 0x55c38b9575e3 - core::panicking::panic_fmt::h157cca2ea8c38d2f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/panicking.rs:64:14 [INFO] [stdout] 18: 0x55c38b96377b - kind2_tests::test_checker::h509f85277d724da0 [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:46:1 [INFO] [stdout] 19: 0x55c38b96377b - kind2_tests::test_checker::{{closure}}::he119d0dca50bb524 [INFO] [stdout] at /opt/rustwide/workdir/tests/mod.rs:47:22 [INFO] [stdout] 20: 0x55c38b96377b - core::ops::function::FnOnce::call_once::h2475fdaa07a0d342 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 21: 0x55c38b99b9df - core::ops::function::FnOnce::call_once::h01f8fec7cfa52c10 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x55c38b99b9df - test::__rust_begin_short_backtrace::hd9df3824e14a263a [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:656:18 [INFO] [stdout] 23: 0x55c38b96d2fc - test::run_test::{{closure}}::h14eccc3df692cf45 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:647:30 [INFO] [stdout] 24: 0x55c38b96d2fc - core::ops::function::FnOnce::call_once{{vtable.shim}}::h22daa4200e93d413 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x55c38b99a96a - as core::ops::function::FnOnce>::call_once::hf53e6a300c60e24c [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 26: 0x55c38b99a96a - as core::ops::function::FnOnce<()>>::call_once::h6791832c0d80daf4 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 27: 0x55c38b99a96a - std::panicking::try::do_call::h8cc8eae26cdc3fd4 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:483:40 [INFO] [stdout] 28: 0x55c38b99a96a - std::panicking::try::hda02a22cf2e8baa4 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:447:19 [INFO] [stdout] 29: 0x55c38b99a96a - std::panic::catch_unwind::h3eae6cdd2df69f71 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panic.rs:140:14 [INFO] [stdout] 30: 0x55c38b99a96a - test::run_test_in_process::h16fa719e43e0b71b [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:679:27 [INFO] [stdout] 31: 0x55c38b99a96a - test::run_test::run_test_inner::{{closure}}::h17c8526637871e5a [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:573:39 [INFO] [stdout] 32: 0x55c38b967a71 - test::run_test::run_test_inner::{{closure}}::h2af69c9d277fc069 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/test/src/lib.rs:600:37 [INFO] [stdout] 33: 0x55c38b967a71 - std::sys_common::backtrace::__rust_begin_short_backtrace::h399ac55f3162af04 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys_common/backtrace.rs:121:18 [INFO] [stdout] 34: 0x55c38b96d43b - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h5a9b49c4b29a004f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/thread/mod.rs:558:17 [INFO] [stdout] 35: 0x55c38b96d43b - as core::ops::function::FnOnce<()>>::call_once::h204fece3743b973e [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/panic/unwind_safe.rs:271:9 [INFO] [stdout] 36: 0x55c38b96d43b - std::panicking::try::do_call::he9e3e4b585f918a2 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:483:40 [INFO] [stdout] 37: 0x55c38b96d43b - std::panicking::try::h4de93a5845ac1690 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panicking.rs:447:19 [INFO] [stdout] 38: 0x55c38b96d43b - std::panic::catch_unwind::ha9ec01218adeb4c0 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/panic.rs:140:14 [INFO] [stdout] 39: 0x55c38b96d43b - std::thread::Builder::spawn_unchecked_::{{closure}}::h5c8e32d46eb0d8ba [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 40: 0x55c38b96d43b - core::ops::function::FnOnce::call_once{{vtable.shim}}::hbddd861cd79853bf [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 41: 0x55c38bbe1733 - as core::ops::function::FnOnce>::call_once::hb2ad3b54544c79ff [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 42: 0x55c38bbe1733 - as core::ops::function::FnOnce>::call_once::hb64cd5ea55c83f79 [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/alloc/src/boxed.rs:1988:9 [INFO] [stdout] 43: 0x55c38bbe1733 - std::sys::unix::thread::Thread::new::thread_start::ha585169903024f8f [INFO] [stdout] at /rustc/613d1972a772987ed4ee06faccbab0e2fc839871/library/std/src/sys/unix/thread.rs:108:17 [INFO] [stdout] 44: 0x7fd1c06d9b43 - [INFO] [stdout] 45: 0x7fd1c076abb4 - __clone [INFO] [stdout] 46: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test_checker [INFO] [stdout] [INFO] [stdout] test result: FAILED. 3 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.02s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--test kind2-tests` [INFO] running `Command { std: "docker" "inspect" "345dcde7ec972efbb770a1ab8c8951dea2c156d956e47487ed3312d6587ae8a8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "345dcde7ec972efbb770a1ab8c8951dea2c156d956e47487ed3312d6587ae8a8", kill_on_drop: false }` [INFO] [stdout] 345dcde7ec972efbb770a1ab8c8951dea2c156d956e47487ed3312d6587ae8a8