[INFO] cloning repository https://github.com/czeti/asm_tracer
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/czeti/asm_tracer" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fczeti%2Fasm_tracer", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fczeti%2Fasm_tracer'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] b72284ccde19b058c35afd8eeeda38bbaa771403
[INFO] testing czeti/asm_tracer against master#d933cf483edf1605142ac6899ff32536c0ad8b22 for pr-150933
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fczeti%2Fasm_tracer" "/workspace/builds/worker-2-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/czeti/asm_tracer
[INFO] finished tweaking git repo https://github.com/czeti/asm_tracer
[INFO] tweaked toml for git repo https://github.com/czeti/asm_tracer written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/czeti/asm_tracer on toolchain d933cf483edf1605142ac6899ff32536c0ad8b22
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/czeti/asm_tracer 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" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.114
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.114
[INFO] [stderr]   Downloaded resolver v0.1.3
[INFO] [stderr]   Downloaded oorandom v11.1.5
[INFO] [stderr]   Downloaded criterion-plot v0.8.2
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.114
[INFO] [stderr]   Downloaded clap v4.5.60
[INFO] [stderr]   Downloaded unicode-ident v1.0.24
[INFO] [stderr]   Downloaded criterion v0.8.2
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.114
[INFO] [stderr]   Downloaded cc v1.2.56
[INFO] [stderr]   Downloaded zerocopy-derive v0.8.40
[INFO] [stderr]   Downloaded js-sys v0.3.91
[INFO] [stderr]   Downloaded bumpalo v3.20.2
[INFO] [stderr]   Downloaded clap_builder v4.5.60
[INFO] [stderr]   Downloaded zerocopy v0.8.40
[INFO] [stderr]   Downloaded syn v2.0.117
[INFO] [stderr]   Downloaded regex-syntax v0.8.10
[INFO] [stderr]   Downloaded web-sys v0.3.91
[INFO] [stderr]   Downloaded libc v0.2.182
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 582b1b51cbfc895d2699fa5b2890448a62c6faad00a2d37fe474800a2867c2c2
[INFO] running `Command { std: "docker" "start" "-a" "582b1b51cbfc895d2699fa5b2890448a62c6faad00a2d37fe474800a2867c2c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "582b1b51cbfc895d2699fa5b2890448a62c6faad00a2d37fe474800a2867c2c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "582b1b51cbfc895d2699fa5b2890448a62c6faad00a2d37fe474800a2867c2c2", kill_on_drop: false }`
[INFO] [stdout] 582b1b51cbfc895d2699fa5b2890448a62c6faad00a2d37fe474800a2867c2c2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 91c2d956193e1d574ab0e3a394b3e4e71d5b39a1261b7bcf80e5b326a95b6aac
[INFO] running `Command { std: "docker" "start" "-a" "91c2d956193e1d574ab0e3a394b3e4e71d5b39a1261b7bcf80e5b326a95b6aac", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling libc v0.2.182
[INFO] [stderr]    Compiling zmij v1.0.21
[INFO] [stderr]    Compiling zerocopy v0.8.40
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling cc v1.2.56
[INFO] [stderr]    Compiling rayon-core v1.13.0
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling syscall v0.1.0 (/opt/rustwide/workdir/crates/syscall)
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr] warning: syscall@0.1.0: Linked libsyscall.a from /opt/rustwide/target/debug/build/syscall-0bb31fd9cdde126b/out
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling clap_builder v4.5.60
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling page_size v0.6.0
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling alloca v0.4.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling criterion-plot v0.8.2
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling clap v4.5.60
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling zerocopy-derive v0.8.40
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling asm_tracer_core v0.1.0 (/opt/rustwide/workdir/crates/asm_tracer_core)
[INFO] [stderr]    Compiling exec v0.1.0 (/opt/rustwide/workdir/crates/exec)
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling resolver v0.1.3
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion v0.8.2
[INFO] [stderr]    Compiling asm_tracer v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 55.48s
[INFO] running `Command { std: "docker" "inspect" "91c2d956193e1d574ab0e3a394b3e4e71d5b39a1261b7bcf80e5b326a95b6aac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "91c2d956193e1d574ab0e3a394b3e4e71d5b39a1261b7bcf80e5b326a95b6aac", kill_on_drop: false }`
[INFO] [stdout] 91c2d956193e1d574ab0e3a394b3e4e71d5b39a1261b7bcf80e5b326a95b6aac
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 52118d755c0c454d3e164e97b6d591ee94ac40521e50455f5738915631489d5c
[INFO] running `Command { std: "docker" "start" "-a" "52118d755c0c454d3e164e97b6d591ee94ac40521e50455f5738915631489d5c", kill_on_drop: false }`
[INFO] [stderr] warning: syscall@0.1.0: Linked libsyscall.a from /opt/rustwide/target/debug/build/syscall-0bb31fd9cdde126b/out
[INFO] [stderr]    Compiling asm_tracer v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.09s
[INFO] running `Command { std: "docker" "inspect" "52118d755c0c454d3e164e97b6d591ee94ac40521e50455f5738915631489d5c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "52118d755c0c454d3e164e97b6d591ee94ac40521e50455f5738915631489d5c", kill_on_drop: false }`
[INFO] [stdout] 52118d755c0c454d3e164e97b6d591ee94ac40521e50455f5738915631489d5c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 90e683af85036f9439506d52de5b872c73d45e84c3f24753454a7de9300151b6
[INFO] running `Command { std: "docker" "start" "-a" "90e683af85036f9439506d52de5b872c73d45e84c3f24753454a7de9300151b6", kill_on_drop: false }`
[INFO] [stderr] warning: syscall@0.1.0: Linked libsyscall.a from /opt/rustwide/target/debug/build/syscall-0bb31fd9cdde126b/out
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/asm_tracer-e385f71db003c99a)
[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/integration.rs (/opt/rustwide/target/debug/deps/integration-1be8ae7046770e9a)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] hello
[INFO] [stdout] hello
[INFO] [stdout] hello
[INFO] [stdout] test test_memory_read_after_child_death ... ok
[INFO] [stdout] hello
[INFO] [stdout] test test_concurrent_children_mixed ... ok
[INFO] [stdout] hello
[INFO] [stdout] hello
[INFO] [stdout] hello
[INFO] [stdout] hello
[INFO] [stdout] hello
[INFO] [stdout] test test_drain_before_and_after_wait ... FAILED
[INFO] [stdout] test test_hook_panic_does_not_deadlock ... ok
[INFO] [stdout] test test_sequential_children_mixed_syscalls ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_drain_before_and_after_wait stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_drain_before_and_after_wait' (19) panicked at tests/integration.rs:145:5:
[INFO] [stdout] assertion failed: !early.is_empty()
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61755e24a0ca - std[69d51b4813ba037]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61755e24a0ca - std[69d51b4813ba037]::backtrace_rs::backtrace::trace_unsynchronized::<std[69d51b4813ba037]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61755e24a0ca - std[69d51b4813ba037]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61755e24a0ca - <<std[69d51b4813ba037]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[51d4adbef590f61]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61755e25df3a - <core[51d4adbef590f61]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61755e25df3a - core[51d4adbef590f61]::fmt::write
[INFO] [stdout]    6:     0x61755e24e952 - std[69d51b4813ba037]::io::default_write_fmt::<alloc[18203fec9a0cd4ec]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x61755e24e952 - <alloc[18203fec9a0cd4ec]::vec::Vec<u8> as std[69d51b4813ba037]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x61755e22921f - <std[69d51b4813ba037]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61755e22921f - std[69d51b4813ba037]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61755e2425c9 - std[69d51b4813ba037]::panicking::default_hook
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61755e1d5b5c - <alloc[18203fec9a0cd4ec]::boxed::Box<dyn for<'a, 'b> core[51d4adbef590f61]::ops::function::Fn<(&'a std[69d51b4813ba037]::panic::PanicHookInfo<'b>,), Output = ()> + core[51d4adbef590f61]::marker::Sync + core[51d4adbef590f61]::marker::Send> as core[51d4adbef590f61]::ops::function::Fn<(&std[69d51b4813ba037]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x61755e1d5b5c - test[8fe65b9e05bf4c74]::test_main_with_exit_callback::<test[8fe65b9e05bf4c74]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x61755e242782 - <alloc[18203fec9a0cd4ec]::boxed::Box<dyn for<'a, 'b> core[51d4adbef590f61]::ops::function::Fn<(&'a std[69d51b4813ba037]::panic::PanicHookInfo<'b>,), Output = ()> + core[51d4adbef590f61]::marker::Sync + core[51d4adbef590f61]::marker::Send> as core[51d4adbef590f61]::ops::function::Fn<(&std[69d51b4813ba037]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x61755e242782 - std[69d51b4813ba037]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61755e22930a - std[69d51b4813ba037]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x61755e220c79 - std[69d51b4813ba037]::sys::backtrace::__rust_end_short_backtrace::<std[69d51b4813ba037]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61755e229fed - __rustc[cb16bc19fc993bd7]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61755e25e7ec - core[51d4adbef590f61]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61755e25e7b2 - core[51d4adbef590f61]::panicking::panic
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x61755e1b851d - integration[931faa42012ebbe3]::test_drain_before_and_after_wait
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration.rs:145:5
[INFO] [stdout]   21:     0x61755e1b7467 - integration[931faa42012ebbe3]::test_drain_before_and_after_wait::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/integration.rs:129:38
[INFO] [stdout]   22:     0x61755e1b4756 - <integration[931faa42012ebbe3]::test_drain_before_and_after_wait::{closure#0} as core[51d4adbef590f61]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x61755e1c9c3b - <fn() -> core[51d4adbef590f61]::result::Result<(), alloc[18203fec9a0cd4ec]::string::String> as core[51d4adbef590f61]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x61755e1c9c3b - test[8fe65b9e05bf4c74]::__rust_begin_short_backtrace::<core[51d4adbef590f61]::result::Result<(), alloc[18203fec9a0cd4ec]::string::String>, fn() -> core[51d4adbef590f61]::result::Result<(), alloc[18203fec9a0cd4ec]::string::String>>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x61755e1d662b - test[8fe65b9e05bf4c74]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x61755e1d662b - <core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<test[8fe65b9e05bf4c74]::run_test_in_process::{closure#0}> as core[51d4adbef590f61]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x61755e1d662b - std[69d51b4813ba037]::panicking::catch_unwind::do_call::<core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<test[8fe65b9e05bf4c74]::run_test_in_process::{closure#0}>, core[51d4adbef590f61]::result::Result<(), alloc[18203fec9a0cd4ec]::string::String>>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x61755e1d662b - std[69d51b4813ba037]::panicking::catch_unwind::<core[51d4adbef590f61]::result::Result<(), alloc[18203fec9a0cd4ec]::string::String>, core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<test[8fe65b9e05bf4c74]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x61755e1d662b - std[69d51b4813ba037]::panic::catch_unwind::<core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<test[8fe65b9e05bf4c74]::run_test_in_process::{closure#0}>, core[51d4adbef590f61]::result::Result<(), alloc[18203fec9a0cd4ec]::string::String>>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x61755e1d662b - test[8fe65b9e05bf4c74]::run_test_in_process
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x61755e1d662b - test[8fe65b9e05bf4c74]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x61755e1d1d44 - test[8fe65b9e05bf4c74]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x61755e1d1d44 - std[69d51b4813ba037]::sys::backtrace::__rust_begin_short_backtrace::<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x61755e1d9232 - std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked::<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x61755e1d9232 - <core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[51d4adbef590f61]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x61755e1d9232 - std[69d51b4813ba037]::panicking::catch_unwind::do_call::<core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x61755e1d9232 - std[69d51b4813ba037]::panicking::catch_unwind::<(), core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x61755e1d9232 - std[69d51b4813ba037]::panic::catch_unwind::<core[51d4adbef590f61]::panic::unwind_safe::AssertUnwindSafe<std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x61755e1d9232 - std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked::<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x61755e1d9232 - <std[69d51b4813ba037]::thread::lifecycle::spawn_unchecked<test[8fe65b9e05bf4c74]::run_test::{closure#1}, ()>::{closure#1} as core[51d4adbef590f61]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x61755e2499df - <alloc[18203fec9a0cd4ec]::boxed::Box<dyn core[51d4adbef590f61]::ops::function::FnOnce<(), Output = ()> + core[51d4adbef590f61]::marker::Send> as core[51d4adbef590f61]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   42:     0x61755e2499df - <std[69d51b4813ba037]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/d933cf483edf1605142ac6899ff32536c0ad8b22/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   43:     0x76ccb65d2aa4 - <unknown>
[INFO] [stdout]   44:     0x76ccb665fa64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_drain_before_and_after_wait
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 4 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test integration`
[INFO] running `Command { std: "docker" "inspect" "90e683af85036f9439506d52de5b872c73d45e84c3f24753454a7de9300151b6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "90e683af85036f9439506d52de5b872c73d45e84c3f24753454a7de9300151b6", kill_on_drop: false }`
[INFO] [stdout] 90e683af85036f9439506d52de5b872c73d45e84c3f24753454a7de9300151b6