[INFO] cloning repository https://github.com/lispcat/filetags [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/lispcat/filetags" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flispcat%2Ffiletags", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flispcat%2Ffiletags'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 873c482e65993603c79227138f7baffec0fadbeb [INFO] testing lispcat/filetags against master#ad85bc524b1ad696e42061ad8338d382dffbdbe5 for pr-146237 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flispcat%2Ffiletags" "/workspace/builds/worker-5-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc1/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/lispcat/filetags [INFO] finished tweaking git repo https://github.com/lispcat/filetags [INFO] tweaked toml for git repo https://github.com/lispcat/filetags written to /workspace/builds/worker-5-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/lispcat/filetags on toolchain ad85bc524b1ad696e42061ad8338d382dffbdbe5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/lispcat/filetags 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" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded build-env v0.3.1 [INFO] [stderr] Downloaded libredox v0.1.4 [INFO] [stderr] Downloaded shellexpand v3.1.1 [INFO] [stderr] Downloaded libsystemd-sys v0.9.3 [INFO] [stderr] Downloaded utf8-cstr v0.1.6 [INFO] [stderr] Downloaded cstr-argument v0.1.2 [INFO] [stderr] Downloaded kqueue-sys v1.0.4 [INFO] [stderr] Downloaded kqueue v1.1.1 [INFO] [stderr] Downloaded notify v8.0.0 [INFO] [stderr] Downloaded systemd v0.10.0 [INFO] [stderr] Downloaded notify-types v2.0.0 [INFO] [stderr] Downloaded serde_yml v0.0.12 [INFO] [stderr] Downloaded serde_regex v1.1.0 [INFO] [stderr] Downloaded smart-default v0.7.1 [INFO] [stderr] Downloaded libyml v0.0.5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] c8ecafe70c46e87534c736559b0665966a272ee146d6f8aa77c5b9c00591748d [INFO] running `Command { std: "docker" "start" "-a" "c8ecafe70c46e87534c736559b0665966a272ee146d6f8aa77c5b9c00591748d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "c8ecafe70c46e87534c736559b0665966a272ee146d6f8aa77c5b9c00591748d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c8ecafe70c46e87534c736559b0665966a272ee146d6f8aa77c5b9c00591748d", kill_on_drop: false }` [INFO] [stdout] c8ecafe70c46e87534c736559b0665966a272ee146d6f8aa77c5b9c00591748d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] cb7081b599d6993dda12f1d3fea70481209e188db8f7795d1d24d6d704e6c093 [INFO] running `Command { std: "docker" "start" "-a" "cb7081b599d6993dda12f1d3fea70481209e188db8f7795d1d24d6d704e6c093", kill_on_drop: false }` [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling bitflags v2.9.1 [INFO] [stderr] Compiling build-env v0.3.1 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling rustix v1.0.7 [INFO] [stderr] Compiling serde_yml v0.0.12 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling anstyle v1.0.11 [INFO] [stderr] Compiling foreign-types-shared v0.3.1 [INFO] [stderr] Compiling clap_lex v0.7.5 [INFO] [stderr] Compiling syn v2.0.104 [INFO] [stderr] Compiling hashbrown v0.15.4 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling libyml v0.0.5 [INFO] [stderr] Compiling filetime v0.2.25 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling cstr-argument v0.1.2 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling utf8-cstr v0.1.6 [INFO] [stderr] Compiling libsystemd-sys v0.9.3 [INFO] [stderr] Compiling notify-types v2.0.0 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling inotify v0.11.0 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling clap_builder v4.5.40 [INFO] [stderr] Compiling itertools v0.14.0 [INFO] [stderr] Compiling shellexpand v3.1.1 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling indexmap v2.9.0 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling notify v8.0.0 [INFO] [stderr] Compiling tempfile v3.20.0 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling foreign-types-macros v0.2.3 [INFO] [stderr] Compiling clap_derive v4.5.40 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling smart-default v0.7.1 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling foreign-types v0.5.0 [INFO] [stderr] Compiling systemd v0.10.0 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling clap v4.5.40 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling serde_regex v1.1.0 [INFO] [stderr] Compiling filetags v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 28.63s [INFO] running `Command { std: "docker" "inspect" "cb7081b599d6993dda12f1d3fea70481209e188db8f7795d1d24d6d704e6c093", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cb7081b599d6993dda12f1d3fea70481209e188db8f7795d1d24d6d704e6c093", kill_on_drop: false }` [INFO] [stdout] cb7081b599d6993dda12f1d3fea70481209e188db8f7795d1d24d6d704e6c093 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] dc3a1af8f8b4c247ca71790c9de51244a5f1efef629783b0efe8947dfdfcd71b [INFO] running `Command { std: "docker" "start" "-a" "dc3a1af8f8b4c247ca71790c9de51244a5f1efef629783b0efe8947dfdfcd71b", kill_on_drop: false }` [INFO] [stderr] Compiling filetags v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.42s [INFO] running `Command { std: "docker" "inspect" "dc3a1af8f8b4c247ca71790c9de51244a5f1efef629783b0efe8947dfdfcd71b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "dc3a1af8f8b4c247ca71790c9de51244a5f1efef629783b0efe8947dfdfcd71b", kill_on_drop: false }` [INFO] [stdout] dc3a1af8f8b4c247ca71790c9de51244a5f1efef629783b0efe8947dfdfcd71b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 767ba15dccfecbaf5e98b18da6e7c679010f353999d03ce3681c34b7485989de [INFO] running `Command { std: "docker" "start" "-a" "767ba15dccfecbaf5e98b18da6e7c679010f353999d03ce3681c34b7485989de", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/filetags-418060a2d768e47c) [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 unittests src/main.rs (/opt/rustwide/target/debug/deps/filetags-e452ed180ec0a043) [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/common.rs (/opt/rustwide/target/debug/deps/common-6478c30bcb8628f0) [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_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-92d065083d34db80) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] 2025-09-10T18:27:58.315939Z  INFO integration_tests: This is an info message! [INFO] [stdout] 2025-09-10T18:27:58.315991Z  WARN integration_tests: This is a warn message! [INFO] [stdout] test logging_wip ... ok [INFO] [stdout] 2025-09-10T18:27:58.326792Z DEBUG T_basic2: filetags: Config: Config { [INFO] [stdout] misc: MiscSettings { [INFO] [stdout] systemd_service: false, [INFO] [stdout] }, [INFO] [stdout] rules: [ [INFO] [stdout] Rule { [INFO] [stdout] name: "test", [INFO] [stdout] watch_dirs: [ [INFO] [stdout] "/tmp/.tmp9Xr8lP/watch_dir", [INFO] [stdout] ], [INFO] [stdout] link_dirs: [ [INFO] [stdout] "/tmp/.tmp9Xr8lP/link_dir", [INFO] [stdout] ], [INFO] [stdout] regex: [ [INFO] [stdout] Regex( [INFO] [stdout] "^_.*", [INFO] [stdout] ), [INFO] [stdout] ], [INFO] [stdout] raw_settings: None, [INFO] [stdout] settings: RuleSettings { [INFO] [stdout] create_missing_dirs: true, [INFO] [stdout] exclude_pattern: [], [INFO] [stdout] max_depth: 50, [INFO] [stdout] follow_symlinks: false, [INFO] [stdout] clean_interval: Some( [INFO] [stdout] 10, [INFO] [stdout] ), [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] ], [INFO] [stdout] } [INFO] [stdout] 2025-09-10T18:27:58.327190Z DEBUG T_basic1: filetags: Config: Config { [INFO] [stdout] misc: MiscSettings { [INFO] [stdout] systemd_service: false, [INFO] [stdout] }, [INFO] [stdout] rules: [ [INFO] [stdout] Rule { [INFO] [stdout] name: "test", [INFO] [stdout] watch_dirs: [ [INFO] [stdout] "/tmp/.tmpFKHDoY/watch_dir", [INFO] [stdout] ], [INFO] [stdout] link_dirs: [ [INFO] [stdout] "/tmp/.tmpFKHDoY/link_dir", [INFO] [stdout] ], [INFO] [stdout] regex: [ [INFO] [stdout] Regex( [INFO] [stdout] "^_.*", [INFO] [stdout] ), [INFO] [stdout] ], [INFO] [stdout] raw_settings: None, [INFO] [stdout] settings: RuleSettings { [INFO] [stdout] create_missing_dirs: true, [INFO] [stdout] exclude_pattern: [], [INFO] [stdout] max_depth: 50, [INFO] [stdout] follow_symlinks: false, [INFO] [stdout] clean_interval: Some( [INFO] [stdout] 10, [INFO] [stdout] ), [INFO] [stdout] }, [INFO] [stdout] }, [INFO] [stdout] ], [INFO] [stdout] } [INFO] [stdout] 2025-09-10T18:27:58.827674Z DEBUG T_basic2: filetags::utils: Symlink is broken: "/tmp/.tmp9Xr8lP/link_dir/_file2.txt" [INFO] [stdout] 2025-09-10T18:27:58.827850Z DEBUG T_basic2: filetags::dispatch::actions::symlinking: Regex matches! "/tmp/.tmp9Xr8lP/watch_dir/_file1.txt" [INFO] [stdout] 2025-09-10T18:27:58.827916Z DEBUG T_basic2: filetags::dispatch::actions::symlinking: Symlink points to the correct source file! "/tmp/.tmp9Xr8lP/watch_dir/_file1.txt", "/tmp/.tmp9Xr8lP/watch_dir/_file1.txt", "/tmp/.tmp9Xr8lP/link_dir/_file1.txt" [INFO] [stdout] 2025-09-10T18:27:58.828487Z DEBUG T_basic2: integration_tests::common: Sorted hashset real: [ [INFO] [stdout] "link_dir", [INFO] [stdout] "link_dir/_file1.txt", [INFO] [stdout] "watch_dir", [INFO] [stdout] "watch_dir/_file1.txt", [INFO] [stdout] ] [INFO] [stdout] Sorted hashset expe: [ [INFO] [stdout] "link_dir", [INFO] [stdout] "link_dir/_file1.txt", [INFO] [stdout] "watch_dir", [INFO] [stdout] "watch_dir/_file1.txt", [INFO] [stdout] ] [INFO] [stdout] test basic2 ... ok [INFO] [stdout] 2025-09-10T18:27:58.942846Z DEBUG T_basic1: filetags::dispatch::actions::symlinking: Regex matches! "/tmp/.tmpFKHDoY/watch_dir/_file4.txt" [INFO] [stdout] 2025-09-10T18:27:58.943020Z DEBUG T_basic1: filetags::dispatch::actions::symlinking: Regex matches! "/tmp/.tmpFKHDoY/watch_dir/_file3.txt" [INFO] [stdout] 2025-09-10T18:27:58.943119Z DEBUG T_basic1: filetags::dispatch::actions::symlinking: Regex matches! "/tmp/.tmpFKHDoY/watch_dir/_file2.txt" [INFO] [stdout] 2025-09-10T18:27:58.945680Z DEBUG T_basic1: integration_tests::common: Sorted hashset real: [ [INFO] [stdout] "link_dir", [INFO] [stdout] "link_dir/_file2.txt", [INFO] [stdout] "link_dir/_file3.txt", [INFO] [stdout] "link_dir/_file4.txt", [INFO] [stdout] "watch_dir", [INFO] [stdout] "watch_dir/_file2.txt", [INFO] [stdout] "watch_dir/_file3.txt", [INFO] [stdout] "watch_dir/_file4.txt", [INFO] [stdout] "watch_dir/file1.txt", [INFO] [stdout] ] [INFO] [stdout] Sorted hashset expe: [ [INFO] [stdout] "link_dir", [INFO] [stdout] "link_dir/_file2.txt", [INFO] [stdout] "link_dir/_file3.txt", [INFO] [stdout] "link_dir/_file4.txt", [INFO] [stdout] "watch_dir", [INFO] [stdout] "watch_dir/_file2.txt", [INFO] [stdout] "watch_dir/_file3.txt", [INFO] [stdout] "watch_dir/_file4.txt", [INFO] [stdout] "watch_dir/file1.txt", [INFO] [stdout] ] [INFO] [stdout] test basic1 ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.63s [INFO] [stdout] [INFO] [stderr] Doc-tests filetags [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" "767ba15dccfecbaf5e98b18da6e7c679010f353999d03ce3681c34b7485989de", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "767ba15dccfecbaf5e98b18da6e7c679010f353999d03ce3681c34b7485989de", kill_on_drop: false }` [INFO] [stdout] 767ba15dccfecbaf5e98b18da6e7c679010f353999d03ce3681c34b7485989de