[INFO] cloning repository https://github.com/getmeosu/appcall
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/getmeosu/appcall" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgetmeosu%2Fappcall", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgetmeosu%2Fappcall'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] c7d69a98a53f2d5636cfc5dc15088aa711a526a4
[INFO] testing getmeosu/appcall against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fgetmeosu%2Fappcall" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] [stderr] Updating files:   8% (8896/100441)
Updating files:   9% (9040/100441)
Updating files:  10% (10045/100441)
Updating files:  11% (11049/100441)
Updating files:  12% (12053/100441)
Updating files:  13% (13058/100441)
Updating files:  14% (14062/100441)
Updating files:  15% (15067/100441)
Updating files:  16% (16071/100441)
Updating files:  17% (17075/100441)
Updating files:  18% (18080/100441)
Updating files:  19% (19084/100441)
Updating files:  20% (20089/100441)
Updating files:  21% (21093/100441)
Updating files:  22% (22098/100441)
Updating files:  22% (22223/100441)
Updating files:  23% (23102/100441)
Updating files:  24% (24106/100441)
Updating files:  25% (25111/100441)
Updating files:  26% (26115/100441)
Updating files:  27% (27120/100441)
Updating files:  28% (28124/100441)
Updating files:  29% (29128/100441)
Updating files:  30% (30133/100441)
Updating files:  31% (31137/100441)
Updating files:  32% (32142/100441)
Updating files:  33% (33146/100441)
Updating files:  33% (33443/100441)
Updating files:  34% (34150/100441)
Updating files:  35% (35155/100441)
Updating files:  36% (36159/100441)
Updating files:  37% (37164/100441)
Updating files:  38% (38168/100441)
Updating files:  39% (39172/100441)
Updating files:  40% (40177/100441)
Updating files:  41% (41181/100441)
Updating files:  42% (42186/100441)
Updating files:  43% (43190/100441)
Updating files:  44% (44195/100441)
Updating files:  45% (45199/100441)
Updating files:  45% (45991/100441)
Updating files:  46% (46203/100441)
Updating files:  47% (47208/100441)
Updating files:  48% (48212/100441)
Updating files:  49% (49217/100441)
Updating files:  50% (50221/100441)
Updating files:  51% (51225/100441)
Updating files:  52% (52230/100441)
Updating files:  53% (53234/100441)
Updating files:  53% (54007/100441)
Updating files:  54% (54239/100441)
Updating files:  55% (55243/100441)
Updating files:  56% (56247/100441)
Updating files:  57% (57252/100441)
Updating files:  58% (58256/100441)
Updating files:  59% (59261/100441)
Updating files:  60% (60265/100441)
Updating files:  61% (61270/100441)
Updating files:  62% (62274/100441)
Updating files:  63% (63278/100441)
Updating files:  64% (64283/100441)
Updating files:  64% (64980/100441)
Updating files:  65% (65287/100441)
Updating files:  66% (66292/100441)
Updating files:  67% (67296/100441)
Updating files:  68% (68300/100441)
Updating files:  69% (69305/100441)
Updating files:  70% (70309/100441)
Updating files:  71% (71314/100441)
Updating files:  72% (72318/100441)
Updating files:  73% (73322/100441)
Updating files:  74% (74327/100441)
Updating files:  75% (75331/100441)
Updating files:  76% (76336/100441)
Updating files:  77% (77340/100441)
Updating files:  77% (78069/100441)
Updating files:  78% (78344/100441)
Updating files:  79% (79349/100441)
Updating files:  80% (80353/100441)
Updating files:  81% (81358/100441)
Updating files:  82% (82362/100441)
Updating files:  83% (83367/100441)
Updating files:  84% (84371/100441)
Updating files:  85% (85375/100441)
Updating files:  86% (86380/100441)
Updating files:  87% (87384/100441)
Updating files:  88% (88389/100441)
Updating files:  89% (89393/100441)
Updating files:  90% (90397/100441)
Updating files:  90% (90694/100441)
Updating files:  91% (91402/100441)
Updating files:  92% (92406/100441)
Updating files:  93% (93411/100441)
Updating files:  94% (94415/100441)
Updating files:  95% (95419/100441)
Updating files:  96% (96424/100441)
Updating files:  97% (97428/100441)
Updating files:  98% (98433/100441)
Updating files:  99% (99437/100441)
Updating files: 100% (100441/100441)
Updating files: 100% (100441/100441), done.
[INFO] started tweaking git repo https://github.com/getmeosu/appcall
[INFO] finished tweaking git repo https://github.com/getmeosu/appcall
[INFO] tweaked toml for git repo https://github.com/getmeosu/appcall written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/getmeosu/appcall on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/getmeosu/appcall 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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: unused field `repository` in `workspace.package`
[INFO] [stderr]  --> Cargo.toml:8:1
[INFO] [stderr]   |
[INFO] [stderr] 8 | repository = "https://github.com/getmeosu/appcall"
[INFO] [stderr]   | ^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_package_fields` is set to `warn` by default
[INFO] [stderr] help: consider removing the field `workspace.package.repository`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac
[INFO] running `Command { std: "docker" "start" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: unused field `repository` in `workspace.package`
[INFO] [stderr]  --> Cargo.toml:8:1
[INFO] [stderr]   |
[INFO] [stderr] 8 | repository = "https://github.com/getmeosu/appcall"
[INFO] [stderr]   | ^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_package_fields` is set to `warn` by default
[INFO] [stderr] help: consider removing the field `workspace.package.repository`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling find-msvc-tools v0.1.12
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling pkg-config v0.3.34
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling smallvec v1.16.0
[INFO] [stderr]    Compiling cpufeatures v0.3.1
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling cc v1.4.5
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling libsqlite3-sys v0.30.1
[INFO] [stderr]    Compiling hybrid-array v0.4.14
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling rusqlite v0.32.1
[INFO] [stderr]    Compiling appcall-engine v0.1.0 (/opt/rustwide/workdir/crates/appcall-engine)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 40s
[INFO] running `Command { std: "docker" "inspect" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: unused field `repository` in `workspace.package`
[INFO] [stderr]  --> Cargo.toml:8:1
[INFO] [stderr]   |
[INFO] [stderr] 8 | repository = "https://github.com/getmeosu/appcall"
[INFO] [stderr]   | ^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_package_fields` is set to `warn` by default
[INFO] [stderr] help: consider removing the field `workspace.package.repository`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling rusqlite v0.32.1
[INFO] [stderr]    Compiling appcall-engine v0.1.0 (/opt/rustwide/workdir/crates/appcall-engine)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 38.76s
[INFO] running `Command { std: "docker" "inspect" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: unused field `repository` in `workspace.package`
[INFO] [stderr]  --> Cargo.toml:8:1
[INFO] [stderr]   |
[INFO] [stderr] 8 | repository = "https://github.com/getmeosu/appcall"
[INFO] [stderr]   | ^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_package_fields` is set to `warn` by default
[INFO] [stderr] help: consider removing the field `workspace.package.repository`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.18s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/appcall-engine/9e0f2a67e3208aa4/out/appcall_engine-9e0f2a67e3208aa4)
[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/acceptance_bar.rs (/opt/rustwide/target/release/build/appcall-engine/4c15bece2718a821/out/acceptance_bar-4c15bece2718a821)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test caps_1024_and_1mib_remain_frozen_in_source ... ok
[INFO] [stdout] test uncertain_blocks_until_fenced_reconcile_no_blind_redispatch_exclusive_owner ... ok
[INFO] [stdout] test exclusive_owner_epoch_cas_stale_revision_conflict_no_mutation ... ok
[INFO] [stdout] test continue_as_new_digest_claim_check_successor_empty_no_raw_secret ... ok
[INFO] [stdout] test waiting_noise_signals_revision_delta_proves_noop_drive_skip ... ok
[INFO] [stdout] test recover_no_retry_storm_drop_engine_before_sqlite_reopen ... ok
[INFO] [stdout] test history_1024_still_limit_via_progressed_batches ... ok
[INFO] [stdout] test encoded_record_1mib_still_limit_via_sqlite_commit_chunk_growth has been running for over 60 seconds
[INFO] [stdout] test encoded_record_1mib_still_limit_via_sqlite_commit_chunk_growth ... ok
[INFO] [stderr]      Running tests/blob_rewrite_sqlite.rs (/opt/rustwide/target/release/build/appcall-engine/8e3a8e0fe3700208/out/blob_rewrite_sqlite-8e3a8e0fe3700208)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 195.99s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test measure_sqlite_blob_rewrite_vs_history_depth ... ignored, set APPCALL_BLOB_REWRITE_PHASE=before|after to regenerate evidence
[INFO] [stdout] test sqlite_sealed_history_is_load_bearing_in_source_and_evidence ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/child_failure.rs (/opt/rustwide/target/release/build/appcall-engine/5ba434cd6673fc82/out/child_failure-5ba434cd6673fc82)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test parent_cancellation_keeps_unknown_child_reconcilable ... ok
[INFO] [stdout] test failed_child_wakes_parent_and_preserves_terminal_failure_across_restart ... ok
[INFO] [stdout] test parent_cancellation_preserves_terminal_child_states ... ok
[INFO] [stdout] test cancelled_child_select_cascades_before_ready_timer_or_signal_and_survives_restart ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/durability.rs (/opt/rustwide/target/release/build/appcall-engine/daaa6e4355681d8d/out/durability-daaa6e4355681d8d)
[INFO] [stdout] 
[INFO] [stdout] running 73 tests
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test abrupt_exit_fixture ... ok
[INFO] [stdout] test exclusive_owner_and_pinned_version ... ok
[INFO] [stdout] test cancellation_preserves_failed_terminal_identity ... ok
[INFO] [stdout] test duplicate_start_returns_conflict ... ok
[INFO] [stdout] test cancellation_preserves_nondeterminism_terminal_identity ... ok
[INFO] [stdout] test completed_child_wakes_waiting_parent ... ok
[INFO] [stdout] test cancellation_preserves_outcome_unknown_and_allows_reconciliation ... ok
[INFO] [stdout] test cancellation_during_retry_backoff_removes_future_wakeup ... ok
[INFO] [stdout] test buffered_signal_before_wait_survives_restart_and_enters_history ... ok
[INFO] [stdout] test dispatch_capacity_parks_without_busy_wakeup_and_completion_releases_it ... ok
[INFO] [stdout] test committed_effect_survives_abrupt_process_exit_without_checkpoint ... ok
[INFO] [stdout] test cancelled_uncertain_task_stays_cancelled_after_restart ... ok
[INFO] [stdout] test engine_save_at_max_revision_returns_limit_without_panicking ... ok
[INFO] [stdout] test ambiguous_effect_requires_reconciliation_and_late_attempt_is_fenced ... ok
[INFO] [stdout] test epoch_zero_retry_policy_preserves_elapsed_budget_after_recovery ... ok
[INFO] [stdout] test attached_children_cancel_and_late_effect_does_not_advance ... ok
[INFO] [stdout] test explicit_failure_reports_retry_safely_fence_stale_attempts_and_release_capacity ... ok
[INFO] [stdout] test fenced_reconcile_rejects_stale_attempt_and_epoch_without_mutation ... ok
[INFO] [stdout] test invocation_timestamp_starts_retry_elapsed_budget_after_payload_resolution ... ok
[INFO] [stdout] test fenced_reconcile_blocks_delayed_provider_after_owner_epoch_change ... ok
[INFO] [stdout] test fenced_reconcile_with_evidence_records_ref_not_body_and_survives_restart ... ok
[INFO] [stdout] test missing_ephemeral_input_suspends_and_payload_content_is_not_persisted ... ok
[INFO] [stdout] test maximum_length_parent_id_can_start_an_attached_child ... ok
[INFO] [stdout] test joining_cancelled_child_cancels_attached_siblings ... ok
[INFO] [stdout] test later_unknown_sibling_upgrades_retry_exhaustion_back_to_reconcilable_state ... ok
[INFO] [stdout] test known_unexecuted_payload_can_resume_without_restart ... ok
[INFO] [stdout] test known_unexecuted_missing_implementation_can_resume_after_restart ... ok
[INFO] [stdout] test interrupted_cancellation_is_runnable_and_recovers ... ok
[INFO] [stdout] test known_unexecuted_payload_dispatch_does_not_consume_retry_budget ... ok
[INFO] [stdout] test owned_native_invocation_allows_cancellation_during_callback ... ok
[INFO] [stdout] test legacy_issue_92_in_flight_record_starts_retry_budget_after_recovery ... ok
[INFO] [stdout] test legacy_max_attempt_in_flight_recovers_as_exhausted_without_dispatch_or_capacity_leak ... ok
[INFO] [stdout] test owned_sibling_completion_after_retry_exhaustion_preserves_terminal_failure ... ok
[INFO] [stdout] test parent_cannot_complete_with_unjoined_live_child ... ok
[INFO] [stdout] test reconciliation_rejects_ephemeral_observation_without_mutation_then_accepts_durable ... ok
[INFO] [stdout] test native_activities_signal_child_join_and_select_survive_restart ... ok
[INFO] [stdout] test persisted_missing_workflow_can_be_resumed_after_registration_without_a_duplicate ... ok
[INFO] [stdout] test reopen_keeps_passive_signal_wait_asleep ... ok
[INFO] [stdout] test reopen_keeps_passive_child_wait_asleep ... ok
[INFO] [stdout] test read_retry_keeps_effect_id_and_fences_old_owner ... ok
[INFO] [stdout] test retry_policy_durable_serde_rejects_malformed_policies ... ok
[INFO] [stdout] test reopen_rejects_malformed_running_record_without_rewriting_it ... ok
[INFO] [stdout] test reopen_fails_stale_safe_attempt_at_persisted_elapsed_deadline ... ok
[INFO] [stdout] test reopen_requeues_ready_task_with_null_wakeup ... ok
[INFO] [stdout] test restored_ephemeral_input_requeues_persisted_needs_input_after_restart ... ok
[INFO] [stdout] test reopen_requeues_ready_task_with_future_timer_wakeup ... ok
[INFO] [stdout] test resume_running_does_not_bypass_a_future_timer ... ok
[INFO] [stdout] test restart_replays_without_repeating_completed_effect ... ok
[INFO] [stdout] test retry_attempt_budget_reports_bounded_exhaustion ... ok
[INFO] [stdout] test retry_elapsed_budget_exhausts_before_an_extra_dispatch ... ok
[INFO] [stdout] test retry_backoff_deadline_and_policy_survive_restart ... ok
[INFO] [stdout] test retry_failure_preserves_an_already_due_ready_activity_wakeup ... ok
[INFO] [stdout] test retry_failure_preserves_an_earlier_timer_wakeup ... ok
[INFO] [stdout] test select_validates_all_sources_even_if_first_is_ready ... ok
[INFO] [stdout] test retry_exhaustion_preserves_an_earlier_unknown_sibling_for_reconciliation ... ok
[INFO] [stdout] test retryable_failure_persists_deadline_and_bounded_attempts ... ok
[INFO] [stdout] test serialized_payload_reference_validates_and_altered_attempt_is_rejected ... ok
[INFO] [stdout] test retrying_run_does_not_starve_unrelated_ready_work ... ok
[INFO] [stdout] test signal_rejected_on_terminal_run_without_history_mutation ... ok
[INFO] [stdout] test retryable_failure_backoff_starts_at_actual_failure_time ... ok
[INFO] [stdout] test replay_rejects_changed_command_and_early_return ... ok
[INFO] [stdout] test sqlite_commit_with_max_expected_returns_conflict_without_panicking ... ok
[INFO] [stdout] test selected_ephemeral_payload_requires_input_after_restart ... ok
[INFO] [stdout] test signal_fifo_order_survives_restart_across_two_waits ... ok
[INFO] [stdout] test sqlite_signed_revision_overflow_returns_limit_for_insert_and_commit ... ok
[INFO] [stdout] test store_cas_and_child_insert_are_atomic ... ok
[INFO] [stdout] test resume_reopens_stale_read_and_idempotent_attempts_without_drive ... ok
[INFO] [stdout] test swallowing_blocked_before_second_command_fails_closed ... ok
[INFO] [stdout] test swallowed_blocked_timer_cannot_complete_workflow ... ok
[INFO] [stdout] test timer_remains_scheduled_while_parallel_activity_is_in_flight ... ok
[INFO] [stdout] test transient_wakeup_failure_retains_parked_dispatch_for_retry ... ok
[INFO] [stdout] test unknown_sibling_reconciliation_after_retry_exhaustion_survives_restart ... ok
[INFO] [stdout] test unrelated_signal_does_not_complete_wait_across_restart ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.79s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/durability_perf.rs (/opt/rustwide/target/release/build/appcall-engine/1777a7e1e3251ec8/out/durability_perf-1777a7e1e3251ec8)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test select_baseline_no_match_returns_none ... ok
[INFO] [stdout] test select_baseline_newest_matching_host_wins ... ok
[INFO] [stdout] test noop_waiting_redrive_rss_and_latency_under_budget ... FAILED
[INFO] [stdout] test durability_path_rss_and_latency_under_budget ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- noop_waiting_redrive_rss_and_latency_under_budget stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'noop_waiting_redrive_rss_and_latency_under_budget' (1469) panicked at crates/appcall-engine/tests/durability_perf.rs:431:71:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 30, kind: ReadOnlyFilesystem, message: "Read-only file system" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6b7275d1d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b6b7277250a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5b6b727616bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5b6b727374b6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6b727549a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5b6b72523e00 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5b6b72754b62 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b6b72737562 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b6b7272ff29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b6b7273816d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b6b72772c6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6b72772a42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5b6b725025e0 - <durability_perf[6514f9daf94ec814]::noop_waiting_redrive_rss_and_latency_under_budget::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x5b6b7251724b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   14:     0x5b6b72524425 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x5b6b7251dd14 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x5b6b725274b2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   17:     0x5b6b7275c53f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x72500f594dfa - <unknown>
[INFO] [stdout]   19:     0x72500f6283d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- durability_path_rss_and_latency_under_budget stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'durability_path_rss_and_latency_under_budget' (1468) panicked at crates/appcall-engine/tests/durability_perf.rs:305:71:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 30, kind: ReadOnlyFilesystem, message: "Read-only file system" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6b7275d1d1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b6b7277250a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x5b6b727616bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5b6b727374b6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6b727549a9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x5b6b72523e00 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x5b6b72754b62 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b6b72737562 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b6b7272ff29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b6b7273816d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b6b72772c6c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6b72772a42 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x5b6b724fdebd - durability_perf[6514f9daf94ec814]::durability_path_rss_and_latency_under_budget
[INFO] [stdout]   13:     0x5b6b72500779 - <durability_perf[6514f9daf94ec814]::durability_path_rss_and_latency_under_budget::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x5b6b7251724b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   15:     0x5b6b72524425 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x5b6b7251dd14 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x5b6b725274b2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x5b6b7275c53f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x72500f594dfa - <unknown>
[INFO] [stdout]   20:     0x72500f6283d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     durability_path_rss_and_latency_under_budget
[INFO] [stdout]     noop_waiting_redrive_rss_and_latency_under_budget
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 2 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 7.07s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test durability_perf`
[INFO] running `Command { std: "docker" "inspect" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac", kill_on_drop: false }`
[INFO] [stdout] 93f045a19f7e2f0845f2046bdb72668f93ad1ed10e55251f46703101052a8fac
