[INFO] fetching crate rsspec 0.5.0...
[INFO] testing rsspec-0.5.0 against beta-2026-04-21 for beta-1.96-2
[INFO] extracting crate rsspec 0.5.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate rsspec 0.5.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rsspec 0.5.0
[INFO] tweaked toml for crates.io crate rsspec 0.5.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rsspec 0.5.0 on toolchain beta-2026-04-21
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rsspec 0.5.0 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-2026-04-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c4b4c784c5885be02905ecbc294d85ad6d4096bc54d54f6505f1f1253f26db3a
[INFO] running `Command { std: "docker" "start" "-a" "c4b4c784c5885be02905ecbc294d85ad6d4096bc54d54f6505f1f1253f26db3a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c4b4c784c5885be02905ecbc294d85ad6d4096bc54d54f6505f1f1253f26db3a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c4b4c784c5885be02905ecbc294d85ad6d4096bc54d54f6505f1f1253f26db3a", kill_on_drop: false }`
[INFO] [stdout] c4b4c784c5885be02905ecbc294d85ad6d4096bc54d54f6505f1f1253f26db3a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 230f631a06e577f7ae4f270cd252d587d65c00a36a08158903c514d17210b3df
[INFO] running `Command { std: "docker" "start" "-a" "230f631a06e577f7ae4f270cd252d587d65c00a36a08158903c514d17210b3df", kill_on_drop: false }`
[INFO] [stderr]    Compiling rsspec v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.97s
[INFO] running `Command { std: "docker" "inspect" "230f631a06e577f7ae4f270cd252d587d65c00a36a08158903c514d17210b3df", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "230f631a06e577f7ae4f270cd252d587d65c00a36a08158903c514d17210b3df", kill_on_drop: false }`
[INFO] [stdout] 230f631a06e577f7ae4f270cd252d587d65c00a36a08158903c514d17210b3df
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a633506e481e01112626a3ffb53cbdfd497553f6b187e53b18322630ac3f30c4
[INFO] running `Command { std: "docker" "start" "-a" "a633506e481e01112626a3ffb53cbdfd497553f6b187e53b18322630ac3f30c4", kill_on_drop: false }`
[INFO] [stderr]    Compiling rsspec v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.66s
[INFO] running `Command { std: "docker" "inspect" "a633506e481e01112626a3ffb53cbdfd497553f6b187e53b18322630ac3f30c4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a633506e481e01112626a3ffb53cbdfd497553f6b187e53b18322630ac3f30c4", kill_on_drop: false }`
[INFO] [stdout] a633506e481e01112626a3ffb53cbdfd497553f6b187e53b18322630ac3f30c4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-04-21" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 4bce5720ba875fd7e43ebca4d311afe8d0168d8954499bdb654a972562fc6da0
[INFO] running `Command { std: "docker" "start" "-a" "4bce5720ba875fd7e43ebca4d311afe8d0168d8954499bdb654a972562fc6da0", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/rsspec-221c58ee638e9bd1)
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test runner::tests::detect_libtest_args_catches_format ... ok
[INFO] [stdout] test runner::tests::detect_libtest_args_catches_other_libtest_flags ... ok
[INFO] [stdout] test runner::tests::detect_libtest_args_catches_test_threads ... ok
[INFO] [stdout] test runner::tests::detect_libtest_args_ignores_rsspec_args ... ok
[INFO] [stdout] test runner::tests::ordered_is_skipped_when_focus_mode_is_active ... ok
[INFO] [stdout] test runner::tests::skip_reports_as_skipped_not_passed ... ok
[INFO] [stdout] test tests::test_guard_runs_on_success ... ok
[INFO] [stdout] test tests::test_labels_or_filter_with_negation ... ok
[INFO] [stdout] test tests::test_labels_positive_or ... ok
[INFO] [stdout] test tests::test_labels_pure_negation ... ok
[INFO] [stdout] test runner::tests::mixed_and_or_filter_is_rejected ... ok
[INFO] [stdout] test tests::test_labels_and_filter_with_negation ... ok
[INFO] [stdout] test runner::tests::after_all_panic_reports_failure ... ok
[INFO] [stdout] test runner::tests::after_each_panic_runs_remaining_hooks ... ok
[INFO] [stdout] test runner::tests::deferred_cleanup_panic_does_not_skip_remaining ... ok
[INFO] [stdout] test runner::tests::before_all_panic_reports_failure_and_runs_after_all ... ok
[INFO] [stdout] test runner::tests::before_each_panic_still_runs_after_each ... ok
[INFO] [stdout] test tests::test_with_retries_success_first_try ... ok
[INFO] [stdout] test runner::tests::retries_and_must_pass_repeatedly_compose ... ok
[INFO] [stdout] test tests::test_guard_runs_on_panic ... ok
[INFO] [stdout] test runner::tests::after_each_panic_preserves_test_failure ... ok
[INFO] [stdout] test tests::test_with_retries_eventual_success ... ok
[INFO] [stdout] test tests::test_labels_mixed_and_or_rejected ... ok
[INFO] [stdout] test runner::tests::retries_and_timeout_compose ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/decorators_test.rs (/opt/rustwide/target/debug/deps/decorators_test-d31594a23b2eb2cf)
[INFO] [stderr]   attempt 1/4 failed, retrying...
[INFO] [stderr]   attempt 2/4 failed, retrying...
[INFO] [stderr]   [1/2] step 1
[INFO] [stderr]   [2/2] step 2
[INFO] [stdout] 
[INFO] [stderr]      Running tests/describe_test.rs (/opt/rustwide/target/debug/deps/describe_test-2b9c7efa8591cd69)
[INFO] [stdout] decorators
[INFO] [stdout]   ✓ supports labels for filtering
[INFO] [stdout]   ✓ retries on failure up to N times
[INFO] [stdout]   ✓ requires N consecutive passes
[INFO] [stdout]   ✓ fails if execution exceeds timeout
[INFO] [stdout] table-driven tests
[INFO] [stdout]   addition
[INFO] [stdout]     ✓ positive
[INFO] [stdout]     ✓ large
[INFO] [stdout]     ✓ negative
[INFO] [stdout] ordered tests
[INFO] [stdout]   ✓ runs steps in sequence
[INFO] [stdout] 
[INFO] [stdout] PASS
[INFO] [stdout] 8 passed (0.000s)
[INFO] [stdout] 
[INFO] [stdout] describe, context, when
[INFO] [stdout]   ✓ groups tests under describe
[INFO] [stdout]   nested with context
[INFO] [stdout]     ✓ inherits the outer scope
[INFO] [stdout]   using when as alias
[INFO] [stdout]     ✓ behaves like context
[INFO] [stdout] it and specify
[INFO] [stdout]   ✓ defines a test case
[INFO] [stdout]   ✓ is an alias for it
[INFO] [stdout] pending tests
[INFO] [stdout]   - skips individual tests with xit
[INFO] [stdout]   skips entire groups with xdescribe
[INFO] [stdout]     - is also pending
[INFO] [stderr]      Running tests/hooks_test.rs (/opt/rustwide/target/debug/deps/hooks_test-5788251a20b945c0)
[INFO] [stdout] describe-level labels
[INFO] [stdout]   ✓ are inherited by child tests
[INFO] [stdout] 
[INFO] [stdout] PASS
[INFO] [stdout] 6 passed, 2 pending (0.001s)
[INFO] [stderr]      Running tests/utilities_test.rs (/opt/rustwide/target/debug/deps/utilities_test-0e2cdaac829e48fc)
[INFO] [stdout] 
[INFO] [stdout] before_each
[INFO] [stdout]   returning a simple value
[INFO] [stdout]     ✓ passes the return value to it via &T
[INFO] [stdout]   returning a struct
[INFO] [stdout]     ✓ works with any type, no Clone required
[INFO] [stdout]   with no return value
[INFO] [stdout]     ✓ still works with plain Fn() closures
[INFO] [stdout]   test isolation
[INFO] [stdout]     ✓ provides a fresh value to each test
[INFO] [stdout]     ✓ is not affected by previous tests
[INFO] [stdout]   nested contexts
[INFO] [stdout]     inner scope with its own before_each
[INFO] [stdout]       ✓ receives the inner value for the same type
[INFO] [stdout] before_each as action
[INFO] [stdout]   parsing and transforming data
[INFO] [stdout]     ✓ parses all entries
[INFO] [stdout]     ✓ extracts correct values
[INFO] [stdout]   building a complex object graph
[INFO] [stdout]     ✓ builds the correct depth
[INFO] [stdout]     ✓ builds the correct node count
[INFO] [stdout]   filtering and aggregating a dataset
[INFO] [stdout]     ✓ computes the correct total
[INFO] [stdout]     ✓ counts all elements
[INFO] [stdout]     ✓ filters values above threshold
[INFO] [stdout]   multi-step pipeline
[INFO] [stdout]     ✓ tokenizes the input
[INFO] [stdout]     ✓ counts word frequencies
[INFO] [stdout]     ✓ preserves the original input
[INFO] [stdout] after_each
[INFO] [stdout]   ✓ runs after normal completion
[INFO] [stdout]   ✓ confirms it ran for the previous test
[INFO] [stdout] before_all and after_all
[INFO] [stdout]   returning a value
[INFO] [stdout]     ✓ is available in the first test
[INFO] [stdout]     ✓ persists across subsequent tests
[INFO] [stdout]   runs exactly once
[INFO] [stdout]     ✓ has run once after the first test
[INFO] [stdout]     ✓ still only once after the second test
[INFO] [stdout]   expensive one-time setup
[INFO] [stdout]     ✓ provides config to the first test
[INFO] [stdout]     ✓ provides the same config to subsequent tests
[INFO] [stdout] before_each same-type overwrite
[INFO] [stdout]   two hooks returning the same type
[INFO] [stdout]     ✓ receives the last-registered value
[INFO] [stdout] before_each takes priority over before_all
[INFO] [stdout]   both hooks return the same type
[INFO] [stdout]     ✓ receives the before_each value
[INFO] [stdout] before_all nested scope isolation
[INFO] [stdout]   outer and inner both register before_all for the same type
[INFO] [stdout]     inner scope
[INFO] [stdout]       ✓ receives the inner value inside the inner scope
[INFO] [stdout]     ✓ receives the outer value after inner scope closes
[INFO] [stdout] just_before_each
[INFO] [stdout]   ✓ runs after before_each but before the test body
[INFO] [stdout] 
[INFO] [stdout] PASS
[INFO] [stdout] 29 passed (0.000s)
[INFO] [stderr]   STEP: setting up
[INFO] [stdout] 
[INFO] [stderr]   STEP: verifying
[INFO] [stdout] defer_cleanup
[INFO] [stdout]   ✓ registers a cleanup function
[INFO] [stdout]   ✓ runs cleanup after the previous test
[INFO] [stdout] by()
[INFO] [stdout]   ✓ documents steps within a test
[INFO] [stdout] 
[INFO] [stdout] PASS
[INFO] [stdout] 3 passed (0.000s)
[INFO] [stderr]    Doc-tests rsspec
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test src/context.rs - context::Context::ordered (line 404) - compile ... ok
[INFO] [stdout] test src/table.rs - table::TableBuilder (line 15) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 9) - compile ... ok
[INFO] [stdout] test src/context.rs - context::Context::before_each (line 309) - compile ... ok
[INFO] [stdout] test src/context.rs - context::run_inline (line 746) - compile ... ok
[INFO] [stdout] test src/context.rs - context::Context::labels (line 367) - compile ... ok
[INFO] [stdout] test src/context.rs - context::Context::describe_table (line 384) - compile ... ok
[INFO] [stdout] test src/ordered.rs - ordered::OrderedContext (line 9) - compile ... ok
[INFO] [stdout] test src/context.rs - context::Context (line 188) - compile ... ok
[INFO] [stdout] test src/context.rs - context::ItBuilder (line 569) - compile ... ok
[INFO] [stdout] test src/context.rs - context::Context::it (line 261) - compile ... ok
[INFO] [stdout] test src/context.rs - context::run (line 691) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.23s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4bce5720ba875fd7e43ebca4d311afe8d0168d8954499bdb654a972562fc6da0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4bce5720ba875fd7e43ebca4d311afe8d0168d8954499bdb654a972562fc6da0", kill_on_drop: false }`
[INFO] [stdout] 4bce5720ba875fd7e43ebca4d311afe8d0168d8954499bdb654a972562fc6da0
