[INFO] fetching crate mfm-machine 0.1.0...
[INFO] testing mfm-machine-0.1.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1
[INFO] extracting crate mfm-machine 0.1.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate mfm-machine 0.1.0
[INFO] finished tweaking crates.io crate mfm-machine 0.1.0
[INFO] tweaked toml for crates.io crate mfm-machine 0.1.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate mfm-machine 0.1.0 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate mfm-machine 0.1.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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ca8af47f84a3daf14ed137c35ddb08b52c4c67d83770309beec94761cd5521ef
[INFO] running `Command { std: "docker" "start" "-a" "ca8af47f84a3daf14ed137c35ddb08b52c4c67d83770309beec94761cd5521ef", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ca8af47f84a3daf14ed137c35ddb08b52c4c67d83770309beec94761cd5521ef", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ca8af47f84a3daf14ed137c35ddb08b52c4c67d83770309beec94761cd5521ef", kill_on_drop: false }`
[INFO] [stdout] ca8af47f84a3daf14ed137c35ddb08b52c4c67d83770309beec94761cd5521ef
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5455165c4efbef4ddbcab034e4fb5bb25ea760d32c469a85cbfa50dfa225a053
[INFO] running `Command { std: "docker" "start" "-a" "5455165c4efbef4ddbcab034e4fb5bb25ea760d32c469a85cbfa50dfa225a053", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling getrandom v0.3.2
[INFO] [stderr]    Compiling zerocopy v0.8.24
[INFO] [stderr]    Compiling zmij v1.0.20
[INFO] [stderr]    Compiling cc v1.2.55
[INFO] [stderr]    Compiling nix v0.31.1
[INFO] [stderr]    Compiling tracing-core v0.1.33
[INFO] [stderr]    Compiling sha1_smol v1.0.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling mio v1.0.3
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.9.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling uuid v1.16.0
[INFO] [stderr]    Compiling mfm-machine v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 23.81s
[INFO] running `Command { std: "docker" "inspect" "5455165c4efbef4ddbcab034e4fb5bb25ea760d32c469a85cbfa50dfa225a053", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5455165c4efbef4ddbcab034e4fb5bb25ea760d32c469a85cbfa50dfa225a053", kill_on_drop: false }`
[INFO] [stdout] 5455165c4efbef4ddbcab034e4fb5bb25ea760d32c469a85cbfa50dfa225a053
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 451a07d6e07b1176b0c5cd3aff09ce266aaee359cc1ff9cd99a9dc53ad14195d
[INFO] running `Command { std: "docker" "start" "-a" "451a07d6e07b1176b0c5cd3aff09ce266aaee359cc1ff9cd99a9dc53ad14195d", kill_on_drop: false }`
[INFO] [stderr]    Compiling mfm-machine v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.16s
[INFO] running `Command { std: "docker" "inspect" "451a07d6e07b1176b0c5cd3aff09ce266aaee359cc1ff9cd99a9dc53ad14195d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "451a07d6e07b1176b0c5cd3aff09ce266aaee359cc1ff9cd99a9dc53ad14195d", kill_on_drop: false }`
[INFO] [stdout] 451a07d6e07b1176b0c5cd3aff09ce266aaee359cc1ff9cd99a9dc53ad14195d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 64e0193d33afb60b0c117d98275f92deb3d556feb005988e3bace6aa002ba51c
[INFO] running `Command { std: "docker" "start" "-a" "64e0193d33afb60b0c117d98275f92deb3d556feb005988e3bace6aa002ba51c", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mfm_machine-604bca0a310d0818)
[INFO] [stdout] running 53 tests
[INFO] [stdout] test context_runtime::tests::staged_context_commit_and_discard_semantics ... ok
[INFO] [stdout] test attempt_envelope::tests::validates_and_ignores_domain_events_for_checkpoint ... ok
[INFO] [stdout] test event_profile::tests::minimal_filters_non_critical_domain_events ... ok
[INFO] [stdout] test errors::tests::missing_fact_key_code_is_stable ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::rejects_disallowed_program_path_by_default ... ok
[INFO] [stdout] test context_runtime::tests::full_snapshot_ids_are_deterministic_for_same_logical_context ... ok
[INFO] [stdout] test event_profile::tests::filtering_event_recorder_enforces_profile ... ok
[INFO] [stdout] test event_profile::tests::normal_allows_custom_domain_events ... ok
[INFO] [stdout] test attempt_envelope::tests::detects_orphan_attempt_at_end_of_stream ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::rejects_invalid_request_shape ... ok
[INFO] [stdout] test hashing::tests::artifact_id_sha256_lowercase_hex ... ok
[INFO] [stdout] test hashing::tests::canonical_json_rejects_secrets ... ok
[INFO] [stdout] test hashing::tests::canonical_json_vectors_basic ... ok
[INFO] [stdout] test attempt_envelope::tests::rejects_terminal_without_entered ... ok
[INFO] [stdout] test hashing::tests::canonical_json_rejects_floats ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::rejects_missing_program_path_even_if_allowlisted ... ok
[INFO] [stdout] test ids::ids_tests::id_segment_validation ... ok
[INFO] [stdout] test events::tests::child_run_payloads_are_canonical_and_non_secret ... ok
[INFO] [stdout] test ids::ids_tests::op_path_shape_and_segments ... ok
[INFO] [stdout] test ids::ids_tests::op_id_constructor_enforces_segment_rules ... ok
[INFO] [stdout] test ids::ids_tests::state_id_constructor_enforces_shape_and_segment_rules ... ok
[INFO] [stdout] test ids::ids_tests::serde_rejects_invalid_op_and_state_ids ... ok
[INFO] [stdout] test ids::ids_tests::state_id_shape_and_segments ... ok
[INFO] [stdout] test live_io_registry::tests::duplicate_namespace_group_is_rejected ... ok
[INFO] [stdout] test live_io_router::tests::routes_by_namespace_group_prefix ... ok
[INFO] [stdout] test live_io_router::tests::routes_to_longest_matching_prefix ... ok
[INFO] [stdout] test runtime::runtime_tests::fact_payloads_with_secrets_are_rejected ... ok
[INFO] [stdout] test runtime::runtime_tests::facts_are_single_assignment_and_reused_across_retries ... ok
[INFO] [stdout] test runtime::runtime_tests::domain_events_with_secrets_are_rejected ... ok
[INFO] [stdout] test replay_io::tests::missing_fact_retryable_follows_run_config ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::non_zero_exit_includes_safe_failure_metadata ... ok
[INFO] [stdout] test runtime::runtime_tests::crash_resume_orphan_attempt_reuses_facts ... ok
[INFO] [stdout] test live_io_router::tests::unknown_namespace_is_stable_error ... ok
[INFO] [stdout] test process_exec::tests::stream_overflow_is_detected_without_unbounded_growth ... ok
[INFO] [stdout] test replay_io::tests::deterministic_call_missing_fact_key_is_stable ... ok
[INFO] [stdout] test runtime::runtime_tests::state_failed_error_messages_are_redacted_before_persisting ... ok
[INFO] [stdout] test runtime::runtime_tests::skip_tags_skips_tagged_states_without_running_handler ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::rejects_path_traversal_that_escapes_allow_prefix ... ok
[INFO] [stdout] test runtime::runtime_tests::replay_mode_missing_fact_fails_without_live_io ... ok
[INFO] [stdout] test runtime::runtime_tests::retry_policy_retries_retryable_errors ... ok
[INFO] [stdout] test runtime::runtime_tests::rejects_fanout_join_execution_mode ... ok
[INFO] [stdout] test runtime::runtime_tests::secrets_in_initial_context_are_rejected_and_not_persisted ... ok
[INFO] [stdout] test runtime::runtime_tests::replay_mode_serves_recorded_facts_without_live_io ... ok
[INFO] [stdout] test runtime::runtime_tests::start_then_resume_retries_orphan_attempt_from_base_snapshot ... ok
[INFO] [stdout] test secrets::tests::detects_mnemonic_phrase_like_strings ... ok
[INFO] [stdout] test secrets::tests::detects_forbidden_substrings ... ok
[INFO] [stdout] test secrets::tests::json_detection_is_recursive_and_checks_keys ... ok
[INFO] [stdout] test runtime::runtime_tests::time_and_random_are_recorded_as_facts ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::stdout_overflow_reports_bounded_failure_metadata ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::timeout_includes_program_and_timeout_metadata ... ok
[INFO] [stdout] test process_exec::tests::timeout_covers_stdin_write ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::rejects_stdin_payloads_larger_than_limit ... ok
[INFO] [stdout] test exec_transport::exec_transport_tests::timeout_applies_while_writing_stdin ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests mfm_machine
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/lib.rs - ids::OpId::new (line 190) ... ok
[INFO] [stdout] test src/lib.rs - (line 10) ... ok
[INFO] [stdout] test src/lib.rs - ids::StateId::new (line 286) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.93s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "64e0193d33afb60b0c117d98275f92deb3d556feb005988e3bace6aa002ba51c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "64e0193d33afb60b0c117d98275f92deb3d556feb005988e3bace6aa002ba51c", kill_on_drop: false }`
[INFO] [stdout] 64e0193d33afb60b0c117d98275f92deb3d556feb005988e3bace6aa002ba51c