[INFO] cloning repository https://github.com/NavyD/watcher [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/NavyD/watcher" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNavyD%2Fwatcher", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNavyD%2Fwatcher'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 092b99002aa34b0f0a8a463af7cec3511402d7ed [INFO] testing NavyD/watcher against try#7af779037716ae4125ceabb429791b4cf5dd0a43 for pr-136932 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FNavyD%2Fwatcher" "/workspace/builds/worker-4-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/NavyD/watcher on toolchain 7af779037716ae4125ceabb429791b4cf5dd0a43 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+7af779037716ae4125ceabb429791b4cf5dd0a43" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/NavyD/watcher [INFO] finished tweaking git repo https://github.com/NavyD/watcher [INFO] tweaked toml for git repo https://github.com/NavyD/watcher written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] crate git repo https://github.com/NavyD/watcher 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" "+7af779037716ae4125ceabb429791b4cf5dd0a43" "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-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:3959728d45483eb673ba100a951a6b9f5012970368db2a9e08e0ac9b67c8a5ad" "/opt/rustwide/cargo-home/bin/cargo" "+7af779037716ae4125ceabb429791b4cf5dd0a43" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] f9bf7c3dc243513abc42610c24edc3a16c7544d10effba7997e58bfb11a015ad [INFO] running `Command { std: "docker" "start" "-a" "f9bf7c3dc243513abc42610c24edc3a16c7544d10effba7997e58bfb11a015ad", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "f9bf7c3dc243513abc42610c24edc3a16c7544d10effba7997e58bfb11a015ad", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f9bf7c3dc243513abc42610c24edc3a16c7544d10effba7997e58bfb11a015ad", kill_on_drop: false }` [INFO] [stdout] f9bf7c3dc243513abc42610c24edc3a16c7544d10effba7997e58bfb11a015ad [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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:3959728d45483eb673ba100a951a6b9f5012970368db2a9e08e0ac9b67c8a5ad" "/opt/rustwide/cargo-home/bin/cargo" "+7af779037716ae4125ceabb429791b4cf5dd0a43" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3562a983b410f71dbc02abc94e4c247c01c5afe710d7441d70a41de734cc84e2 [INFO] running `Command { std: "docker" "start" "-a" "3562a983b410f71dbc02abc94e4c247c01c5afe710d7441d70a41de734cc84e2", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.66 [INFO] [stderr] Compiling unicode-ident v1.0.11 [INFO] [stderr] Compiling rustix v0.38.6 [INFO] [stderr] Compiling libc v0.2.147 [INFO] [stderr] Compiling linux-raw-sys v0.4.5 [INFO] [stderr] Compiling bitflags v2.3.3 [INFO] [stderr] Compiling heck v0.4.1 [INFO] [stderr] Compiling utf8parse v0.2.1 [INFO] [stderr] Compiling rustversion v1.0.14 [INFO] [stderr] Compiling colorchoice v1.0.0 [INFO] [stderr] Compiling regex-syntax v0.7.4 [INFO] [stderr] Compiling log v0.4.19 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling anstyle-query v1.0.0 [INFO] [stderr] Compiling anstyle v1.0.1 [INFO] [stderr] Compiling aho-corasick v1.0.2 [INFO] [stderr] Compiling anstyle-parse v0.2.1 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling clap_lex v0.5.0 [INFO] [stderr] Compiling once_cell v1.18.0 [INFO] [stderr] Compiling anyhow v1.0.72 [INFO] [stderr] Compiling walkdir v2.3.3 [INFO] [stderr] Compiling bstr v1.6.0 [INFO] [stderr] Compiling termcolor v1.2.0 [INFO] [stderr] Compiling humantime v2.1.0 [INFO] [stderr] Compiling either v1.9.0 [INFO] [stderr] Compiling shlex v1.1.0 [INFO] [stderr] Compiling fake-tty v0.3.1 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling mio v0.8.8 [INFO] [stderr] Compiling filetime v0.2.21 [INFO] [stderr] Compiling quote v1.0.32 [INFO] [stderr] Compiling inotify v0.9.6 [INFO] [stderr] Compiling syn v2.0.28 [INFO] [stderr] Compiling regex-automata v0.3.4 [INFO] [stderr] Compiling notify v5.2.0 [INFO] [stderr] Compiling is-terminal v0.4.9 [INFO] [stderr] Compiling anstream v0.3.2 [INFO] [stderr] Compiling clap_builder v4.3.19 [INFO] [stderr] Compiling strum_macros v0.24.3 [INFO] [stderr] Compiling regex v1.9.1 [INFO] [stderr] Compiling env_logger v0.10.0 [INFO] [stderr] Compiling globset v0.4.12 [INFO] [stderr] Compiling clap_derive v4.3.12 [INFO] [stderr] Compiling strum v0.24.1 [INFO] [stderr] Compiling clap v4.3.19 [INFO] [stderr] Compiling clap_complete v4.3.2 [INFO] [stderr] Compiling watcher v0.5.3 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 16.99s [INFO] running `Command { std: "docker" "inspect" "3562a983b410f71dbc02abc94e4c247c01c5afe710d7441d70a41de734cc84e2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3562a983b410f71dbc02abc94e4c247c01c5afe710d7441d70a41de734cc84e2", kill_on_drop: false }` [INFO] [stdout] 3562a983b410f71dbc02abc94e4c247c01c5afe710d7441d70a41de734cc84e2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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:3959728d45483eb673ba100a951a6b9f5012970368db2a9e08e0ac9b67c8a5ad" "/opt/rustwide/cargo-home/bin/cargo" "+7af779037716ae4125ceabb429791b4cf5dd0a43" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 99d97679cac7587dca3ec4348a64e35d6787fdf770390698fcc02dd20abccd01 [INFO] running `Command { std: "docker" "start" "-a" "99d97679cac7587dca3ec4348a64e35d6787fdf770390698fcc02dd20abccd01", kill_on_drop: false }` [INFO] [stderr] Compiling rustix v0.38.6 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling ppv-lite86 v0.2.17 [INFO] [stderr] Compiling getrandom v0.2.10 [INFO] [stderr] Compiling fastrand v2.0.0 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling is-terminal v0.4.9 [INFO] [stderr] Compiling tempfile v3.7.0 [INFO] [stderr] Compiling anstream v0.3.2 [INFO] [stderr] Compiling env_logger v0.10.0 [INFO] [stderr] Compiling clap_builder v4.3.19 [INFO] [stderr] Compiling clap v4.3.19 [INFO] [stderr] Compiling strum_macros v0.24.3 [INFO] [stderr] Compiling ctor v0.1.26 [INFO] [stderr] Compiling clap_complete v4.3.2 [INFO] [stderr] Compiling strum v0.24.1 [INFO] [stderr] Compiling watcher v0.5.3 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 13.02s [INFO] running `Command { std: "docker" "inspect" "99d97679cac7587dca3ec4348a64e35d6787fdf770390698fcc02dd20abccd01", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "99d97679cac7587dca3ec4348a64e35d6787fdf770390698fcc02dd20abccd01", kill_on_drop: false }` [INFO] [stdout] 99d97679cac7587dca3ec4348a64e35d6787fdf770390698fcc02dd20abccd01 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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" "-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:3959728d45483eb673ba100a951a6b9f5012970368db2a9e08e0ac9b67c8a5ad" "/opt/rustwide/cargo-home/bin/cargo" "+7af779037716ae4125ceabb429791b4cf5dd0a43" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 2a204dc514fc388563743de4b1850e6904fa19647b5e14331e763699ca1519e4 [INFO] running `Command { std: "docker" "start" "-a" "2a204dc514fc388563743de4b1850e6904fa19647b5e14331e763699ca1519e4", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/watcher-5f651e6cd22d867e) [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test tests::test_args ... ok [INFO] [stdout] test tests::test_interested_mock ... ok [INFO] [stdout] test tests::test_interested_excludes ... ok [INFO] [stdout] test tests::test_watcher_iter ... FAILED [INFO] [stdout] test tests::test_single_handler ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::test_watcher_iter stdout ---- [INFO] [stdout] waiting [Access, Modify, Move, Create, Delete] events in interval 1s for recurive 1 paths: ["/tmp/.tmp6GKBAP"] [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher] found Create(Folder) event in ["/tmp/.tmp6GKBAP/a"] has attrs EventAttributes { inner: None } [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher::tests] writing /tmp/.tmp6GKBAP/a/b/c/c.txt for watching with content: Ugyu6ocOM4 [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher::tests] writing /tmp/.tmp6GKBAP/a/b/b.txt for watching with content: DQqQM18d7I [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher::tests] writing /tmp/.tmp6GKBAP/a/a.txt for watching with content: I5qND31KNI [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher] found Create(Folder) event in ["/tmp/.tmp6GKBAP/a/b"] has attrs EventAttributes { inner: None } [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher] found Create(File) event in ["/tmp/.tmp6GKBAP/a/a.txt"] has attrs EventAttributes { inner: None } [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher] found Modify(Data(Any)) event in ["/tmp/.tmp6GKBAP/a/a.txt"] has attrs EventAttributes { inner: None } [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher] found Access(Close(Write)) event in ["/tmp/.tmp6GKBAP/a/a.txt"] has attrs EventAttributes { inner: None } [INFO] [stdout] [2025-02-12T23:27:45Z INFO watcher] close the iter for disconnected channel [INFO] [stdout] [2025-02-12T23:27:45Z INFO watcher::tests] received 6 events: [Some(EventInfo { event: Create, paths: ["/tmp/.tmp6GKBAP/a"] }), Some(EventInfo { event: Create, paths: ["/tmp/.tmp6GKBAP/a/b"] }), Some(EventInfo { event: Create, paths: ["/tmp/.tmp6GKBAP/a/a.txt"] }), Some(EventInfo { event: Modify, paths: ["/tmp/.tmp6GKBAP/a/a.txt"] }), Some(EventInfo { event: Access, paths: ["/tmp/.tmp6GKBAP/a/a.txt"] }), None] [INFO] [stdout] [INFO] [stdout] thread 'tests::test_watcher_iter' panicked at src/main.rs:657:9: [INFO] [stdout] assertion failed: tmp_paths.len() * 2 < events.len() [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5ca914f45e1f - std::backtrace_rs::backtrace::libunwind::trace::h273cca4b4ff52a75 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x5ca914f45e1f - std::backtrace_rs::backtrace::trace_unsynchronized::h0dcaf4eaddc63f4d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x5ca914f45e1f - std::sys::backtrace::_print_fmt::h99227612ab693c91 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5ca914f45e1f - ::fmt::hd0fb9a24f673249b [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5ca914f70563 - core::fmt::rt::Argument::fmt::h3790bd73ff49839a [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/fmt/rt.rs:184:76 [INFO] [stdout] 5: 0x5ca914f70563 - core::fmt::write::hf7b505e27c2f59c0 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/fmt/mod.rs:1449:21 [INFO] [stdout] 6: 0x5ca914f422b3 - std::io::Write::write_fmt::hb002ef36900cedb4 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/io/mod.rs:1890:15 [INFO] [stdout] 7: 0x5ca914f45c72 - std::sys::backtrace::BacktraceLock::print::h5fa244ae2c1a02f2 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x5ca914f4724f - std::panicking::default_hook::{{closure}}::hd977fe155ed94062 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:298:22 [INFO] [stdout] 9: 0x5ca914f47068 - std::panicking::default_hook::hf1aee4d1a1a53629 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:322:9 [INFO] [stdout] 10: 0x5ca914b2dfd5 - as core::ops::function::Fn>::call::h863d5366fd8956b9 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 11: 0x5ca914b2dfd5 - test::test_main::{{closure}}::h069467131d9eccce [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:135:21 [INFO] [stdout] 12: 0x5ca914f47d43 - as core::ops::function::Fn>::call::h4d850f4eb7d2da54 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 13: 0x5ca914f47d43 - std::panicking::rust_panic_with_hook::h8442779a6bb2c045 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:839:13 [INFO] [stdout] 14: 0x5ca914f479c6 - std::panicking::begin_panic_handler::{{closure}}::h5fc7eacd91180541 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:697:13 [INFO] [stdout] 15: 0x5ca914f46339 - std::sys::backtrace::__rust_end_short_backtrace::h83d61fb88e4c1551 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:168:18 [INFO] [stdout] 16: 0x5ca914f4768d - rust_begin_unwind [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:695:5 [INFO] [stdout] 17: 0x5ca914f6ec10 - core::panicking::panic_fmt::hcb1ec5c71ed9d2aa [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panicking.rs:75:14 [INFO] [stdout] 18: 0x5ca914f6ec9c - core::panicking::panic::h34d63eab7a5b2b25 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panicking.rs:145:5 [INFO] [stdout] 19: 0x5ca914aa40fe - watcher::tests::test_watcher_iter::h938fc7a4700b67ba [INFO] [stdout] at /opt/rustwide/workdir/src/main.rs:657:9 [INFO] [stdout] 20: 0x5ca914aa3787 - watcher::tests::test_watcher_iter::{{closure}}::h3592cf26effae662 [INFO] [stdout] at /opt/rustwide/workdir/src/main.rs:624:31 [INFO] [stdout] 21: 0x5ca914a7b6f6 - core::ops::function::FnOnce::call_once::he34055a3cf88e177 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x5ca914b337eb - core::ops::function::FnOnce::call_once::h4f53344eaef367a7 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x5ca914b337eb - test::__rust_begin_short_backtrace::hd74e5b45349ae0eb [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:637:18 [INFO] [stdout] 24: 0x5ca914b32648 - test::run_test_in_process::{{closure}}::h464496cd0c1c7008 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:660:60 [INFO] [stdout] 25: 0x5ca914b32648 - as core::ops::function::FnOnce<()>>::call_once::h783dbf339886dbb5 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x5ca914b32648 - std::panicking::try::do_call::h939d38eaf9984278 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:587:40 [INFO] [stdout] 27: 0x5ca914b32648 - std::panicking::try::h7462749e6e425b58 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:550:19 [INFO] [stdout] 28: 0x5ca914b32648 - std::panic::catch_unwind::hd7e0994ef35a8a51 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panic.rs:358:14 [INFO] [stdout] 29: 0x5ca914b32648 - test::run_test_in_process::hf0a6730e4eaf615d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:660:27 [INFO] [stdout] 30: 0x5ca914b32648 - test::run_test::{{closure}}::h6563d57083dce3d5 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:581:43 [INFO] [stdout] 31: 0x5ca914af55c5 - test::run_test::{{closure}}::hd77e8481970892f0 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:611:41 [INFO] [stdout] 32: 0x5ca914af55c5 - std::sys::backtrace::__rust_begin_short_backtrace::h3affc308abe149f2 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:152:18 [INFO] [stdout] 33: 0x5ca914af8f6a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h0bcb7f1fc989ad90 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 34: 0x5ca914af8f6a - as core::ops::function::FnOnce<()>>::call_once::h7f1d3c81251e8f39 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x5ca914af8f6a - std::panicking::try::do_call::ha04f8c9df155cd96 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:587:40 [INFO] [stdout] 36: 0x5ca914af8f6a - std::panicking::try::hbac6309ffcaaa153 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:550:19 [INFO] [stdout] 37: 0x5ca914af8f6a - std::panic::catch_unwind::h05df0eec2faa6d0d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panic.rs:358:14 [INFO] [stdout] 38: 0x5ca914af8f6a - std::thread::Builder::spawn_unchecked_::{{closure}}::h0931fb094701c63d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 39: 0x5ca914af8f6a - core::ops::function::FnOnce::call_once{{vtable.shim}}::h58033be664b55387 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x5ca914f4ccdb - as core::ops::function::FnOnce>::call_once::hc2b66a72ea953e16 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x5ca914f4ccdb - as core::ops::function::FnOnce>::call_once::h64ac890f18adb1a4 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x5ca914f4ccdb - std::sys::pal::unix::thread::Thread::new::thread_start::h8744afc3d48ab12e [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/pal/unix/thread.rs:106:17 [INFO] [stdout] 43: 0x79f22ade1ac3 - [INFO] [stdout] 44: 0x79f22ae72a04 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- tests::test_single_handler stdout ---- [INFO] [stdout] [2025-02-12T23:27:44Z INFO watcher::tests] sending event None [INFO] [stdout] [2025-02-12T23:27:44Z TRACE watcher] checking timeout last info `None` for child process none [INFO] [stdout] [2025-02-12T23:27:44Z INFO watcher::tests] sending event Some(EventInfo { event: Create, paths: ["a.txt"] }) [INFO] [stdout] [2025-02-12T23:27:44Z INFO watcher] found new event create in path ["a.txt"] [INFO] [stdout] [2025-02-12T23:27:44Z DEBUG watcher] waiting event info EventInfo { event: Create, paths: ["a.txt"] } for next timeout [INFO] [stdout] [2025-02-12T23:27:45Z INFO watcher::tests] sending event Some(EventInfo { event: Create, paths: ["b.txt"] }) [INFO] [stdout] [2025-02-12T23:27:45Z INFO watcher] found new event create in path ["b.txt"] [INFO] [stdout] [2025-02-12T23:27:45Z DEBUG watcher] waiting event info EventInfo { event: Create, paths: ["b.txt"] } for next timeout [INFO] [stdout] [2025-02-12T23:27:45Z INFO watcher::tests] sending event None [INFO] [stdout] [2025-02-12T23:27:45Z TRACE watcher] checking timeout last info `Some(EventInfo { event: Create, paths: ["b.txt"] })` for child process none [INFO] [stdout] [2025-02-12T23:27:45Z DEBUG watcher] spawning new process with args: ProcessArgs { tty: false, send_stdin: true, command: "false" } [INFO] [stdout] starting new process by command: `false` [INFO] [stdout] [2025-02-12T23:27:45Z TRACE watcher] executing command `false` with args: ["false"] [INFO] [stdout] [2025-02-12T23:27:45Z TRACE watcher] Writing info `EventInfo { event: Create, paths: ["b.txt"] }` to be formatted to the stdin of process 17 [INFO] [stdout] [2025-02-12T23:27:46Z INFO watcher::tests] sending event None [INFO] [stdout] [2025-02-12T23:27:46Z TRACE watcher] checking timeout last info `None` for child process 17 [INFO] [stdout] [2025-02-12T23:27:46Z TRACE watcher] found exited process 17 with status: Some(1) [INFO] [stdout] [2025-02-12T23:27:46Z ERROR watcher] failed to run command `false` with status 1 [INFO] [stdout] [2025-02-12T23:27:46Z INFO watcher::tests] sending event Some(EventInfo { event: Create, paths: ["c.txt"] }) [INFO] [stdout] [2025-02-12T23:27:46Z INFO watcher] found new event create in path ["c.txt"] [INFO] [stdout] [2025-02-12T23:27:46Z DEBUG watcher] waiting event info EventInfo { event: Create, paths: ["c.txt"] } for next timeout [INFO] [stdout] [2025-02-12T23:27:47Z INFO watcher::tests] sending event None [INFO] [stdout] [2025-02-12T23:27:47Z TRACE watcher] checking timeout last info `Some(EventInfo { event: Create, paths: ["c.txt"] })` for child process none [INFO] [stdout] [2025-02-12T23:27:47Z DEBUG watcher] spawning new process with args: ProcessArgs { tty: false, send_stdin: true, command: "false" } [INFO] [stdout] starting new process by command: `false` [INFO] [stdout] [2025-02-12T23:27:47Z TRACE watcher] executing command `false` with args: ["false"] [INFO] [stdout] [2025-02-12T23:27:47Z TRACE watcher] Writing info `EventInfo { event: Create, paths: ["c.txt"] }` to be formatted to the stdin of process 18 [INFO] [stdout] [2025-02-12T23:27:47Z INFO watcher::tests] sending event Some(EventInfo { event: Create, paths: ["d.txt"] }) [INFO] [stdout] [2025-02-12T23:27:47Z INFO watcher] found new event create in path ["d.txt"] [INFO] [stdout] [2025-02-12T23:27:47Z ERROR watcher] failed to run process 18 with command `false` exit code 1 [INFO] [stdout] [2025-02-12T23:27:47Z DEBUG watcher] cleaning exited status Some(1) for process 18 [INFO] [stdout] [2025-02-12T23:27:47Z DEBUG watcher] waiting event info EventInfo { event: Create, paths: ["d.txt"] } for next timeout [INFO] [stdout] [2025-02-12T23:27:48Z INFO watcher::tests] sending event None [INFO] [stdout] [2025-02-12T23:27:48Z TRACE watcher] checking timeout last info `Some(EventInfo { event: Create, paths: ["d.txt"] })` for child process none [INFO] [stdout] [2025-02-12T23:27:48Z DEBUG watcher] spawning new process with args: ProcessArgs { tty: false, send_stdin: true, command: "false" } [INFO] [stdout] starting new process by command: `false` [INFO] [stdout] [2025-02-12T23:27:48Z TRACE watcher] executing command `false` with args: ["false"] [INFO] [stdout] [2025-02-12T23:27:48Z TRACE watcher] Writing info `EventInfo { event: Create, paths: ["d.txt"] }` to be formatted to the stdin of process 19 [INFO] [stdout] [2025-02-12T23:27:48Z DEBUG watcher::tests] handler result: Err(Broken pipe (os error 32)) [INFO] [stdout] [INFO] [stdout] thread 'tests::test_single_handler' panicked at src/main.rs:718:9: [INFO] [stdout] assertion failed: res.unwrap_err().to_string().contains("events hang up") [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5ca914f45e1f - std::backtrace_rs::backtrace::libunwind::trace::h273cca4b4ff52a75 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5 [INFO] [stdout] 1: 0x5ca914f45e1f - std::backtrace_rs::backtrace::trace_unsynchronized::h0dcaf4eaddc63f4d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x5ca914f45e1f - std::sys::backtrace::_print_fmt::h99227612ab693c91 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5ca914f45e1f - ::fmt::hd0fb9a24f673249b [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5ca914f70563 - core::fmt::rt::Argument::fmt::h3790bd73ff49839a [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/fmt/rt.rs:184:76 [INFO] [stdout] 5: 0x5ca914f70563 - core::fmt::write::hf7b505e27c2f59c0 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/fmt/mod.rs:1449:21 [INFO] [stdout] 6: 0x5ca914f422b3 - std::io::Write::write_fmt::hb002ef36900cedb4 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/io/mod.rs:1890:15 [INFO] [stdout] 7: 0x5ca914f45c72 - std::sys::backtrace::BacktraceLock::print::h5fa244ae2c1a02f2 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 8: 0x5ca914f4724f - std::panicking::default_hook::{{closure}}::hd977fe155ed94062 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:298:22 [INFO] [stdout] 9: 0x5ca914f47068 - std::panicking::default_hook::hf1aee4d1a1a53629 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:322:9 [INFO] [stdout] 10: 0x5ca914b2dfd5 - as core::ops::function::Fn>::call::h863d5366fd8956b9 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 11: 0x5ca914b2dfd5 - test::test_main::{{closure}}::h069467131d9eccce [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:135:21 [INFO] [stdout] 12: 0x5ca914f47d43 - as core::ops::function::Fn>::call::h4d850f4eb7d2da54 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2030:9 [INFO] [stdout] 13: 0x5ca914f47d43 - std::panicking::rust_panic_with_hook::h8442779a6bb2c045 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:839:13 [INFO] [stdout] 14: 0x5ca914f479c6 - std::panicking::begin_panic_handler::{{closure}}::h5fc7eacd91180541 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:697:13 [INFO] [stdout] 15: 0x5ca914f46339 - std::sys::backtrace::__rust_end_short_backtrace::h83d61fb88e4c1551 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:168:18 [INFO] [stdout] 16: 0x5ca914f4768d - rust_begin_unwind [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:695:5 [INFO] [stdout] 17: 0x5ca914f6ec10 - core::panicking::panic_fmt::hcb1ec5c71ed9d2aa [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panicking.rs:75:14 [INFO] [stdout] 18: 0x5ca914f6ec9c - core::panicking::panic::h34d63eab7a5b2b25 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panicking.rs:145:5 [INFO] [stdout] 19: 0x5ca914aa53bc - watcher::tests::test_single_handler::hd06ee7c06fe2efb7 [INFO] [stdout] at /opt/rustwide/workdir/src/main.rs:718:9 [INFO] [stdout] 20: 0x5ca914aa4727 - watcher::tests::test_single_handler::{{closure}}::ha196ed1876c87741 [INFO] [stdout] at /opt/rustwide/workdir/src/main.rs:669:33 [INFO] [stdout] 21: 0x5ca914a7b306 - core::ops::function::FnOnce::call_once::h77ef3ce6d68e0a5d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 22: 0x5ca914b337eb - core::ops::function::FnOnce::call_once::h4f53344eaef367a7 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 23: 0x5ca914b337eb - test::__rust_begin_short_backtrace::hd74e5b45349ae0eb [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:637:18 [INFO] [stdout] 24: 0x5ca914b32648 - test::run_test_in_process::{{closure}}::h464496cd0c1c7008 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:660:60 [INFO] [stdout] 25: 0x5ca914b32648 - as core::ops::function::FnOnce<()>>::call_once::h783dbf339886dbb5 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x5ca914b32648 - std::panicking::try::do_call::h939d38eaf9984278 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:587:40 [INFO] [stdout] 27: 0x5ca914b32648 - std::panicking::try::h7462749e6e425b58 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:550:19 [INFO] [stdout] 28: 0x5ca914b32648 - std::panic::catch_unwind::hd7e0994ef35a8a51 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panic.rs:358:14 [INFO] [stdout] 29: 0x5ca914b32648 - test::run_test_in_process::hf0a6730e4eaf615d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:660:27 [INFO] [stdout] 30: 0x5ca914b32648 - test::run_test::{{closure}}::h6563d57083dce3d5 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:581:43 [INFO] [stdout] 31: 0x5ca914af55c5 - test::run_test::{{closure}}::hd77e8481970892f0 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/test/src/lib.rs:611:41 [INFO] [stdout] 32: 0x5ca914af55c5 - std::sys::backtrace::__rust_begin_short_backtrace::h3affc308abe149f2 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/backtrace.rs:152:18 [INFO] [stdout] 33: 0x5ca914af8f6a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h0bcb7f1fc989ad90 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 34: 0x5ca914af8f6a - as core::ops::function::FnOnce<()>>::call_once::h7f1d3c81251e8f39 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x5ca914af8f6a - std::panicking::try::do_call::ha04f8c9df155cd96 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:587:40 [INFO] [stdout] 36: 0x5ca914af8f6a - std::panicking::try::hbac6309ffcaaa153 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panicking.rs:550:19 [INFO] [stdout] 37: 0x5ca914af8f6a - std::panic::catch_unwind::h05df0eec2faa6d0d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/panic.rs:358:14 [INFO] [stdout] 38: 0x5ca914af8f6a - std::thread::Builder::spawn_unchecked_::{{closure}}::h0931fb094701c63d [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 39: 0x5ca914af8f6a - core::ops::function::FnOnce::call_once{{vtable.shim}}::h58033be664b55387 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 40: 0x5ca914f4ccdb - as core::ops::function::FnOnce>::call_once::hc2b66a72ea953e16 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 41: 0x5ca914f4ccdb - as core::ops::function::FnOnce>::call_once::h64ac890f18adb1a4 [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/alloc/src/boxed.rs:2016:9 [INFO] [stdout] 42: 0x5ca914f4ccdb - std::sys::pal::unix::thread::Thread::new::thread_start::h8744afc3d48ab12e [INFO] [stdout] at /rustc/7af779037716ae4125ceabb429791b4cf5dd0a43/library/std/src/sys/pal/unix/thread.rs:106:17 [INFO] [stdout] 43: 0x79f22ade1ac3 - [INFO] [stdout] 44: 0x79f22ae72a04 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::test_single_handler [INFO] [stdout] tests::test_watcher_iter [INFO] [stdout] [INFO] [stdout] test result: FAILED. 3 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.01s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--bin watcher` [INFO] running `Command { std: "docker" "inspect" "2a204dc514fc388563743de4b1850e6904fa19647b5e14331e763699ca1519e4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2a204dc514fc388563743de4b1850e6904fa19647b5e14331e763699ca1519e4", kill_on_drop: false }` [INFO] [stdout] 2a204dc514fc388563743de4b1850e6904fa19647b5e14331e763699ca1519e4