[INFO] fetching crate gitwatch-rs 0.1.1... [INFO] testing gitwatch-rs-0.1.1 against beta-2025-09-21 for beta-1.91-2 [INFO] extracting crate gitwatch-rs 0.1.1 into /workspace/builds/worker-6-tc2/source [INFO] removed /workspace/builds/worker-6-tc2/source/.cargo/config.toml [INFO] removed /workspace/builds/worker-6-tc2/source/rust-toolchain.toml [INFO] started tweaking crates.io crate gitwatch-rs 0.1.1 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate gitwatch-rs 0.1.1 [INFO] tweaked toml for crates.io crate gitwatch-rs 0.1.1 written to /workspace/builds/worker-6-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate gitwatch-rs 0.1.1 on toolchain beta-2025-09-21 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-09-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate gitwatch-rs 0.1.1 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" "+beta-2025-09-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-09-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 6fcec314de351f0654d9bd4b17afa3b33efa1c7bd45b0d8137fd4c378dcd68ca [INFO] running `Command { std: "docker" "start" "-a" "6fcec314de351f0654d9bd4b17afa3b33efa1c7bd45b0d8137fd4c378dcd68ca", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "6fcec314de351f0654d9bd4b17afa3b33efa1c7bd45b0d8137fd4c378dcd68ca", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6fcec314de351f0654d9bd4b17afa3b33efa1c7bd45b0d8137fd4c378dcd68ca", kill_on_drop: false }` [INFO] [stdout] 6fcec314de351f0654d9bd4b17afa3b33efa1c7bd45b0d8137fd4c378dcd68ca [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-09-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6b0e507f8405d0b7f4f7b96c5aee271085b2e884a5f9f313d7074f2ab4e3ee40 [INFO] running `Command { std: "docker" "start" "-a" "6b0e507f8405d0b7f4f7b96c5aee271085b2e884a5f9f313d7074f2ab4e3ee40", kill_on_drop: false }` [INFO] [stderr] Compiling syn v2.0.104 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling proc-macro2-diagnostics v0.10.1 [INFO] [stderr] Compiling percent-encoding v2.3.1 [INFO] [stderr] Compiling jobserver v0.1.33 [INFO] [stderr] Compiling dirs-sys v0.5.0 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling uncased v0.9.10 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling clap_builder v4.5.42 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling figment v0.10.19 [INFO] [stderr] Compiling bstr v1.12.0 [INFO] [stderr] Compiling inlinable_string v0.1.15 [INFO] [stderr] Compiling notify-types v2.0.0 [INFO] [stderr] Compiling terminal-prompt v0.2.3 [INFO] [stderr] Compiling inotify v0.11.0 [INFO] [stderr] Compiling form_urlencoded v1.2.1 [INFO] [stderr] Compiling dirs v6.0.0 [INFO] [stderr] Compiling cc v1.2.29 [INFO] [stderr] Compiling file-id v0.2.2 [INFO] [stderr] Compiling shellexpand v3.1.1 [INFO] [stderr] Compiling fern v0.7.1 [INFO] [stderr] Compiling paris v1.5.15 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling notify v8.1.0 [INFO] [stderr] Compiling notify-debouncer-full v0.5.0 [INFO] [stderr] Compiling libz-sys v1.1.22 [INFO] [stderr] Compiling openssl-sys v0.9.109 [INFO] [stderr] Compiling libssh2-sys v0.3.1 [INFO] [stderr] Compiling libgit2-sys v0.18.2+1.9.1 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling synstructure v0.13.2 [INFO] [stderr] Compiling zerofrom-derive v0.1.6 [INFO] [stderr] Compiling yoke-derive v0.8.0 [INFO] [stderr] Compiling zerovec-derive v0.11.1 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling clap_derive v4.5.41 [INFO] [stderr] Compiling pear_codegen v0.2.9 [INFO] [stderr] Compiling globset v0.4.16 [INFO] [stderr] Compiling pear v0.2.9 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling yoke v0.8.0 [INFO] [stderr] Compiling zerotrie v0.2.2 [INFO] [stderr] Compiling ignore v0.4.23 [INFO] [stderr] Compiling zerovec v0.11.2 [INFO] [stderr] Compiling clap v4.5.42 [INFO] [stderr] Compiling clap_complete v4.5.55 [INFO] [stderr] Compiling tinystr v0.8.1 [INFO] [stderr] Compiling potential_utf v0.1.2 [INFO] [stderr] Compiling icu_locale_core v2.0.0 [INFO] [stderr] Compiling icu_collections v2.0.0 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling icu_provider v2.0.0 [INFO] [stderr] Compiling icu_normalizer v2.0.0 [INFO] [stderr] Compiling icu_properties v2.0.1 [INFO] [stderr] Compiling idna_adapter v1.2.1 [INFO] [stderr] Compiling idna v1.0.3 [INFO] [stderr] Compiling url v2.5.4 [INFO] [stderr] Compiling git2 v0.20.2 [INFO] [stderr] Compiling serde_yaml v0.9.34+deprecated [INFO] [stderr] Compiling serde_regex v1.1.0 [INFO] [stderr] Compiling auth-git2 v0.5.8 [INFO] [stderr] Compiling gitwatch-rs v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 57.55s [INFO] running `Command { std: "docker" "inspect" "6b0e507f8405d0b7f4f7b96c5aee271085b2e884a5f9f313d7074f2ab4e3ee40", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6b0e507f8405d0b7f4f7b96c5aee271085b2e884a5f9f313d7074f2ab4e3ee40", kill_on_drop: false }` [INFO] [stdout] 6b0e507f8405d0b7f4f7b96c5aee271085b2e884a5f9f313d7074f2ab4e3ee40 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-09-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c2c11bce6f838c48f4547db7191e1218153cc2eda284d09899e926b6d355981a [INFO] running `Command { std: "docker" "start" "-a" "c2c11bce6f838c48f4547db7191e1218153cc2eda284d09899e926b6d355981a", kill_on_drop: false }` [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling inotify v0.11.0 [INFO] [stderr] Compiling predicates-core v1.0.9 [INFO] [stderr] Compiling float-cmp v0.10.0 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling normalize-line-endings v0.3.0 [INFO] [stderr] Compiling rustix v1.0.7 [INFO] [stderr] Compiling termtree v0.5.1 [INFO] [stderr] Compiling assert_cmd v2.0.17 [INFO] [stderr] Compiling wait-timeout v0.2.1 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling testresult v0.4.1 [INFO] [stderr] Compiling git2 v0.20.2 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling predicates-tree v1.0.12 [INFO] [stderr] Compiling notify v8.1.0 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling notify-debouncer-full v0.5.0 [INFO] [stderr] Compiling tempfile v3.20.0 [INFO] [stderr] Compiling bstr v1.12.0 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling globset v0.4.16 [INFO] [stderr] Compiling serde_regex v1.1.0 [INFO] [stderr] Compiling predicates v3.1.3 [INFO] [stderr] Compiling ignore v0.4.23 [INFO] [stderr] Compiling auth-git2 v0.5.8 [INFO] [stderr] Compiling gitwatch-rs v0.1.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 15.20s [INFO] running `Command { std: "docker" "inspect" "c2c11bce6f838c48f4547db7191e1218153cc2eda284d09899e926b6d355981a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c2c11bce6f838c48f4547db7191e1218153cc2eda284d09899e926b6d355981a", kill_on_drop: false }` [INFO] [stdout] c2c11bce6f838c48f4547db7191e1218153cc2eda284d09899e926b6d355981a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-09-21" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 2457484e00a6a00cca947cdef4e1af7dc634c5431fb5742ebafbf6a382322fd5 [INFO] running `Command { std: "docker" "start" "-a" "2457484e00a6a00cca947cdef4e1af7dc634c5431fb5742ebafbf6a382322fd5", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/gitwatch_rs-c25e22bbf01655aa) [INFO] [stdout] [INFO] [stdout] running 34 tests [INFO] [stdout] test cli::tests::test_loglevel_conversion ... ok [INFO] [stdout] test commit_message::tests::test_nonexistent_script ... ok [INFO] [stdout] test app_config::tests::test_absolute_paths ... ok [INFO] [stdout] test config_file::tests::test_load_empty_config ... ok [INFO] [stdout] test app_config::tests::test_config_from_cli_invalid ... ok [INFO] [stdout] test app_config::tests::test_relative_paths ... ok [INFO] [stdout] test app_config::tests::test_config_validation ... ok [INFO] [stdout] test config_file::tests::test_load_invalid_config ... ok [INFO] [stderr] [debug] Path /tmp/.tmp89vPPu/test.ignored ignored via .gitignore [INFO] [stdout] test filter::tests::test_path_absolute_error ... ok [INFO] [stderr] [debug] Path /tmp/.tmp89vPPu/ignored_dir/file.txt ignored via .gitignore [INFO] [stdout] test app_config::tests::test_config_precedence_cli_only ... ok [INFO] [stderr] [debug] Using git repository '/tmp/.tmpLlKbOk' [INFO] [stdout] test app_config::tests::test_config_partial_file ... ok [INFO] [stdout] test filter::tests::test_strip_prefix_error ... ok [INFO] [stderr] [debug] Path test.temp ignored via --ignore-regex [INFO] [stderr] [debug] Path subdir/another.temp ignored via --ignore-regex [INFO] [stderr] [trace] Received notify events { events [DebouncedEvent { event: Event { kind: Any, paths: ["test1.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 58679699 } }, DebouncedEvent { event: Event { kind: Any, paths: ["test1.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 58680439 } }, DebouncedEvent { event: Event { kind: Any, paths: ["test1.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 58680699 } }] } [INFO] [stdout] test filter::tests::test_gitignore ... ok [INFO] [stdout] test config_file::tests::test_load_config_file ... ok [INFO] [stdout] test filter::tests::test_ignore_regex ... ok [INFO] [stdout] test watcher::tests::test_all_paths_ignored ... ok [INFO] [stdout] test util::tests::test_normalize_path ... ok [INFO] [stderr] [trace] registering event source with poller: token=Token(0), interests=READABLE [INFO] [stderr] [trace] registering event source with poller: token=Token(0), interests=READABLE [INFO] [stdout] test app_config::tests::test_config_precedence_with_file ... ok [INFO] [stderr] [trace] adding inotify watch: /tmp/.tmppiROfi [INFO] [stdout] Watching for changes... [INFO] [stdout] test app_config::tests::test_config_from_cli ... ok [INFO] [stderr] [trace] registering event source with poller: token=Token(0), interests=READABLE [INFO] [stderr] [trace] adding inotify watch: /tmp/.tmpVGfUme [INFO] [stdout] test watcher::tests::test_watcher_notify_error ... ok [INFO] [stdout] Watching for changes... [INFO] [stdout] test repo::tests::test_empty_repo ... ok [INFO] [stderr] [debug] Using git repository '/tmp/.tmp0YcueM' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpsROToS' [INFO] [stderr] [debug] Repository has uncommitted changes) [INFO] [stderr] [debug] Modified files: [INFO] [stderr] [debug] foo.txt [INFO] [stderr] [warn] Watch is disabled [INFO] [stderr] [debug] Repository has uncommitted changes) [INFO] [stderr] [debug] Modified files: [INFO] [stderr] [debug] foo.txt [INFO] [stdout] test app::tests::test_watch_false ... ok [INFO] [stdout] test app::tests::test_commit_on_start ... ok [INFO] [stderr] [debug] Using git repository '/tmp/.tmpqNWYoe' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpsbkS8q' [INFO] [stderr] [debug] Index is empty [INFO] [stderr] [debug] Using git repository '/tmp/.tmp5Ybda7' [INFO] [stdout] test repo::tests::test_invalid_remote ... ok [INFO] [stdout] test repo::tests::test_invalid_commit_message_script ... ok [INFO] [stderr] [debug] Pushing to remote origin [INFO] [stderr] [debug] Using git repository '/tmp/.tmpmvMARp' [INFO] [stderr] [trace] Pushing refspec: HEAD:refs/heads/master [INFO] [stdout] test repo::tests::test_commit_and_push ... ok [INFO] [stdout] test repo::tests::test_ignore_regex ... ok [INFO] [stderr] [debug] Executing commit message script '/tmp/.tmpwxGqz2/test_script.sh' [INFO] [stderr] [debug] Executing commit message script '/tmp/.tmpQdzMwO/test_script.sh' [INFO] [stderr] [debug] Executing commit message script '/tmp/.tmp4h8EoC/test_script.sh' [INFO] [stderr] [debug] Executing commit message script '/tmp/.tmpSEwJLi/test_script.sh' [INFO] [stderr] [debug] Executing commit message script '/tmp/.tmppYllip/test_script.sh' [INFO] [stderr] [debug] Generated commit message: 'Test commit message' [INFO] [stdout] test commit_message::tests::test_successful_script_execution ... ok [INFO] [stdout] test commit_message::tests::test_whitespace_only_output ... ok [INFO] [stderr] [debug] Generated commit message: '/tmp/.tmpSEwJLi' [INFO] [stdout] test commit_message::tests::test_empty_output ... ok [INFO] [stdout] test commit_message::tests::test_working_directory ... ok [INFO] [stdout] test commit_message::tests::test_script_execution_failure ... ok [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(CREATE), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Create(File), paths: ["/tmp/.tmppiROfi/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(OPEN), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Access(Open(Any)), paths: ["/tmp/.tmppiROfi/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [trace] Received notify events { events [DebouncedEvent { event: Event { kind: Create(File), paths: ["/tmp/.tmppiROfi/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 159643943 } }] } [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(MODIFY), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Modify(Data(Any)), paths: ["/tmp/.tmppiROfi/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [warn] Failed to commit changes. Retrying... (1/2). [INFO] [stderr] Error: Mock callback error [INFO] [stderr] [INFO] [stderr] Stack backtrace: [INFO] [stderr] 0: anyhow::error::::msg [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/src/backtrace.rs:27:14 [INFO] [stderr] 1: anyhow::__private::format_err [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/src/lib.rs:694:13 [INFO] [stderr] 2: gitwatch_rs::watcher::tests::test_watcher_callback_error::{{closure}}::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:178:21 [INFO] [stderr] 3: core::ops::function::impls:: for &F>::call [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/core/src/ops/function.rs:263:22 [INFO] [stderr] 4: gitwatch_rs::watcher::FileWatcher::handle_events [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:96:23 [INFO] [stderr] 5: gitwatch_rs::watcher::FileWatcher::watch [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:60:46 [INFO] [stderr] 6: gitwatch_rs::watcher::tests::test_watcher_callback_error::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:174:21 [INFO] [stderr] 7: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/sys/backtrace.rs:158:18 [INFO] [stderr] 8: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/thread/mod.rs:559:17 [INFO] [stderr] 9: as core::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stderr] 10: std::panicking::catch_unwind::do_call [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/panicking.rs:590:40 [INFO] [stderr] 11: __rust_try [INFO] [stderr] 12: std::panicking::catch_unwind [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/panicking.rs:553:19 [INFO] [stderr] 13: std::panic::catch_unwind [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/panic.rs:359:14 [INFO] [stderr] 14: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/thread/mod.rs:557:30 [INFO] [stderr] 15: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/core/src/ops/function.rs:250:5 [INFO] [stderr] 16: as core::ops::function::FnOnce>::call_once [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/alloc/src/boxed.rs:1985:9 [INFO] [stderr] 17: std::sys::thread::unix::Thread::new::thread_start [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/sys/thread/unix.rs:126:17 [INFO] [stderr] 18: [INFO] [stderr] 19: clone [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(CLOSE_WRITE), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(CREATE), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Create(File), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [trace] raw event: Event { kind: Access(Close(Write)), paths: ["/tmp/.tmppiROfi/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(OPEN), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Access(Open(Any)), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(MODIFY), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Modify(Data(Any)), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [trace] inotify event: Event { wd: WatchDescriptor { id: 1, fd: (Weak) }, mask: EventMask(CLOSE_WRITE), cookie: 0, name: Some("test.txt") } [INFO] [stderr] [trace] raw event: Event { kind: Access(Close(Write)), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None } [INFO] [stderr] [warn] Failed to commit changes. Retrying... (2/2). [INFO] [stderr] Error: Mock callback error [INFO] [stderr] [INFO] [stderr] Stack backtrace: [INFO] [stderr] 0: anyhow::error::::msg [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/src/backtrace.rs:27:14 [INFO] [stderr] 1: anyhow::__private::format_err [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.98/src/lib.rs:694:13 [INFO] [stderr] 2: gitwatch_rs::watcher::tests::test_watcher_callback_error::{{closure}}::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:178:21 [INFO] [stderr] 3: core::ops::function::impls:: for &F>::call [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/core/src/ops/function.rs:263:22 [INFO] [stderr] 4: gitwatch_rs::watcher::FileWatcher::handle_events [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:96:23 [INFO] [stderr] 5: gitwatch_rs::watcher::FileWatcher::watch [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:60:46 [INFO] [stderr] 6: gitwatch_rs::watcher::tests::test_watcher_callback_error::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/watcher.rs:174:21 [INFO] [stderr] 7: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/sys/backtrace.rs:158:18 [INFO] [stderr] 8: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/thread/mod.rs:559:17 [INFO] [stderr] 9: as core::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/core/src/panic/unwind_safe.rs:274:9 [INFO] [stderr] 10: std::panicking::catch_unwind::do_call [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/panicking.rs:590:40 [INFO] [stderr] 11: __rust_try [INFO] [stderr] 12: std::panicking::catch_unwind [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/panicking.rs:553:19 [INFO] [stderr] 13: std::panic::catch_unwind [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/panic.rs:359:14 [INFO] [stderr] 14: std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/thread/mod.rs:557:30 [INFO] [stderr] 15: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/core/src/ops/function.rs:250:5 [INFO] [stderr] 16: as core::ops::function::FnOnce>::call_once [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/alloc/src/boxed.rs:1985:9 [INFO] [stderr] 17: std::sys::thread::unix::Thread::new::thread_start [INFO] [stderr] at /rustc/bb624dcb4c8ab987e10c0808d92d76f3b84dd117/library/std/src/sys/thread/unix.rs:126:17 [INFO] [stderr] 18: [INFO] [stderr] 19: clone [INFO] [stderr] [trace] Received notify events { events [DebouncedEvent { event: Event { kind: Create(File), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 220099999 } }, DebouncedEvent { event: Event { kind: Access(Open(Any)), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 220337499 } }, DebouncedEvent { event: Event { kind: Access(Close(Write)), paths: ["/tmp/.tmpVGfUme/test.txt"], attr:tracker: None, attr:flag: None, attr:info: None, attr:source: None }, time: Instant { tv_sec: 10015, tv_nsec: 220602929 } }] } [INFO] [stderr] [error] All retry attempts failed: Mock callback error [INFO] [stdout] test watcher::tests::test_watcher_callback_error ... ok [INFO] [stderr] [debug] Received shutdown signal [INFO] [stdout] test watcher::tests::test_watcher_shutdown ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.17s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/gitwatch-695923df580ecf8f) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test tests::test_main_error_handling ... ok [INFO] [stdout] [INFO] [stderr] Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-1c4cd4a9b5fc9d0e) [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 13 tests [INFO] [stdout] test test_completion_command ... ok [INFO] [stdout] test test_invalid_command ... ok [INFO] [stdout] test test_main_invalid_path ... ok [INFO] [stdout] Opened repo '.tmppDzPxY' at commit '[7e2dc16] Initial commit' [INFO] [stderr] [debug] Using git repository '/tmp/.tmppDzPxY' [INFO] [stdout] Opened repo '.tmp6ylNmw' at commit '[7e2dc16] Initial commit' [INFO] [stderr] [debug] Using git repository '/tmp/.tmp6ylNmw' [INFO] [stdout] Opened repo '.tmpwd7xi6' at commit '[7e2dc16] Initial commit' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpwd7xi6' [INFO] [stdout] Opened repo '.tmpT0kAJf' at commit '[7e2dc16] Initial commit' [INFO] [stderr] [debug] Repository has uncommitted changes) [INFO] [stdout] Opened repo '.tmpD2BsTl' at commit '[7e2dc16] Initial commit' [INFO] [stderr] [debug] Modified files: [INFO] [stderr] [debug] commit-message-script.sh [INFO] [stderr] [debug] Using git repository '/tmp/.tmpT0kAJf' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpD2BsTl' [INFO] [stderr] [debug] Executing commit message script '/tmp/.tmpwd7xi6/commit-message-script.sh' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpzBjoyh' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpboSjPk' [INFO] [stderr] [debug] Generated commit message: 'generated commit message' [INFO] [stdout] Opened repo '.tmpzBjoyh' at commit '[7e2dc16] Initial commit' [INFO] [stdout] Opened repo '.tmpboSjPk' at commit '[7e2dc16] Initial commit' [INFO] [stderr] [warn] Watch is disabled [INFO] [stderr] [warn] Watch is disabled [INFO] [stdout] Created commit: 128ca9a 'test commit message' [INFO] [stderr] [debug] Using git repository '/tmp/.tmpyCG9Hf' [INFO] [stdout] Created commit: 2d2b022 'generated commit message' [INFO] [stderr] [debug] Pushing to remote origin [INFO] [stdout] Watching for changes... [INFO] [stderr] [warn] Changes will not be commited (dry-run enabled)! [INFO] [stdout] Created commit: 128ca9a 'test commit message' [INFO] [stderr] [warn] Watch is disabled [INFO] [stdout] Opened repo '.tmpyCG9Hf' at commit '[7e2dc16] Initial commit' [INFO] [stdout] Commit message: [INFO] [stdout] test commit message [INFO] [stdout] Staged files: [INFO] [stdout] foo.txt [INFO] [stdout]  [INFO] [stdout] Created commit: 128ca9a 'test commit message' [INFO] [stdout] test test_dry_run ... ok [INFO] [stderr] [debug] Using git repository '/tmp/.tmp70VmCX' [INFO] [stdout] Opened repo '.tmp70VmCX' at commit '[7e2dc16] Initial commit' [INFO] [stdout] test test_commit_message_script ... ok [INFO] [stderr] [debug] Pushing to remote origin [INFO] [stdout] Created commit: 128ca9a 'test commit message' [INFO] [stderr] [debug] Working tree clean [INFO] [stdout] test test_push_invalid_remote ... ok [INFO] [stdout] test test_commit_on_start ... ok [INFO] [stdout] Pushed changes to origin [INFO] [stderr] [debug] Working tree clean [INFO] [stdout] test test_push ... ok [INFO] [stderr] [warn] Watch is disabled [INFO] [stdout] Watching for changes... [INFO] [stdout] Watching for changes... [INFO] [stdout] Watching for changes... [INFO] [stdout] test test_main_valid_args ... ok [INFO] [stderr] [debug] Detected changes: [INFO] [stderr] foo.txt [INFO] [stdout] Created commit: bd7f328 'test commit message' [INFO] [stderr] [debug] Received shutdown signal [INFO] [stderr] [debug] Received shutdown signal [INFO] [stderr] [debug] Received shutdown signal [INFO] [stdout] test test_watch ... ok [INFO] [stdout] test test_gitignore ... ok [INFO] [stdout] test test_ignore_regex ... ok [INFO] [stderr] [debug] Detected changes: [INFO] [stdout] Created commit: 44f5937 'test commit message' [INFO] [stderr] foo.txt [INFO] [stderr] [debug] Received shutdown signal [INFO] [stdout] test test_debounce ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.67s [INFO] [stdout] [INFO] [stderr] Doc-tests gitwatch_rs [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] running `Command { std: "docker" "inspect" "2457484e00a6a00cca947cdef4e1af7dc634c5431fb5742ebafbf6a382322fd5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2457484e00a6a00cca947cdef4e1af7dc634c5431fb5742ebafbf6a382322fd5", kill_on_drop: false }` [INFO] [stdout] 2457484e00a6a00cca947cdef4e1af7dc634c5431fb5742ebafbf6a382322fd5