[INFO] cloning repository https://github.com/sashee/dune2-rs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/sashee/dune2-rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsashee%2Fdune2-rs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsashee%2Fdune2-rs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f8d92867f22924785ba8a825dfc2473b1c4e2ebb
[INFO] testing sashee/dune2-rs against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fsashee%2Fdune2-rs" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/sashee/dune2-rs
[INFO] finished tweaking git repo https://github.com/sashee/dune2-rs
[INFO] tweaked toml for git repo https://github.com/sashee/dune2-rs written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/sashee/dune2-rs on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/sashee/dune2-rs 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.99.0-beta.8" "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]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded utf-8 v0.7.6
[INFO] [stderr]   Downloaded crypto-common v0.1.7
[INFO] [stderr]   Downloaded tungstenite v0.24.0
[INFO] [stderr]   Downloaded sha1 v0.10.7
[INFO] [stderr]   Downloaded cpufeatures v0.2.17
[INFO] [stderr]   Downloaded leb128fmt v0.1.0
[INFO] [stderr]   Downloaded wat v1.255.0
[INFO] [stderr]   Downloaded foldhash v0.1.5
[INFO] [stderr]   Downloaded data-encoding v2.11.1
[INFO] [stderr]   Downloaded zmij v1.0.23
[INFO] [stderr]   Downloaded wasmi_collections v1.1.0
[INFO] [stderr]   Downloaded wasi v0.11.1+wasi-snapshot-preview1
[INFO] [stderr]   Downloaded ppv-lite86 v0.2.21
[INFO] [stderr]   Downloaded wasmi_ir v1.1.0
[INFO] [stderr]   Downloaded spin v0.9.9
[INFO] [stderr]   Downloaded string-interner v0.19.0
[INFO] [stderr]   Downloaded serde_derive v1.0.229
[INFO] [stderr]   Downloaded log v0.4.33
[INFO] [stderr]   Downloaded bitflags v2.13.1
[INFO] [stderr]   Downloaded wasmi_core v1.1.0
[INFO] [stderr]   Downloaded typenum v1.20.1
[INFO] [stderr]   Downloaded rand v0.8.7
[INFO] [stderr]   Downloaded wasm-encoder v0.255.0
[INFO] [stderr]   Downloaded bumpalo v3.20.3
[INFO] [stderr]   Downloaded zerocopy-derive v0.8.56
[INFO] [stderr]   Downloaded hashbrown v0.15.5
[INFO] [stderr]   Downloaded serde_json v1.0.151
[INFO] [stderr]   Downloaded libm v0.2.16
[INFO] [stderr]   Downloaded wast v255.0.0
[INFO] [stderr]   Downloaded wasmparser v0.239.0
[INFO] [stderr]   Downloaded zerocopy v0.8.56
[INFO] [stderr]   Downloaded wasmi v1.1.0
[INFO] [stderr]   Downloaded wasmparser v0.255.0
[INFO] [stderr]   Downloaded syn v2.0.119
[INFO] [stderr]   Downloaded unicode-width v0.2.2
[INFO] [stderr]   Downloaded syn v3.0.3
[INFO] [stderr]   Downloaded libc v0.2.189
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad
[INFO] running `Command { std: "docker" "start" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", 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" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", 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" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling leb128fmt v0.1.0
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling bumpalo v3.20.3
[INFO] [stderr]    Compiling wasmparser v0.239.0
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling wasmi_collections v1.1.0
[INFO] [stderr]    Compiling wasm-encoder v0.255.0
[INFO] [stderr]    Compiling spin v0.9.9
[INFO] [stderr]    Compiling wast v255.0.0
[INFO] [stderr]    Compiling wasmi_core v1.1.0
[INFO] [stderr]    Compiling wasmi_ir v1.1.0
[INFO] [stderr]    Compiling wat v1.255.0
[INFO] [stderr]    Compiling wasmi v1.1.0
[INFO] [stderr]    Compiling dune2 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 35.79s
[INFO] running `Command { std: "docker" "inspect" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", 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" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling dune2 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.27s
[INFO] running `Command { std: "docker" "inspect" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", 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" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/dune2-8026401298cd9ba7)
[INFO] [stdout] 
[INFO] [stdout] running 94 tests
[INFO] [stdout] test ai::world::tests::a_sighting_goes_off_but_a_building_does_not ... ok
[INFO] [stdout] test ai::world::tests::a_worked_out_field_is_forgotten_but_only_that_field ... ok
[INFO] [stdout] test ai::protocol::tests::a_note_survives_the_payload ... ok
[INFO] [stdout] test ai::protocol::tests::a_sender_relative_delta_composes_through_from ... ok
[INFO] [stdout] test geom::tests::ring_surrounds_the_rect_exactly_once ... ok
[INFO] [stdout] test ai::world::tests::harvesters_are_counted_apart_from_the_army ... ok
[INFO] [stdout] test ai::world::tests::the_headcount_decays_rather_than_collapsing ... ok
[INFO] [stdout] test ai::protocol::tests::an_empty_payload_is_not_one_of_ours ... ok
[INFO] [stdout] test geom::tests::chebyshev_is_the_range_metric ... ok
[INFO] [stdout] test ground::tests::all_four_states_survive_the_wire ... ok
[INFO] [stdout] test ground::tests::unknown_is_not_empty_and_neither_is_off_map ... ok
[INFO] [stdout] test ground::tests::worked_out_spice_is_still_spice_terrain ... ok
[INFO] [stdout] test hash::tests::events_do_not_affect_the_hash ... ok
[INFO] [stdout] test hash::tests::identical_states_hash_identically ... ok
[INFO] [stdout] test map::tests::out_of_bounds_reads_as_mountain ... ok
[INFO] [stdout] test hash::tests::a_single_hit_point_changes_the_hash ... ok
[INFO] [stdout] test map::tests::parses_a_scenario ... ok
[INFO] [stdout] test map::tests::rejects_unknown_terrain ... ok
[INFO] [stdout] test map::tests::rejects_a_short_terrain_row ... ok
[INFO] [stdout] test map::tests::rejects_a_structure_named_as_a_unit ... ok
[INFO] [stdout] test hash::tests::a_moved_unit_changes_the_hash ... ok
[INFO] [stdout] test program::abi::tests::a_refusal_survives_the_wire ... ok
[INFO] [stdout] test program::abi::tests::an_all_zero_intent_means_do_nothing ... ok
[INFO] [stdout] test program::abi::tests::intent_round_trips ... ok
[INFO] [stdout] test program::abi::tests::perception_round_trips ... ok
[INFO] [stdout] test program::abi::tests::the_layout_adds_up ... ok
[INFO] [stdout] test program::abi::tests::the_outbox_is_read_back ... ok
[INFO] [stdout] test program::message::tests::a_delta_is_all_a_reply_needs ... ok
[INFO] [stdout] test program::message::tests::deltas_clamp_rather_than_wrap ... ok
[INFO] [stdout] test program::set::tests::a_new_game_starts_with_the_builtins ... ok
[INFO] [stdout] test program::set::tests::different_bytes_are_different_programs ... ok
[INFO] [stdout] test program::abi::tests::the_ground_survives_the_wire ... ok
[INFO] [stdout] test program::set::tests::registering_the_same_bytes_twice_is_free ... ok
[INFO] [stdout] test program::abi::tests::a_step_in_a_direction_that_does_not_exist_is_nonsense_not_east ... ok
[INFO] [stdout] test program::set::tests::the_digest_ignores_insertion_order ... ok
[INFO] [stdout] test program::abi::tests::the_inbox_reaches_the_wire_with_its_delta ... ok
[INFO] [stdout] test program::abi::tests::an_empty_outbox_slot_says_nothing ... ok
[INFO] [stdout] test program::tests::ids_render_short_and_stable ... ok
[INFO] [stdout] test program::tests::the_id_is_the_content ... ok
[INFO] [stdout] test program::set::tests::the_registry_is_capped ... ok
[INFO] [stdout] test program::vm::tests::a_module_missing_the_abi_is_refused ... ok
[INFO] [stdout] test program::vm::tests::a_mismatched_abi_is_refused ... ok
[INFO] [stdout] test program::abi::tests::the_abi_version_is_stamped_into_every_perception ... ok
[INFO] [stdout] test program::vm::tests::a_minimal_program_returns_an_intent ... ok
[INFO] [stdout] test program::vm::tests::a_verb_this_engine_does_not_know_is_nonsense_rather_than_a_guess ... ok
[INFO] [stdout] test program::vm::tests::a_program_can_read_its_perception ... ok
[INFO] [stdout] test program::vm::tests::a_program_that_writes_nothing_does_nothing ... ok
[INFO] [stdout] test program::vm::tests::a_program_can_speak ... ok
[INFO] [stdout] test program::vm::tests::an_oversized_module_is_refused ... ok
[INFO] [stdout] test program::vm::tests::floating_point_is_refused_at_the_door ... ok
[INFO] [stdout] test program::vm::tests::imports_are_refused ... ok
[INFO] [stdout] test program::vm::tests::memory_is_wiped_between_calls ... ok
[INFO] [stdout] test program::vm::tests::ground_the_unit_cannot_see_is_not_ground_it_can_walk_on ... ok
[INFO] [stdout] test program::vm::tests::nonsense_bytes_are_refused ... ok
[INFO] [stdout] test program::vm::tests::overlapping_buffers_are_refused ... ok
[INFO] [stdout] test program::set::tests::every_builtin_actually_loads ... ok
[INFO] [stdout] test program::vm::tests::a_half_finished_thought_is_not_a_memory ... ok
[INFO] [stdout] test program::vm::tests::identical_calls_give_identical_results ... ok
[INFO] [stdout] test program::vm::tests::two_units_sharing_a_program_do_not_share_scratch ... ok
[INFO] [stdout] test program::vm::tests::the_thread_local_can_be_reset ... ok
[INFO] [stdout] test replay::tests::every_command_variant_survives_the_text_format ... ok
[INFO] [stdout] test replay::tests::rejects_a_file_without_the_magic_line ... ok
[INFO] [stdout] test replay::tests::encode_decode_round_trips ... ok
[INFO] [stdout] test rng::tests::range_stays_in_bounds ... ok
[INFO] [stdout] test rng::tests::same_seed_same_sequence ... ok
[INFO] [stdout] test rules::tests::every_unit_has_a_builder_and_a_price ... ok
[INFO] [stdout] test rules::tests::names_round_trip ... ok
[INFO] [stdout] test rules::tests::prerequisites_are_acyclic_and_reachable ... ok
[INFO] [stdout] test program::vm::tests::writing_out_of_bounds_traps_and_is_contained ... ok
[INFO] [stdout] test setup::tests::builds_a_consistent_initial_state ... ok
[INFO] [stdout] test setup::tests::placement_requires_free_buildable_rock_next_to_the_base ... ok
[INFO] [stdout] test setup::tests::rejects_a_unit_spawned_in_a_mountain ... ok
[INFO] [stdout] test setup::tests::spice_lands_only_on_spice_terrain_and_within_range ... ok
[INFO] [stdout] test setup::tests::the_same_seed_produces_the_same_spice ... ok
[INFO] [stdout] test state::tests::a_moving_unit_holds_both_tiles ... ok
[INFO] [stdout] test setup::tests::rejects_overlapping_starting_structures ... ok
[INFO] [stdout] test program::vm::tests::scratch_persists_across_calls ... ok
[INFO] [stdout] test program::vm::tests::an_endless_program_runs_out_of_fuel ... ok
[INFO] [stdout] test state::tests::occupancy_covers_the_whole_structure_footprint ... ok
[INFO] [stdout] test state::tests::spice_is_seeded_only_on_spice_terrain ... ok
[INFO] [stdout] test replay::tests::detects_a_tampered_checkpoint ... ok
[INFO] [stdout] test view::tests::a_player_cannot_see_its_own_units ... ok
[INFO] [stdout] test state::tests::capacity_and_power_are_derived_from_structures ... ok
[INFO] [stdout] test view::tests::a_player_sees_its_own_buildings_and_nothing_of_the_other_side ... ok
[INFO] [stdout] test view::tests::the_edge_of_the_world_is_distinguishable_from_unseen_ground ... ok
[INFO] [stdout] test view::tests::ground_beyond_base_sight_is_unknown_not_empty ... ok
[INFO] [stdout] test view::tests::every_legal_site_is_one_the_player_has_looked_at ... ok
[INFO] [stdout] test view::tests::voice_reaches_exactly_as_far_as_sight ... ok
[INFO] [stdout] test view::tests::losing_the_yard_is_a_blackout_not_an_empty_view ... ok
[INFO] [stdout] test view::tests::spice_is_a_quantity_that_mining_empties_without_changing_the_ground ... ok
[INFO] [stdout] test view::tests::the_yards_own_tiles_read_as_the_yard ... ok
[INFO] [stdout] test replay::tests::verifies_a_recording_of_itself ... ok
[INFO] [stdout] test program::vm::tests::a_reused_vm_matches_a_fresh_one ... ok
[INFO] [stdout] test program::vm::tests::the_cache_is_bounded ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 94 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/dune2.rs (/opt/rustwide/target/debug/deps/dune2-e0751084d4475795)
[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/commander.rs (/opt/rustwide/target/debug/deps/commander-0f57b5e92fe2fb3f)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test a_report_arrives_in_the_senders_frame_and_is_understood_in_the_players ... ok
[INFO] [stdout] test a_base_in_a_corner_can_see_which_way_the_world_ends ... ok
[INFO] [stdout] test a_difference_of_two_deltas_mirrors_exactly ... ok
[INFO] [stdout] test a_worked_out_field_is_forgotten ... ok
[INFO] [stdout] test a_bot_cannot_see_its_own_army ... ok
[INFO] [stdout] test only_friendly_occupants_hear_anything ... ok
[INFO] [stdout] test the_two_players_frames_are_one_tile_out_of_step_and_that_is_deliberate ... ok
[INFO] [stdout] test an_enemy_sighting_is_where_the_army_gets_sent ... ok
[INFO] [stdout] test the_view_is_the_only_thing_the_bot_gets ... ok
[INFO] [stdout] test with_no_report_the_bot_admits_it_does_not_know ... ok
[INFO] [stdout] test a_saturated_inbox_says_so_rather_than_looking_quiet ... ok
[INFO] [stdout] test losing_the_yard_takes_the_bot_off_the_air_entirely ... ok
[INFO] [stdout] test nothing_the_bot_says_can_move_a_unit ... ok
[INFO] [stdout] test the_two_sides_build_mirrored_bases ... ok
[INFO] [stdout] test the_bot_only_builds_on_ground_it_has_looked_at ... ok
[INFO] [stdout] test the_bot_only_ever_speaks_to_squares_it_can_reach ... ok
[INFO] [stdout] test the_bot_never_asks_for_a_placement_the_engine_would_refuse ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 24.47s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/determinism.rs (/opt/rustwide/target/debug/deps/determinism-75842334f0cd58d3)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test nobody_hears_anything_the_same_tick_it_was_said ... ok
[INFO] [stdout] test stepping_never_mutates_the_state_it_was_given ... ok
[INFO] [stdout] test a_state_can_be_forked_and_resumed ... ok
[INFO] [stdout] test command_order_within_a_tick_is_respected ... ok
[INFO] [stdout] test the_same_seed_replays_identically ... ok
[INFO] [stdout] test different_seeds_produce_different_games ... ok
[INFO] [stdout] test a_long_match_stays_reproducible has been running for over 60 seconds
[INFO] [stdout] test both_shipped_maps_produce_a_decisive_game has been running for over 60 seconds
[INFO] [stdout] test every_bot_command_is_legal has been running for over 60 seconds
[INFO] [stdout] test no_unit_paces_the_same_two_tiles has been running for over 60 seconds
[INFO] [stdout] test every_bot_command_is_legal ... ok
[INFO] [stdout] test both_shipped_maps_produce_a_decisive_game ... ok
[INFO] [stdout] test no_unit_paces_the_same_two_tiles ... ok
[INFO] [stdout] test a_long_match_stays_reproducible ... ok
[INFO] [stderr]      Running tests/mechanics.rs (/opt/rustwide/target/debug/deps/mechanics-cae7ff7adf258722)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 166.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 48 tests
[INFO] [stdout] test a_destroyed_structure_frees_its_tiles ... ok
[INFO] [stdout] test a_harvester_drives_into_the_refinery ... ok
[INFO] [stdout] test a_player_cannot_radio_a_tile_it_cannot_see ... ok
[INFO] [stdout] test a_bigger_program_and_more_memory_cost_more_and_the_refund_matches ... ok
[INFO] [stdout] test a_player_cannot_radio_the_other_sides_units ... ok
[INFO] [stdout] test a_finished_structure_waits_for_a_placement_command ... ok
[INFO] [stdout] test a_bay_serves_one_unit_at_a_time ... ok
[INFO] [stdout] test a_bay_does_not_patch_up_the_other_side ... ok
[INFO] [stdout] test a_refinery_takes_in_whoever_is_standing_there_with_spice ... ok
[INFO] [stdout] test a_refinery_will_not_be_ordered_without_a_program_for_its_harvester ... ok
[INFO] [stdout] test a_shot_lands_on_whatever_is_at_the_offset ... ok
[INFO] [stdout] test a_silo_raises_the_storage_ceiling ... ok
[INFO] [stdout] test a_step_into_a_mountain_is_refused ... ok
[INFO] [stdout] test a_step_off_the_map_or_onto_somebody_is_refused ... ok
[INFO] [stdout] test a_bay_repairs_a_unit_parked_against_it ... ok
[INFO] [stdout] test a_step_takes_exactly_one_tile_and_the_time_the_stats_say ... ok
[INFO] [stdout] test a_refinerys_crew_program_is_capped_rather_than_charged ... ok
[INFO] [stdout] test a_unit_cannot_step_onto_a_building ... ok
[INFO] [stdout] test a_unit_cannot_step_while_it_is_already_between_tiles ... ok
[INFO] [stdout] test a_full_repair_costs_a_tenth_of_the_unit ... ok
[INFO] [stdout] test a_unit_inside_a_building_is_off_the_map ... ok
[INFO] [stdout] test a_queue_of_harvesters_all_get_through ... ok
[INFO] [stdout] test a_trike_is_faster_than_a_tank ... ok
[INFO] [stdout] test a_unit_does_nothing_at_all_unless_something_tells_it_to ... ok
[INFO] [stdout] test a_unit_will_not_shoot_its_own_side_or_thin_air ... ok
[INFO] [stdout] test a_unit_walks_to_where_it_is_driven ... ok
[INFO] [stdout] test an_empty_harvester_is_not_taken_in ... ok
[INFO] [stdout] test a_player_who_cannot_pay_does_not_get_repairs_for_free ... ok
[INFO] [stdout] test a_unit_inside_cannot_be_shot_and_cannot_shoot ... ok
[INFO] [stdout] test cancelling_production_refunds_what_was_paid ... ok
[INFO] [stdout] test losing_the_bay_takes_whatever_is_inside_with_it ... ok
[INFO] [stdout] test destroying_everything_wins_the_game ... ok
[INFO] [stdout] test an_idle_bay_costs_nothing ... ok
[INFO] [stdout] test mining_worked_out_ground_is_refused ... ok
[INFO] [stdout] test a_unit_on_the_free_tier_costs_exactly_its_hull ... ok
[INFO] [stdout] test prerequisites_are_enforced ... ok
[INFO] [stdout] test mining_a_named_tile_moves_spice_from_the_ground_into_the_hold ... ok
[INFO] [stdout] test losing_the_yard_refuses_every_command_at_once ... ok
[INFO] [stdout] test radioing_an_empty_tile_looks_exactly_like_radioing_an_occupied_one ... ok
[INFO] [stdout] test storage_is_capped_and_the_overflow_is_reported ... ok
[INFO] [stdout] test a_tank_kills_a_trike_in_the_expected_number_of_shots ... ok
[INFO] [stdout] test illegal_placements_are_rejected_rather_than_obeyed ... ok
[INFO] [stdout] test structures_cannot_be_built_on_sand ... ok
[INFO] [stdout] test a_power_deficit_halves_production ... ok
[INFO] [stdout] test producing_a_unit_costs_exactly_its_price ... ok
[INFO] [stdout] test units_never_share_a_tile ... ok
[INFO] [stdout] test a_boxed_in_factory_still_gets_its_unit_out ... ok
[INFO] [stdout] test only_the_memory_a_unit_paid_for_survives_a_call ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/programs.rs (/opt/rustwide/target/debug/deps/programs-3dcec276393eb4c1)
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test a_housed_units_program_does_not_run ... ok
[INFO] [stdout] test a_call_that_never_finished_leaves_the_record_exactly_as_it_was ... ok
[INFO] [stdout] test a_message_carries_the_way_back_to_where_it_came_from ... ok
[INFO] [stdout] test a_message_to_an_empty_tile_goes_nowhere_and_says_nothing ... ok
[INFO] [stdout] test a_new_game_holds_exactly_the_builtins ... ok
[INFO] [stdout] test a_player_command_becomes_a_message_not_an_order ... ok
[INFO] [stdout] test a_fighter_with_a_bay_to_reach_does_break_off ... ok
[INFO] [stdout] test a_program_can_ignore_the_tactics_library_entirely ... ok
[INFO] [stdout] test a_program_drives_its_unit ... ok
[INFO] [stdout] test a_fighter_with_nowhere_to_be_repaired_keeps_fighting ... ok
[INFO] [stdout] test a_program_that_never_decides_is_not_at_fault ... ok
[INFO] [stdout] test a_program_finds_spice_by_reading_the_ground ... ok
[INFO] [stdout] test a_program_may_obey_the_radio_or_ignore_it ... ok
[INFO] [stdout] test a_hurt_fighter_withdraws_heals_and_returns ... ok
[INFO] [stdout] test a_refused_step_is_reported_so_dead_reckoning_stays_exact ... ok
[INFO] [stdout] test a_repair_bay_out_of_sight_is_a_repair_bay_unknown ... ok
[INFO] [stdout] test a_bay_that_cannot_pay_gives_the_unit_back ... ok
[INFO] [stdout] test a_program_that_thinks_too_hard_too_often_runs_dry ... ok
[INFO] [stdout] test a_trapping_program_is_contained ... ok
[INFO] [stdout] test a_runaway_program_is_cut_off_and_the_game_carries_on ... ok
[INFO] [stdout] test a_unit_can_see_its_own_budget ... ok
[INFO] [stdout] test a_unit_pointing_at_a_missing_program_falls_back_to_the_engine ... ok
[INFO] [stdout] test a_useful_harvester_fits_in_two_kilobytes ... ok
[INFO] [stdout] test an_impossible_intent_is_refused ... ok
[INFO] [stdout] test a_refinery_arrives_with_a_harvester_running_the_chosen_program ... ok
[INFO] [stdout] test an_unprogrammed_unit_simply_does_nothing ... ok
[INFO] [stdout] test a_harvester_can_see_no_further_than_its_sensors ... ok
[INFO] [stdout] test ground_a_unit_cannot_see_reads_as_unknown_rather_than_as_empty ... ok
[INFO] [stdout] test nobody_hears_a_message_aimed_past_the_edge_of_the_base ... ok
[INFO] [stdout] test nonsense_code_is_refused_before_any_money_moves ... ok
[INFO] [stdout] test a_unit_finds_its_way_back_by_counting_its_own_steps ... ok
[INFO] [stdout] test a_shared_cache_does_not_leak_between_games ... ok
[INFO] [stdout] test scratch_is_private_persistent_and_part_of_the_world ... ok
[INFO] [stdout] test the_bucket_fills_at_the_stated_rate_and_stops_at_the_cap ... ok
[INFO] [stdout] test the_building_pushes_the_unit_out_when_it_is_done ... ok
[INFO] [stdout] test the_builtin_fighter_picks_its_own_target ... ok
[INFO] [stdout] test the_builtin_harvester_completes_a_round_trip ... ok
[INFO] [stdout] test ordering_a_unit_chooses_the_program_it_will_run ... ok
[INFO] [stdout] test the_engine_never_names_a_tile_for_a_harvester ... ok
[INFO] [stdout] test the_program_digest_reaches_the_state_hash ... ok
[INFO] [stdout] test the_builtin_harvester_walks_around_its_own_refinery ... ok
[INFO] [stdout] test the_starter_harvester_completes_a_round_trip ... ok
[INFO] [stdout] test thinking_costs_fuel ... ok
[INFO] [stdout] test the_vm_is_only_a_cache ... ok
[INFO] [stdout] test withdrawing_has_hysteresis ... ok
[INFO] [stdout] test a_recorded_program_driven_match_verifies ... ok
[INFO] [stdout] test a_building_never_holds_more_than_one_unit has been running for over 60 seconds
[INFO] [stdout] test a_full_match_runs_without_programs_faulting has been running for over 60 seconds
[INFO] [stdout] test bots_build_a_repair_bay_and_units_use_it has been running for over 60 seconds
[INFO] [stdout] test bots_still_work_with_programmed_units has been running for over 60 seconds
[INFO] [stdout] test running_programs_does_not_make_the_simulation_slow has been running for over 60 seconds
[INFO] [stdout] test units_go_inside_buildings_during_a_real_match has been running for over 60 seconds
[INFO] [stdout] test bots_still_work_with_programmed_units ... ok
[INFO] [stdout] test a_building_never_holds_more_than_one_unit ... ok
[INFO] [stdout] test a_full_match_runs_without_programs_faulting ... ok
[INFO] [stdout] test running_programs_does_not_make_the_simulation_slow ... FAILED
[INFO] [stdout] test units_go_inside_buildings_during_a_real_match ... ok
[INFO] [stdout] test bots_build_a_repair_bay_and_units_use_it ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- running_programs_does_not_make_the_simulation_slow stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'running_programs_does_not_make_the_simulation_slow' (745) panicked at tests/programs.rs:782:5:
[INFO] [stdout] only 246 ticks/s — something has gone badly wrong in the program phase
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61e97620f741 - std[70759c8f55707aa4]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61e97620f741 - std[70759c8f55707aa4]::backtrace_rs::backtrace::trace_unsynchronized::<std[70759c8f55707aa4]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61e97620f741 - std[70759c8f55707aa4]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61e97620f741 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61e9762263ea - <core[df12db4294e9bfd3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61e9762263ea - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    6:     0x61e976213cac - core[df12db4294e9bfd3]::io::write::default_write_fmt::<alloc[2182bb758b4b3781]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x61e976213cac - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x61e9761eb926 - <std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61e9761eb926 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61e976207b19 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61e975ce3320 - <alloc[2182bb758b4b3781]::boxed::Box<dyn for<'a, 'b> core[df12db4294e9bfd3]::ops::function::Fn<(&'a std[70759c8f55707aa4]::panic::PanicHookInfo<'b>,), Output = ()> + core[df12db4294e9bfd3]::marker::Send + core[df12db4294e9bfd3]::marker::Sync> as core[df12db4294e9bfd3]::ops::function::Fn<(&std[70759c8f55707aa4]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2334:9
[INFO] [stdout]   12:     0x61e975ce3320 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61e976207cd2 - <alloc[2182bb758b4b3781]::boxed::Box<dyn for<'a, 'b> core[df12db4294e9bfd3]::ops::function::Fn<(&'a std[70759c8f55707aa4]::panic::PanicHookInfo<'b>,), Output = ()> + core[df12db4294e9bfd3]::marker::Send + core[df12db4294e9bfd3]::marker::Sync> as core[df12db4294e9bfd3]::ops::function::Fn<(&std[70759c8f55707aa4]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2334:9
[INFO] [stdout]   14:     0x61e976207cd2 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x61e9761eb9d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x61e9761e4599 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61e9761ec5cd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x61e976226c3c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61e975cbf42b - programs[f26846d25c0c5f39]::running_programs_does_not_make_the_simulation_slow
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/programs.rs:782:5
[INFO] [stdout]   20:     0x61e975cb2107 - programs[f26846d25c0c5f39]::running_programs_does_not_make_the_simulation_slow::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/programs.rs:761:56
[INFO] [stdout]   21:     0x61e975ccb4a6 - <programs[f26846d25c0c5f39]::running_programs_does_not_make_the_simulation_slow::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x61e975cd660b - <fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x61e975cd660b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:733:18
[INFO] [stdout]   24:     0x61e975ce3c75 - test[9d35eded1c95d3be]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:756:74
[INFO] [stdout]   25:     0x61e975ce3c75 - <core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x61e975ce3c75 - std[70759c8f55707aa4]::panicking::catch_unwind::do_call::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>, core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:576:43
[INFO] [stdout]   27:     0x61e975ce3c75 - std[70759c8f55707aa4]::panicking::catch_unwind::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x61e975ce3c75 - std[70759c8f55707aa4]::panic::catch_unwind::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>, core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x61e975ce3c75 - test[9d35eded1c95d3be]::run_test_in_process
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:756:27
[INFO] [stdout]   30:     0x61e975ce3c75 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:677:43
[INFO] [stdout]   31:     0x61e975cdd534 - test[9d35eded1c95d3be]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:707:41
[INFO] [stdout]   32:     0x61e975cdd534 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x61e975ce6dd2 - std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x61e975ce6dd2 - <core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x61e975ce6dd2 - std[70759c8f55707aa4]::panicking::catch_unwind::do_call::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:576:43
[INFO] [stdout]   36:     0x61e975ce6dd2 - std[70759c8f55707aa4]::panicking::catch_unwind::<(), core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x61e975ce6dd2 - std[70759c8f55707aa4]::panic::catch_unwind::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x61e975ce6dd2 - std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x61e975ce6dd2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x61e97620eca9 - <alloc[2182bb758b4b3781]::boxed::Box<dyn core[df12db4294e9bfd3]::ops::function::FnOnce<(), Output = ()> + core[df12db4294e9bfd3]::marker::Send> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2320:9
[INFO] [stdout]   41:     0x61e97620eca9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   42:     0x72f80c521dfa - <unknown>
[INFO] [stdout]   43:     0x72f80c5b53d4 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     running_programs_does_not_make_the_simulation_slow
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 51 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 170.17s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test programs`
[INFO] running `Command { std: "docker" "inspect" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad", kill_on_drop: false }`
[INFO] [stdout] 1b4258258e4bea4cf48cd28dd1c2556613f20601e9b99449f974d0d9ac0b56ad
