[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.100.0-beta.1 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-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/sashee/dune2-rs 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/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.100.0-beta.1" "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/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27
[INFO] running `Command { std: "docker" "start" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", 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" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27" "/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" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", 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" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "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 wasmparser v0.239.0
[INFO] [stderr]    Compiling bumpalo v3.20.3
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling spin v0.9.9
[INFO] [stderr]    Compiling wasm-encoder v0.255.0
[INFO] [stderr]    Compiling wasmi_collections v1.1.0
[INFO] [stderr]    Compiling wasmi_core v1.1.0
[INFO] [stderr]    Compiling wast v255.0.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.53s
[INFO] running `Command { std: "docker" "inspect" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", 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" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "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 9.40s
[INFO] running `Command { std: "docker" "inspect" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", 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" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/dune2/8026401298cd9ba7/out/dune2-8026401298cd9ba7)
[INFO] [stdout] 
[INFO] [stdout] running 94 tests
[INFO] [stdout] test ai::protocol::tests::a_sender_relative_delta_composes_through_from ... ok
[INFO] [stdout] test ai::world::tests::harvesters_are_counted_apart_from_the_army ... 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::world::tests::the_headcount_decays_rather_than_collapsing ... ok
[INFO] [stdout] test ai::world::tests::a_sighting_goes_off_but_a_building_does_not ... ok
[INFO] [stdout] test geom::tests::chebyshev_is_the_range_metric ... ok
[INFO] [stdout] test geom::tests::ring_surrounds_the_rect_exactly_once ... ok
[INFO] [stdout] test ground::tests::all_four_states_survive_the_wire ... ok
[INFO] [stdout] test ground::tests::worked_out_spice_is_still_spice_terrain ... ok
[INFO] [stdout] test ground::tests::unknown_is_not_empty_and_neither_is_off_map ... ok
[INFO] [stdout] test hash::tests::identical_states_hash_identically ... ok
[INFO] [stdout] test hash::tests::a_moved_unit_changes_the_hash ... ok
[INFO] [stdout] test hash::tests::a_single_hit_point_changes_the_hash ... ok
[INFO] [stdout] test ai::protocol::tests::an_empty_payload_is_not_one_of_ours ... ok
[INFO] [stdout] test map::tests::parses_a_scenario ... ok
[INFO] [stdout] test hash::tests::events_do_not_affect_the_hash ... ok
[INFO] [stdout] test map::tests::out_of_bounds_reads_as_mountain ... ok
[INFO] [stdout] test map::tests::rejects_a_structure_named_as_a_unit ... ok
[INFO] [stdout] test map::tests::rejects_unknown_terrain ... ok
[INFO] [stdout] test map::tests::rejects_a_short_terrain_row ... ok
[INFO] [stdout] test program::abi::tests::a_refusal_survives_the_wire ... 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::abi::tests::the_ground_survives_the_wire ... 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::abi::tests::an_all_zero_intent_means_do_nothing ... ok
[INFO] [stdout] test program::message::tests::deltas_clamp_rather_than_wrap ... ok
[INFO] [stdout] test program::abi::tests::intent_round_trips ... ok
[INFO] [stdout] test program::abi::tests::the_abi_version_is_stamped_into_every_perception ... ok
[INFO] [stdout] test program::abi::tests::the_inbox_reaches_the_wire_with_its_delta ... 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::tests::ids_render_short_and_stable ... ok
[INFO] [stdout] test program::abi::tests::an_empty_outbox_slot_says_nothing ... ok
[INFO] [stdout] test program::set::tests::registering_the_same_bytes_twice_is_free ... ok
[INFO] [stdout] test program::abi::tests::perception_round_trips ... ok
[INFO] [stdout] test program::set::tests::the_digest_ignores_insertion_order ... ok
[INFO] [stdout] test program::set::tests::the_registry_is_capped ... ok
[INFO] [stdout] test program::vm::tests::a_mismatched_abi_is_refused ... ok
[INFO] [stdout] test program::vm::tests::a_program_can_read_its_perception ... ok
[INFO] [stdout] test program::vm::tests::a_module_missing_the_abi_is_refused ... ok
[INFO] [stdout] test program::tests::the_id_is_the_content ... 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::a_minimal_program_returns_an_intent ... ok
[INFO] [stdout] test program::vm::tests::a_program_that_writes_nothing_does_nothing ... ok
[INFO] [stdout] test program::vm::tests::floating_point_is_refused_at_the_door ... 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::a_verb_this_engine_does_not_know_is_nonsense_rather_than_a_guess ... ok
[INFO] [stdout] test program::vm::tests::nonsense_bytes_are_refused ... 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::overlapping_buffers_are_refused ... ok
[INFO] [stdout] test program::vm::tests::identical_calls_give_identical_results ... ok
[INFO] [stdout] test program::vm::tests::a_half_finished_thought_is_not_a_memory ... ok
[INFO] [stdout] test program::vm::tests::two_units_sharing_a_program_do_not_share_scratch ... ok
[INFO] [stdout] test program::set::tests::every_builtin_actually_loads ... ok
[INFO] [stdout] test replay::tests::encode_decode_round_trips ... ok
[INFO] [stdout] test replay::tests::every_command_variant_survives_the_text_format ... ok
[INFO] [stdout] test program::vm::tests::an_endless_program_runs_out_of_fuel ... ok
[INFO] [stdout] test replay::tests::rejects_a_file_without_the_magic_line ... ok
[INFO] [stdout] test rng::tests::range_stays_in_bounds ... ok
[INFO] [stdout] test rng::tests::same_seed_same_sequence ... ok
[INFO] [stdout] test program::vm::tests::scratch_persists_across_calls ... ok
[INFO] [stdout] test rules::tests::every_unit_has_a_builder_and_a_price ... 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_overlapping_starting_structures ... 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 rules::tests::names_round_trip ... ok
[INFO] [stdout] test state::tests::spice_is_seeded_only_on_spice_terrain ... ok
[INFO] [stdout] test view::tests::a_player_cannot_see_its_own_units ... ok
[INFO] [stdout] test view::tests::a_player_sees_its_own_buildings_and_nothing_of_the_other_side ... ok
[INFO] [stdout] test view::tests::every_legal_site_is_one_the_player_has_looked_at ... ok
[INFO] [stdout] test view::tests::ground_beyond_base_sight_is_unknown_not_empty ... 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_edge_of_the_world_is_distinguishable_from_unseen_ground ... ok
[INFO] [stdout] test view::tests::the_yards_own_tiles_read_as_the_yard ... ok
[INFO] [stdout] test view::tests::voice_reaches_exactly_as_far_as_sight ... ok
[INFO] [stdout] test replay::tests::detects_a_tampered_checkpoint ... ok
[INFO] [stdout] test state::tests::capacity_and_power_are_derived_from_structures ... ok
[INFO] [stdout] test state::tests::occupancy_covers_the_whole_structure_footprint ... ok
[INFO] [stdout] test replay::tests::verifies_a_recording_of_itself ... ok
[INFO] [stdout] test program::vm::tests::the_thread_local_can_be_reset ... 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.16s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/dune2.rs (/opt/rustwide/target/debug/build/dune2/e0751084d4475795/out/dune2-e0751084d4475795)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/commander.rs (/opt/rustwide/target/debug/build/dune2/0f57b5e92fe2fb3f/out/commander-0f57b5e92fe2fb3f)
[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] [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_bot_cannot_see_its_own_army ... ok
[INFO] [stdout] test only_friendly_occupants_hear_anything ... 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 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 21.31s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/determinism.rs (/opt/rustwide/target/debug/build/dune2/75842334f0cd58d3/out/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] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 165.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/mechanics.rs (/opt/rustwide/target/debug/build/dune2/cae7ff7adf258722/out/mechanics-cae7ff7adf258722)
[INFO] [stdout] 
[INFO] [stdout] running 48 tests
[INFO] [stdout] test a_destroyed_structure_frees_its_tiles ... ok
[INFO] [stdout] test a_bigger_program_and_more_memory_cost_more_and_the_refund_matches ... 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_bay_does_not_patch_up_the_other_side ... ok
[INFO] [stdout] test a_bay_serves_one_unit_at_a_time ... ok
[INFO] [stdout] test a_bay_repairs_a_unit_parked_against_it ... ok
[INFO] [stdout] test a_player_cannot_radio_the_other_sides_units ... ok
[INFO] [stdout] test a_refinery_takes_in_whoever_is_standing_there_with_spice ... ok
[INFO] [stdout] test a_finished_structure_waits_for_a_placement_command ... 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_refinerys_crew_program_is_capped_rather_than_charged ... ok
[INFO] [stdout] test a_refinery_will_not_be_ordered_without_a_program_for_its_harvester ... ok
[INFO] [stdout] test a_step_takes_exactly_one_tile_and_the_time_the_stats_say ... ok
[INFO] [stdout] test a_step_off_the_map_or_onto_somebody_is_refused ... ok
[INFO] [stdout] test a_step_into_a_mountain_is_refused ... ok
[INFO] [stdout] test a_trike_is_faster_than_a_tank ... ok
[INFO] [stdout] test a_full_repair_costs_a_tenth_of_the_unit ... ok
[INFO] [stdout] test a_unit_cannot_step_while_it_is_already_between_tiles ... ok
[INFO] [stdout] test a_queue_of_harvesters_all_get_through ... ok
[INFO] [stdout] test a_unit_cannot_step_onto_a_building ... ok
[INFO] [stdout] test a_unit_does_nothing_at_all_unless_something_tells_it_to ... ok
[INFO] [stdout] test a_unit_walks_to_where_it_is_driven ... ok
[INFO] [stdout] test a_unit_will_not_shoot_its_own_side_or_thin_air ... ok
[INFO] [stdout] test a_player_who_cannot_pay_does_not_get_repairs_for_free ... ok
[INFO] [stdout] test an_empty_harvester_is_not_taken_in ... ok
[INFO] [stdout] test a_unit_on_the_free_tier_costs_exactly_its_hull ... ok
[INFO] [stdout] test a_unit_inside_a_building_is_off_the_map ... ok
[INFO] [stdout] test an_idle_bay_costs_nothing ... 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 mining_a_named_tile_moves_spice_from_the_ground_into_the_hold ... ok
[INFO] [stdout] test mining_worked_out_ground_is_refused ... ok
[INFO] [stdout] test losing_the_yard_refuses_every_command_at_once ... ok
[INFO] [stdout] test prerequisites_are_enforced ... 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 storage_is_capped_and_the_overflow_is_reported ... ok
[INFO] [stdout] test radioing_an_empty_tile_looks_exactly_like_radioing_an_occupied_one ... ok
[INFO] [stdout] test a_tank_kills_a_trike_in_the_expected_number_of_shots ... ok
[INFO] [stdout] test structures_cannot_be_built_on_sand ... ok
[INFO] [stdout] test illegal_placements_are_rejected_rather_than_obeyed ... 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 only_the_memory_a_unit_paid_for_survives_a_call ... ok
[INFO] [stdout] test a_boxed_in_factory_still_gets_its_unit_out ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 48 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.19s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/programs.rs (/opt/rustwide/target/debug/build/dune2/3dcec276393eb4c1/out/programs-3dcec276393eb4c1)
[INFO] [stdout] running 52 tests
[INFO] [stdout] test a_housed_units_program_does_not_run ... ok
[INFO] [stdout] test a_fighter_with_nowhere_to_be_repaired_keeps_fighting ... 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_call_that_never_finished_leaves_the_record_exactly_as_it_was ... ok
[INFO] [stdout] test a_program_can_ignore_the_tactics_library_entirely ... ok
[INFO] [stdout] test a_fighter_with_a_bay_to_reach_does_break_off ... ok
[INFO] [stdout] test a_program_drives_its_unit ... ok
[INFO] [stdout] test a_program_finds_spice_by_reading_the_ground ... ok
[INFO] [stdout] test a_program_that_never_decides_is_not_at_fault ... 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_harvester_can_see_no_further_than_its_sensors ... ok
[INFO] [stdout] test a_bay_that_cannot_pay_gives_the_unit_back ... ok
[INFO] [stdout] test a_trapping_program_is_contained ... ok
[INFO] [stdout] test a_program_that_thinks_too_hard_too_often_runs_dry ... 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_runaway_program_is_cut_off_and_the_game_carries_on ... ok
[INFO] [stdout] test an_unprogrammed_unit_simply_does_nothing ... ok
[INFO] [stdout] test a_unit_finds_its_way_back_by_counting_its_own_steps ... 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_refinery_arrives_with_a_harvester_running_the_chosen_program ... 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 the_builtin_harvester_walks_around_its_own_refinery ... 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 ordering_a_unit_chooses_the_program_it_will_run ... 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_full_match_runs_without_programs_faulting ... ok
[INFO] [stdout] test a_building_never_holds_more_than_one_unit ... 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 240 ticks/s — something has gone badly wrong in the program phase
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61bf3df9e841 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61bf3df9e841 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61bf3df9e841 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61bf3df9e841 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61bf3dfb522a - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61bf3dfb522a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x61bf3dfa2b2c - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x61bf3dfa2b2c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x61bf3df7a8a6 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61bf3df7a8a6 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x61bf3df96b59 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x61bf3da75870 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x61bf3da75870 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x61bf3df96d12 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x61bf3df96d12 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x61bf3df7a952 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x61bf3df734a9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61bf3df7b55d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x61bf3dfb5a7c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61bf3da51e1b - programs[25861bffeab48355]::running_programs_does_not_make_the_simulation_slow
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/programs.rs:782:5
[INFO] [stdout]   20:     0x61bf3da44817 - programs[25861bffeab48355]::running_programs_does_not_make_the_simulation_slow::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/programs.rs:761:56
[INFO] [stdout]   21:     0x61bf3da64dc6 - <programs[25861bffeab48355]::running_programs_does_not_make_the_simulation_slow::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x61bf3da68cbb - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x61bf3da68cbb - 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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   24:     0x61bf3da75e95 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   25:     0x61bf3da75e95 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x61bf3da75e95 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   27:     0x61bf3da75e95 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   28:     0x61bf3da75e95 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x61bf3da75e95 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   30:     0x61bf3da75e95 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   31:     0x61bf3da6f784 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   32:     0x61bf3da6f784 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x61bf3da78f22 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x61bf3da78f22 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x61bf3da78f22 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   36:     0x61bf3da78f22 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   37:     0x61bf3da78f22 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x61bf3da78f22 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x61bf3da78f22 - <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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x61bf3df9dd3f - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   41:     0x61bf3df9dd3f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   42:     0x7e5a4b141dfa - <unknown>
[INFO] [stdout]   43:     0x7e5a4b1d53d4 - 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.23s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test programs`
[INFO] running `Command { std: "docker" "inspect" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27", kill_on_drop: false }`
[INFO] [stdout] 32c5a912a20fe3e75bb081194c42ae15852f950d5d833a245831010305386c27
