[INFO] cloning repository https://github.com/ansxor/sb-interpreter
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ansxor/sb-interpreter" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fansxor%2Fsb-interpreter", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fansxor%2Fsb-interpreter'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 852a5473a4f58cf723de49e4bd2823f0a7a8ac11
[INFO] testing ansxor/sb-interpreter against 1.97.0-beta.6 for beta-1.98-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fansxor%2Fsb-interpreter" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/ansxor/sb-interpreter
[INFO] finished tweaking git repo https://github.com/ansxor/sb-interpreter
[INFO] tweaked toml for git repo https://github.com/ansxor/sb-interpreter written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/ansxor/sb-interpreter on toolchain 1.97.0-beta.6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/ansxor/sb-interpreter 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.97.0-beta.6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.125
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.125
[INFO] [stderr]   Downloaded gethostname v1.1.0
[INFO] [stderr]   Downloaded wasm-bindgen-futures v0.4.75
[INFO] [stderr]   Downloaded wayland-sys v0.31.11
[INFO] [stderr]   Downloaded cursor-icon v1.2.0
[INFO] [stderr]   Downloaded wayland-cursor v0.31.14
[INFO] [stderr]   Downloaded xcursor v0.3.10
[INFO] [stderr]   Downloaded ab_glyph_rasterizer v0.1.10
[INFO] [stderr]   Downloaded dlib v0.5.3
[INFO] [stderr]   Downloaded proc-macro-crate v3.5.0
[INFO] [stderr]   Downloaded drm-fourcc v2.2.0
[INFO] [stderr]   Downloaded coreaudio-sys v0.2.18
[INFO] [stderr]   Downloaded objc2-user-notifications v0.2.2
[INFO] [stderr]   Downloaded objc2-core-location v0.2.2
[INFO] [stderr]   Downloaded objc2-uniform-type-identifiers v0.2.2
[INFO] [stderr]   Downloaded objc2-link-presentation v0.2.2
[INFO] [stderr]   Downloaded dpi v0.1.2
[INFO] [stderr]   Downloaded calloop-wayland-source v0.3.0
[INFO] [stderr]   Downloaded objc2-symbols v0.2.2
[INFO] [stderr]   Downloaded jni-sys v0.3.1
[INFO] [stderr]   Downloaded objc2-io-surface v0.3.2
[INFO] [stderr]   Downloaded owned_ttf_parser v0.25.1
[INFO] [stderr]   Downloaded drm-ffi v0.9.1
[INFO] [stderr]   Downloaded wayland-protocols-wlr v0.3.12
[INFO] [stderr]   Downloaded bytemuck_derive v1.10.2
[INFO] [stderr]   Downloaded ab_glyph v0.2.32
[INFO] [stderr]   Downloaded dtor v0.8.1
[INFO] [stderr]   Downloaded num_enum_derive v0.7.6
[INFO] [stderr]   Downloaded objc2-quartz-core v0.3.2
[INFO] [stderr]   Downloaded objc2-contacts v0.2.2
[INFO] [stderr]   Downloaded tiny-xlib v0.2.5
[INFO] [stderr]   Downloaded redox_syscall v0.8.1
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.125
[INFO] [stderr]   Downloaded objc2-cloud-kit v0.2.2
[INFO] [stderr]   Downloaded num_enum v0.7.6
[INFO] [stderr]   Downloaded drm-sys v0.8.1
[INFO] [stderr]   Downloaded ctor v0.10.1
[INFO] [stderr]   Downloaded arrayvec v0.7.7
[INFO] [stderr]   Downloaded wayland-scanner v0.31.10
[INFO] [stderr]   Downloaded memmap2 v0.9.11
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.125
[INFO] [stderr]   Downloaded wayland-client v0.31.14
[INFO] [stderr]   Downloaded softbuffer v0.4.8
[INFO] [stderr]   Downloaded drm v0.14.1
[INFO] [stderr]   Downloaded wayland-protocols-plasma v0.3.12
[INFO] [stderr]   Downloaded calloop v0.13.0
[INFO] [stderr]   Downloaded sctk-adwaita v0.10.1
[INFO] [stderr]   Downloaded bytes v1.12.0
[INFO] [stderr]   Downloaded wayland-backend v0.3.15
[INFO] [stderr]   Downloaded toml_edit v0.25.12+spec-1.1.0
[INFO] [stderr]   Downloaded alsa v0.9.1
[INFO] [stderr]   Downloaded cc v1.2.65
[INFO] [stderr]   Downloaded ndk v0.9.0
[INFO] [stderr]   Downloaded objc2-core-graphics v0.3.2
[INFO] [stderr]   Downloaded memchr v2.8.2
[INFO] [stderr]   Downloaded js-sys v0.3.102
[INFO] [stderr]   Downloaded unicode-segmentation v1.13.3
[INFO] [stderr]   Downloaded smithay-client-toolkit v0.19.2
[INFO] [stderr]   Downloaded wasip2 v1.0.4+wasi-0.2.12
[INFO] [stderr]   Downloaded wayland-protocols v0.32.13
[INFO] [stderr]   Downloaded regex v1.12.4
[INFO] [stderr]   Downloaded objc2-core-foundation v0.3.2
[INFO] [stderr]   Downloaded winnow v1.0.3
[INFO] [stderr]   Downloaded quick-xml v0.39.4
[INFO] [stderr]   Downloaded x11rb v0.13.2
[INFO] [stderr]   Downloaded objc2-ui-kit v0.2.2
[INFO] [stderr]   Downloaded android-activity v0.6.1
[INFO] [stderr]   Downloaded objc2-foundation v0.3.2
[INFO] [stderr]   Downloaded ndk-sys v0.6.0+11769913
[INFO] [stderr]   Downloaded rustix v0.38.44
[INFO] [stderr]   Downloaded regex-syntax v0.8.11
[INFO] [stderr]   Downloaded x11rb-protocol v0.13.2
[INFO] [stderr]   Downloaded winit v0.30.13
[INFO] [stderr]   Downloaded web-sys v0.3.102
[INFO] [stderr]   Downloaded orbclient v0.3.55
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570
[INFO] running `Command { std: "docker" "start" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", 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" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", 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" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitflags v2.13.0
[INFO] [stderr]    Compiling pkg-config v0.3.33
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling find-msvc-tools v0.1.9
[INFO] [stderr]    Compiling shlex v2.0.1
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling downcast-rs v1.2.1
[INFO] [stderr]    Compiling scoped-tls v1.0.1
[INFO] [stderr]    Compiling memchr v2.8.2
[INFO] [stderr]    Compiling libloading v0.8.9
[INFO] [stderr]    Compiling wayland-client v0.31.14
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]    Compiling drm-sys v0.8.1
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling memmap2 v0.9.11
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling cursor-icon v1.2.0
[INFO] [stderr]    Compiling xkeysym v0.2.1
[INFO] [stderr]    Compiling linux-raw-sys v0.9.4
[INFO] [stderr]    Compiling drm-fourcc v2.2.0
[INFO] [stderr]    Compiling dlib v0.5.3
[INFO] [stderr]    Compiling strict-num v0.1.1
[INFO] [stderr]    Compiling xcursor v0.3.10
[INFO] [stderr]    Compiling cc v1.2.65
[INFO] [stderr]    Compiling smithay-client-toolkit v0.19.2
[INFO] [stderr]    Compiling quick-xml v0.39.4
[INFO] [stderr]    Compiling ttf-parser v0.25.1
[INFO] [stderr]    Compiling as-raw-xcb-connection v1.0.1
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling zerocopy v0.8.52
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling arrayvec v0.7.7
[INFO] [stderr]    Compiling ab_glyph_rasterizer v0.1.10
[INFO] [stderr]    Compiling wayland-sys v0.31.11
[INFO] [stderr]    Compiling tiny-xlib v0.2.5
[INFO] [stderr]    Compiling x11-dl v2.21.0
[INFO] [stderr]    Compiling x11rb-protocol v0.13.2
[INFO] [stderr]    Compiling winit v0.30.13
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling sb-audio v0.0.1 (/opt/rustwide/workdir/crates/sb-audio)
[INFO] [stderr]    Compiling sb-render v0.0.1 (/opt/rustwide/workdir/crates/sb-render)
[INFO] [stderr]    Compiling ctor v0.10.1
[INFO] [stderr]    Compiling raw-window-handle v0.6.2
[INFO] [stderr]    Compiling xkbcommon-dl v0.4.2
[INFO] [stderr]    Compiling smol_str v0.2.2
[INFO] [stderr]    Compiling dpi v0.1.2
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling wayland-scanner v0.31.10
[INFO] [stderr]    Compiling wayland-backend v0.3.15
[INFO] [stderr]    Compiling sb-core v0.0.1 (/opt/rustwide/workdir/crates/sb-core)
[INFO] [stderr]    Compiling bytemuck_derive v1.10.2
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling bytemuck v1.25.0
[INFO] [stderr]    Compiling owned_ttf_parser v0.25.1
[INFO] [stderr]    Compiling ab_glyph v0.2.32
[INFO] [stderr]    Compiling tiny-skia-path v0.11.4
[INFO] [stderr]    Compiling tiny-skia v0.11.4
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling sb-platform-wasm v0.0.1 (/opt/rustwide/workdir/crates/sb-platform-wasm)
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling polling v3.11.0
[INFO] [stderr]    Compiling gethostname v1.1.0
[INFO] [stderr]    Compiling drm-ffi v0.9.1
[INFO] [stderr]    Compiling calloop v0.13.0
[INFO] [stderr]    Compiling drm v0.14.1
[INFO] [stderr]    Compiling sb-spec v0.0.1 (/opt/rustwide/workdir/crates/sb-spec)
[INFO] [stderr]    Compiling wayland-csd-frame v0.3.0
[INFO] [stderr]    Compiling wayland-protocols v0.32.13
[INFO] [stderr]    Compiling wayland-cursor v0.31.14
[INFO] [stderr]    Compiling calloop-wayland-source v0.3.0
[INFO] [stderr]    Compiling x11rb v0.13.2
[INFO] [stderr]    Compiling softbuffer v0.4.8
[INFO] [stderr]    Compiling wayland-protocols-wlr v0.3.12
[INFO] [stderr]    Compiling wayland-protocols-plasma v0.3.12
[INFO] [stderr]    Compiling sctk-adwaita v0.10.1
[INFO] [stderr]    Compiling sb-platform-native v0.0.1 (/opt/rustwide/workdir/crates/sb-platform-native)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 59.45s
[INFO] running `Command { std: "docker" "inspect" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", 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" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sb-spec v0.0.1 (/opt/rustwide/workdir/crates/sb-spec)
[INFO] [stderr]    Compiling sb-core v0.0.1 (/opt/rustwide/workdir/crates/sb-core)
[INFO] [stderr]    Compiling sb-platform-wasm v0.0.1 (/opt/rustwide/workdir/crates/sb-platform-wasm)
[INFO] [stderr]    Compiling sb-render v0.0.1 (/opt/rustwide/workdir/crates/sb-render)
[INFO] [stderr]    Compiling sb-audio v0.0.1 (/opt/rustwide/workdir/crates/sb-audio)
[INFO] [stderr]    Compiling sb-platform-native v0.0.1 (/opt/rustwide/workdir/crates/sb-platform-native)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.86s
[INFO] running `Command { std: "docker" "inspect" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", 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" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570" "/opt/rustwide/cargo-home/bin/cargo" "+1.97.0-beta.6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.21s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sb_audio-1b815f5046c2cd77)
[INFO] [stdout] 
[INFO] [stdout] running 77 tests
[INFO] [stdout] test effects::tests::decode_waveform_basic_16_samples ... ok
[INFO] [stdout] test effects::tests::decode_waveform_bracket_repeat_groups ... ok
[INFO] [stdout] test effects::tests::decode_waveform_bracket_rejects ... ok
[INFO] [stdout] test effects::tests::decode_waveform_lowercase_hex ... ok
[INFO] [stdout] test effects::tests::decode_waveform_rejects_bad_length ... ok
[INFO] [stdout] test effects::tests::effect_state_defaults ... ok
[INFO] [stdout] test effects::tests::wet_talk_threshold ... ok
[INFO] [stdout] test instruments::tests::default_envelope_attacks_then_sustains_then_releases ... ok
[INFO] [stdout] test instruments::tests::instrument_ranges_map_to_expected_families ... ok
[INFO] [stdout] test instruments::tests::percussion_is_drums_and_sfx_only ... ok
[INFO] [stdout] test instruments::tests::sine_wavetable_is_balanced_and_bounded ... ok
[INFO] [stdout] test instruments::tests::lower_envelope_time_param_is_slower ... ok
[INFO] [stdout] test instruments::tests::periodic_wavetables_have_one_cycle_noise_has_none ... ok
[INFO] [stdout] test mml::tests::abs_pitch_key_number ... ok
[INFO] [stdout] test mml::tests::channels_split ... ok
[INFO] [stdout] test effects::tests::decode_waveform_rejects_non_hex ... ok
[INFO] [stdout] test mml::tests::dotted_default_length ... ok
[INFO] [stdout] test mml::tests::accidentals ... ok
[INFO] [stdout] test mml::tests::dotted_notes ... ok
[INFO] [stdout] test mml::tests::detune_signed ... ok
[INFO] [stdout] test mml::tests::empty_is_silent ... ok
[INFO] [stdout] test mml::tests::corpus_like_program_parses ... ok
[INFO] [stdout] test mml::tests::macro_in_repeat_doc_example ... ok
[INFO] [stdout] test mml::tests::mml_variable_substitution ... ok
[INFO] [stdout] test mml::tests::middle_c_is_60 ... ok
[INFO] [stdout] test mml::tests::nested_repeat_doc_example ... ok
[INFO] [stdout] test mml::tests::control_events ... ok
[INFO] [stdout] test mml::tests::endless_repeat_uses_markers ... ok
[INFO] [stdout] test mml::tests::envelope_and_reset ... ok
[INFO] [stdout] test mml::tests::errors_are_47 ... ok
[INFO] [stdout] test mml::tests::finite_repeat ... ok
[INFO] [stdout] test mml::tests::leading_accidentals ... ok
[INFO] [stdout] test mml::tests::error_offset_points_at_failure ... ok
[INFO] [stdout] test mml::tests::note_scale_and_lengths ... ok
[INFO] [stdout] test mml::tests::gate_and_velocity_ride_on_note ... ok
[INFO] [stdout] test mml::tests::octave_absolute_and_relative ... ok
[INFO] [stdout] test mml::tests::octave_invert ... ok
[INFO] [stdout] test mml::tests::lfos_and_modulation ... ok
[INFO] [stdout] test mml::tests::macro_define_and_use ... ok
[INFO] [stdout] test mml::tests::per_note_length_and_default_length ... ok
[INFO] [stdout] test mml::tests::portamento_flag ... ok
[INFO] [stdout] test mml::tests::real_corpus_strings_parse ... ok
[INFO] [stdout] test mml::tests::rest ... ok
[INFO] [stdout] test mml::tests::slur_different_pitch_keeps_two ... ok
[INFO] [stdout] test mml::tests::tie_without_note_errors ... ok
[INFO] [stdout] test mml::tests::tie_short_form ... ok
[INFO] [stdout] test mml::tests::tie_same_pitch_merges ... ok
[INFO] [stdout] test stream::tests::resample_downsample_halves_length ... ok
[INFO] [stdout] test mml::tests::whitespace_ignored ... ok
[INFO] [stdout] test stream::tests::resample_identity_preserves_signal ... ok
[INFO] [stdout] test stream::tests::resample_interpolates_midpoint ... ok
[INFO] [stdout] test stream::tests::resample_upsample_roughly_doubles_length ... ok
[INFO] [stdout] test stream::tests::ring_clear_empties ... ok
[INFO] [stdout] test stream::tests::resample_streaming_matches_oneshot ... ok
[INFO] [stdout] test stream::tests::ring_push_overflow_drops_tail ... ok
[INFO] [stdout] test stream::tests::ring_wraps_around_capacity ... ok
[INFO] [stdout] test stream::tests::ring_push_pull_roundtrip ... ok
[INFO] [stdout] test stream::tests::ring_underrun_fills_silence_and_counts ... ok
[INFO] [stdout] test synth::tests::empty_song_is_empty ... ok
[INFO] [stdout] test synth::tests::interp_linear_endpoints_and_midpoint ... ok
[INFO] [stdout] test mml::tests::case_insensitive ... ok
[INFO] [stdout] test synth::tests::pitch_matches_the_note_frequency ... ok
[INFO] [stdout] test synth::tests::channels_mix_additively ... ok
[INFO] [stdout] test synth::tests::a_channel_other_than_zero_plays ... ok
[INFO] [stdout] test synth::tests::note_produces_sound ... ok
[INFO] [stdout] test synth::tests::detune_raises_pitch ... ok
[INFO] [stdout] test synth::tests::quarter_note_is_half_second_at_t120 ... ok
[INFO] [stdout] test synth::tests::pan_hard_left_silences_right ... ok
[INFO] [stdout] test synth::tests::one_shot_render_plays_an_endless_loop_once ... ok
[INFO] [stdout] test synth::tests::pan_hard_right_silences_left ... ok
[INFO] [stdout] test synth::tests::octave_up_doubles_the_frequency ... ok
[INFO] [stdout] test synth::tests::render_frames_has_exact_length ... ok
[INFO] [stdout] test synth::tests::gate_creates_a_staccato_tail ... ok
[INFO] [stdout] test synth::tests::tempo_scales_duration ... ok
[INFO] [stdout] test synth::tests::endless_loop_fills_the_frame_budget ... ok
[INFO] [stdout] test synth::tests::rest_then_note_has_leading_silence ... ok
[INFO] [stdout] test synth::tests::render_is_deterministic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 77 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 622 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sb_core-9bfab0abe0ac4fe2)
[INFO] [stdout] test array::tests::array_ref_shares_storage ... ok
[INFO] [stdout] test array::tests::one_d_set_get ... ok
[INFO] [stdout] test array::tests::one_d_declare_defaults_zero ... ok
[INFO] [stdout] test array::tests::push_pop_lifo ... ok
[INFO] [stdout] test array::tests::shift_unshift_fifo ... ok
[INFO] [stdout] test array::tests::stack_ops_reject_multidim ... ok
[INFO] [stdout] test array::tests::string_array_defaults_empty ... ok
[INFO] [stdout] test array::tests::subscript_out_of_range_is_errnum_31 ... ok
[INFO] [stdout] test array::tests::two_d_row_major ... ok
[INFO] [stdout] test array::tests::wrong_subscript_count_is_errnum_3 ... ok
[INFO] [stdout] test ast::tests::clone_and_eq_round_trip ... ok
[INFO] [stdout] test ast::tests::binop_from_token_and_rank ... ok
[INFO] [stdout] test ast::tests::debug_is_non_empty ... ok
[INFO] [stdout] test ast::tests::lvalue_classification ... ok
[INFO] [stdout] test ast::tests::name_carries_suffix_identity ... ok
[INFO] [stdout] test ast::tests::statements_build_clone_eq ... ok
[INFO] [stdout] test builtins::bg::tests::bgchk_shape_and_range ... ok
[INFO] [stdout] test builtins::bg::tests::bgclip_reset_and_rect ... ok
[INFO] [stdout] test ast::tests::unop_from_token_and_word ... ok
[INFO] [stdout] test builtins::bg::tests::bgcoord_shape_mode_and_range ... ok
[INFO] [stdout] test builtins::bg::tests::bgcolor_set_get ... ok
[INFO] [stdout] test builtins::bg::tests::bgcopy_argcount_and_range ... ok
[INFO] [stdout] test builtins::bg::tests::bgfill_then_get ... ok
[INFO] [stdout] test builtins::bg::tests::bgfill_value_contract ... ok
[INFO] [stdout] test builtins::bg::tests::bgload_value_conversion_depends_on_array_type ... ok
[INFO] [stdout] test builtins::bg::tests::bgofs_set_get_with_z ... ok
[INFO] [stdout] test builtins::bg::tests::bghome_set_get ... ok
[INFO] [stdout] test builtins::bg::tests::bghome_value_contract ... ok
[INFO] [stdout] test builtins::bg::tests::bgofs_z_range_guard ... ok
[INFO] [stdout] test builtins::bg::tests::bgpage_set_get ... ok
[INFO] [stdout] test builtins::bg::tests::bgscale_unclamped_get ... ok
[INFO] [stdout] test builtins::bg::tests::bgput_get_round_trip ... ok
[INFO] [stdout] test builtins::bg::tests::bgsave_load_round_trip_and_errors ... ok
[INFO] [stdout] test builtins::bg::tests::bgshow_hide_clr ... ok
[INFO] [stdout] test builtins::bg::tests::screen_data_number_and_hex_string ... ok
[INFO] [stdout] test builtins::console::tests::cls_clears_keeps_color_and_guards_args ... ok
[INFO] [stdout] test builtins::console::tests::color_sets_and_checks_range ... ok
[INFO] [stdout] test builtins::bg::tests::bgvar_set_read_and_guards ... ok
[INFO] [stdout] test builtins::console::tests::locate_omitted_keeps_previous ... ok
[INFO] [stdout] test array::tests::three_and_four_d_layout ... ok
[INFO] [stdout] test builtins::bg::tests::parse_bg_target_rejects_uv_and_i ... ok
[INFO] [stdout] test builtins::bg::tests::bgstart_stop_forms ... ok
[INFO] [stdout] test builtins::bg::tests::bgrot_normalizes ... ok
[INFO] [stdout] test builtins::console::tests::chkchr_reads_grid_and_guards ... ok
[INFO] [stdout] test builtins::console::tests::locate_arg_count_and_function_use ... ok
[INFO] [stdout] test builtins::bg::tests::bgscreen_resizes_and_guards ... ok
[INFO] [stdout] test builtins::console::tests::inkey_empty_and_arg_guard ... ok
[INFO] [stdout] test builtins::console::tests::locate_sets_cursor ... ok
[INFO] [stdout] test builtins::console::tests::locate_x_edge_and_out_of_range ... ok
[INFO] [stdout] test builtins::console::tests::print_item_formats_numbers_and_strings ... ok
[INFO] [stdout] test builtins::device::tests::feature_codes_round_trip ... ok
[INFO] [stdout] test builtins::console::tests::acls_resets_and_guards_args ... ok
[INFO] [stdout] test builtins::device::tests::mic_requires_xon_mic ... ok
[INFO] [stdout] test builtins::device::tests::mp_offline_behavior ... ok
[INFO] [stdout] test builtins::files::tests::dat_pcbn_1d_decodes_into_int_array ... ok
[INFO] [stdout] test builtins::files::tests::dat_int_round_trips ... ok
[INFO] [stdout] test builtins::device::tests::dialog_arg_count_and_forms ... ok
[INFO] [stdout] test builtins::files::tests::dat_pcbn_2d_decodes_row_major ... ok
[INFO] [stdout] test builtins::device::tests::micsave_shape_and_array_guards ... ok
[INFO] [stdout] test builtins::files::tests::dat_pcbn_decodes_into_real_array ... ok
[INFO] [stdout] test builtins::device::tests::motion_requires_xon_motion ... ok
[INFO] [stdout] test builtins::files::tests::dat_pcbn_truncated_data_is_rejected ... ok
[INFO] [stdout] test builtins::files::tests::dat_rejects_foreign_body ... ok
[INFO] [stdout] test builtins::files::tests::dat_rejects_non_array ... ok
[INFO] [stdout] test builtins::files::tests::txt_round_trips ... ok
[INFO] [stdout] test builtins::graphics::tests::gcolor_set_get_and_errors ... ok
[INFO] [stdout] test builtins::graphics::tests::gprio_range_and_form ... ok
[INFO] [stdout] test builtins::math::tests::abs_sgn_classify ... ok
[INFO] [stdout] test builtins::graphics::tests::rgb_arg_and_form_errors ... ok
[INFO] [stdout] test builtins::graphics::tests::primitives_reject_bad_arg_counts_and_return_use ... ok
[INFO] [stdout] test builtins::graphics::tests::rgb_packs_and_clamps ... ok
[INFO] [stdout] test builtins::graphics::tests::rgb_rgbread_round_trip ... ok
[INFO] [stdout] test builtins::graphics::tests::gpset_uses_default_then_explicit_color ... ok
[INFO] [stdout] test builtins::math::tests::arg_count_and_type_errors ... ok
[INFO] [stdout] test builtins::math::tests::min_max_varargs_and_array ... ok
[INFO] [stdout] test builtins::math::tests::trig_and_angle ... ok
[INFO] [stdout] test builtins::math::tests::rounding_family_preserves_type ... ok
[INFO] [stdout] test builtins::math::tests::rounding_family_stays_double_beyond_i32 ... ok
[INFO] [stdout] test builtins::graphics::tests::gpage_set_get_and_errors ... ok
[INFO] [stdout] test builtins::prg::tests::separated_keeps_empties ... ok
[INFO] [stdout] test builtins::graphics::tests::rgbread_splits ... ok
[INFO] [stdout] test builtins::math::tests::powers_roots_logs ... ok
[INFO] [stdout] test builtins::prg::tests::terminated_strips_trailing_lf ... ok
[INFO] [stdout] test builtins::sound::tests::beep_resolves_defaults_and_pan_remap ... ok
[INFO] [stdout] test builtins::sound::tests::beep_range_and_shape_errors ... ok
[INFO] [stdout] test builtins::sound::tests::bgmclear_removes_tunes ... ok
[INFO] [stdout] test builtins::sound::tests::bgmplay_forms_set_playing ... ok
[INFO] [stdout] test builtins::sound::tests::bgmplay_range_and_shape_errors ... ok
[INFO] [stdout] test builtins::sound::tests::bgmvar_write_then_read_while_playing ... ok
[INFO] [stdout] test builtins::sound::tests::bgmset_compiles_and_registers ... ok
[INFO] [stdout] test builtins::sound::tests::bgmvol_sets_volume ... ok
[INFO] [stdout] test builtins::sound::tests::efcon_off_and_wet ... ok
[INFO] [stdout] test builtins::sound::tests::bgmstop_clears_playing ... ok
[INFO] [stdout] test builtins::sound::tests::efcset_presets_and_raw ... ok
[INFO] [stdout] test builtins::sound::tests::talk_transport_round_trips ... ok
[INFO] [stdout] test builtins::sound::tests::wavset_registers_user_instrument ... ok
[INFO] [stdout] test builtins::graphics::tests::gspoit_reads_manip_page ... ok
[INFO] [stdout] test builtins::graphics::tests::gsave_gload_roundtrip_through_builtins ... ok
[INFO] [stdout] test builtins::sprite::tests::spofs_z_range_guard ... ok
[INFO] [stdout] test builtins::sound::tests::wavseta_non_array_and_arg_count_errors ... ok
[INFO] [stdout] test builtins::sprite::tests::spclip_contract ... ok
[INFO] [stdout] test builtins::sprite::tests::sphome_round_trip_and_guards ... ok
[INFO] [stdout] test builtins::string::tests::asc_chr_instr ... ok
[INFO] [stdout] test builtins::string::tests::extract_family ... ok
[INFO] [stdout] test builtins::sprite::tests::sphitinfo_three_var_form_reports_tm_x1_y1 ... ok
[INFO] [stdout] test builtins::sprite::tests::sprot_round_trip_wrap_and_guards ... ok
[INFO] [stdout] test builtins::string::tests::hex_conversion ... ok
[INFO] [stdout] test builtins::string::tests::len_string_and_array ... ok
[INFO] [stdout] test builtins::graphics::tests::gcls_and_gclip_forms ... ok
[INFO] [stdout] test builtins::string::tests::val_parsing ... ok
[INFO] [stdout] test builtins::tests::format_g_matches_smilebasic_doubles ... ok
[INFO] [stdout] test builtins::string::tests::subst_forms ... ok
[INFO] [stdout] test builtins::sprite::tests::spchr_value_contract ... ok
[INFO] [stdout] test builtins::sprite::tests::spcolor_round_trip_and_guards ... ok
[INFO] [stdout] test builtins::sprite::tests::spofs_value_round_trip ... ok
[INFO] [stdout] test builtins::sprite::tests::sppage_round_trip_and_guards ... ok
[INFO] [stdout] test builtins::sprite::tests::spscale_round_trip_and_guards ... ok
[INFO] [stdout] test builtins::tests::format_number_int_and_real ... ok
[INFO] [stdout] test builtins::tests::format_g_str_dollar_table ... ok
[INFO] [stdout] test builtins::sprite::tests::spset_alloc_picks_lowest_and_rejects_reversed_range ... ok
[INFO] [stdout] test builtins::tests::format_print_fixed8_table ... ok
[INFO] [stdout] test builtins::tests::std_builtins_predicate_covers_the_set ... ok
[INFO] [stdout] test builtins::tests::unknown_name_is_undefined_function ... ok
[INFO] [stdout] test clock::tests::begin_vsync_arms_target_relative_to_last_vsync ... ok
[INFO] [stdout] test clock::tests::begin_vsync_immediate_when_already_past_target ... ok
[INFO] [stdout] test clock::tests::begin_wait_arms_target_and_tick_drives_it ... ok
[INFO] [stdout] test clock::tests::begin_wait_zero_resyncs_last_vsync_immediately ... ok
[INFO] [stdout] test clock::tests::resolve_wait_is_noop_with_no_pending_wait ... ok
[INFO] [stdout] test clock::tests::tick_advances_the_counter_without_moving_the_vsync_anchor ... ok
[INFO] [stdout] test clock::tests::vsync_loop_advances_one_frame_each ... ok
[INFO] [stdout] test clock::tests::wait_advances_maincnt_from_now ... ok
[INFO] [stdout] test clock::tests::wait_counts_from_now_even_after_a_tick ... ok
[INFO] [stdout] test clock::tests::starts_at_zero ... ok
[INFO] [stdout] test clock::tests::zero_count_does_not_wait_but_resyncs_the_anchor ... ok
[INFO] [stdout] test compiler::tests::arithmetic_assign_snapshot ... ok
[INFO] [stdout] test compiler::tests::auto_declare_without_strict ... ok
[INFO] [stdout] test compiler::tests::break_and_continue_resolve ... ok
[INFO] [stdout] test compiler::tests::builtins_predicate_resolves_bare_name ... ok
[INFO] [stdout] test compiler::tests::data_pool_and_read ... ok
[INFO] [stdout] test compiler::tests::def_function_is_addressed_and_dispatched ... ok
[INFO] [stdout] test compiler::tests::def_dim_binds_local_shadowing_global ... ok
[INFO] [stdout] test compiler::tests::def_plain_assign_hits_global ... ok
[INFO] [stdout] test compiler::tests::dim_array_then_index ... ok
[INFO] [stdout] test compiler::tests::distinct_suffix_is_not_a_duplicate ... ok
[INFO] [stdout] test compiler::tests::if_then_else_jumps_resolve ... ok
[INFO] [stdout] test compiler::tests::duplicate_var_declaration_is_errnum_18 ... ok
[INFO] [stdout] test compiler::tests::goto_label_resolves_to_address ... ok
[INFO] [stdout] test compiler::tests::locs_parallel_code ... ok
[INFO] [stdout] test compiler::tests::non_constant_binary_emits_operate ... ok
[INFO] [stdout] test compiler::tests::option_strict_declared_is_ok ... ok
[INFO] [stdout] test compiler::tests::option_strict_undeclared_is_errnum_15 ... ok
[INFO] [stdout] test compiler::tests::for_counter_add_selects_promote_by_counter_type ... ok
[INFO] [stdout] test compiler::tests::for_loop_structure ... ok
[INFO] [stdout] test compiler::tests::print_items_lower_to_ops ... ok
[INFO] [stdout] test compiler::tests::redeclaration_is_scoped_per_function ... ok
[INFO] [stdout] test compiler::tests::paren_form_array_read_vs_call ... ok
[INFO] [stdout] test compiler::tests::restore_label_targets_data_index ... ok
[INFO] [stdout] test compiler::tests::swap_pushes_two_refs ... ok
[INFO] [stdout] test compiler::tests::undefined_label_is_errnum_14 ... ok
[INFO] [stdout] test compiler::tests::inc_dec_select_promote_by_target_type ... ok
[INFO] [stdout] test consts::tests::known_constants_resolve ... ok
[INFO] [stdout] test consts::tests::table_is_sorted_and_unique ... ok
[INFO] [stdout] test consts::tests::unknown_constant_is_none ... ok
[INFO] [stdout] test error::tests::displayed_message_uses_pool_for_real_errors ... ok
[INFO] [stdout] test error::tests::names_match_binary_pool_byte_for_byte ... ok
[INFO] [stdout] test error::tests::out_of_band_errnums_fall_back_to_internal_error ... ok
[INFO] [stdout] test host_input::tests::button_press_and_release_toggles_the_bit ... ok
[INFO] [stdout] test host_input::tests::distinct_buttons_or_together ... ok
[INFO] [stdout] test host_input::tests::opposing_axis_keys_cancel ... ok
[INFO] [stdout] test host_input::tests::digital_keys_give_full_stick_deflection ... ok
[INFO] [stdout] test host_input::tests::left_and_right_sticks_are_independent ... ok
[INFO] [stdout] test host_input::tests::shared_dpad_bindings_match_button_weights ... ok
[INFO] [stdout] test host_input::tests::unbound_key_is_ignored ... ok
[INFO] [stdout] test input::tests::brepeat_interval_zero_disables ... ok
[INFO] [stdout] test input::tests::brepeat_refires_pressed_with_repeat ... ok
[INFO] [stdout] test input::tests::brepeat_start_zero_fires_each_interval_plus_one ... ok
[INFO] [stdout] test input::tests::exact_bit_weights ... ok
[INFO] [stdout] test input::tests::feature1_equals_feature2_without_brepeat ... ok
[INFO] [stdout] test input::tests::held_pressed_released_edges ... ok
[INFO] [stdout] test input::tests::key_bindings_default_empty_and_round_trip ... ok
[INFO] [stdout] test input::tests::stick_clamps_to_unit_range ... ok
[INFO] [stdout] test input::tests::invalid_feature_is_none ... ok
[INFO] [stdout] test input::tests::key_queue_drains_in_order ... ok
[INFO] [stdout] test input::tests::touch_time_counts_while_held_and_resets ... ok
[INFO] [stdout] test input::tests::unused_bit_is_masked_off ... ok
[INFO] [stdout] test input_buffer::tests::astral_char_is_surrogate_pair ... ok
[INFO] [stdout] test input_buffer::tests::backspace_removes_astral_codepoint ... ok
[INFO] [stdout] test input_buffer::tests::backspace_removes_ascii ... ok
[INFO] [stdout] test input_buffer::tests::char_and_enter ... ok
[INFO] [stdout] test input_buffer::tests::push_line_and_next_line ... ok
[INFO] [stdout] test input_buffer::tests::set_current_replaces ... ok
[INFO] [stdout] test lexer::tests::comments_end_the_line ... ok
[INFO] [stdout] test lexer::tests::decimal_and_real_numbers ... ok
[INFO] [stdout] test lexer::tests::crlf_is_one_newline ... ok
[INFO] [stdout] test lexer::tests::empty_source_is_just_eof ... ok
[INFO] [stdout] test lexer::tests::exponent_literals ... ok
[INFO] [stdout] test array::tests::bad_dim_count_or_negative_size ... ok
[INFO] [stdout] test lexer::tests::hex_and_binary_literals ... ok
[INFO] [stdout] test lexer::tests::identifiers_are_case_folded ... ok
[INFO] [stdout] test lexer::tests::identifiers_with_suffixes ... ok
[INFO] [stdout] test lexer::tests::labels_and_constants ... ok
[INFO] [stdout] test lexer::tests::line_numbers_track_across_newline_and_colon ... ok
[INFO] [stdout] test lexer::tests::non_ascii_is_not_an_identifier ... ok
[INFO] [stdout] test lexer::tests::second_dot_breaks_number ... ok
[INFO] [stdout] test lexer::tests::single_char_operators_and_punctuation ... ok
[INFO] [stdout] test lexer::tests::string_literals_terminated_and_not ... ok
[INFO] [stdout] test lexer::tests::true_false_fold_to_int ... ok
[INFO] [stdout] test lexer::tests::two_char_operators ... ok
[INFO] [stdout] test lexer::tests::unknown_char_is_carried ... ok
[INFO] [stdout] test lexer::tests::word_operators_stay_identifiers ... ok
[INFO] [stdout] test parser::tests::array_index_and_calls ... ok
[INFO] [stdout] test parser::tests::assignment_forms ... ok
[INFO] [stdout] test parser::tests::assignment_rhs_can_use_equality ... ok
[INFO] [stdout] test parser::tests::bare_equals_is_equality_inside_expression ... ok
[INFO] [stdout] test parser::tests::block_mismatch_has_structural_errnum ... ok
[INFO] [stdout] test parser::tests::command_call_with_omitted_arg ... ok
[INFO] [stdout] test parser::tests::comparisons_and_shifts_are_not_folded ... ok
[INFO] [stdout] test parser::tests::command_keyword_in_expression_position_is_syntax_error ... ok
[INFO] [stdout] test parser::tests::command_with_parenthesized_first_arg ... ok
[INFO] [stdout] test parser::tests::comparison_binds_looser_than_arithmetic ... ok
[INFO] [stdout] test parser::tests::composed_keyword_rhs_stays_syntax_error ... ok
[INFO] [stdout] test parser::tests::def_function_and_command ... ok
[INFO] [stdout] test parser::tests::control_keywords ... ok
[INFO] [stdout] test parser::tests::dim_and_var ... ok
[INFO] [stdout] test parser::tests::data_read_restore ... ok
[INFO] [stdout] test parser::tests::divide_by_zero_is_not_folded ... ok
[INFO] [stdout] test parser::tests::endif_without_if_errline_tracks_endif_line ... ok
[INFO] [stdout] test parser::tests::error_carries_location ... ok
[INFO] [stdout] test parser::tests::command_call_with_out_args ... ok
[INFO] [stdout] test parser::tests::expr_stmt_cmd_sweep_512 ... ok
[INFO] [stdout] test parser::tests::expr_stmt_form_is_arg_count_driven ... ok
[INFO] [stdout] test parser::tests::expression_lookalikes_still_parse ... ok
[INFO] [stdout] test parser::tests::folding_matches_runtime_numeric_rules ... ok
[INFO] [stdout] test parser::tests::if_goto_form ... ok
[INFO] [stdout] test parser::tests::known_const_folds_to_its_value ... ok
[INFO] [stdout] test parser::tests::inc_dec_swap ... ok
[INFO] [stdout] test parser::tests::input_forms ... ok
[INFO] [stdout] test parser::tests::next_as_statement_is_loop_continue ... ok
[INFO] [stdout] test parser::tests::for_body_with_inner_single_line_if ... ok
[INFO] [stdout] test parser::tests::for_loop_next_var_ignored ... ok
[INFO] [stdout] test parser::tests::not_and_lnot_are_nodes ... ok
[INFO] [stdout] test parser::tests::on_goto ... ok
[INFO] [stdout] test parser::tests::for_loop_with_step ... ok
[INFO] [stdout] test parser::tests::label_definition ... ok
[INFO] [stdout] test parser::tests::multi_statement_line ... ok
[INFO] [stdout] test parser::tests::multiline_if_with_elseif ... ok
[INFO] [stdout] test parser::tests::malformed_input_is_syntax_error ... ok
[INFO] [stdout] test parser::tests::paren_form_command_call ... ok
[INFO] [stdout] test parser::tests::precedence_mul_over_add ... ok
[INFO] [stdout] test parser::tests::precedence_with_variables_is_left_assoc ... ok
[INFO] [stdout] test parser::tests::print_trailing_semicolon_suppresses_newline ... ok
[INFO] [stdout] test parser::tests::question_is_print ... ok
[INFO] [stdout] test parser::tests::single_line_if_label_is_goto ... ok
[INFO] [stdout] test parser::tests::single_line_if ... ok
[INFO] [stdout] test parser::tests::sole_class3_closer_rhs_is_structural_error ... ok
[INFO] [stdout] test parser::tests::unary_minus_folds_constants ... ok
[INFO] [stdout] test parser::tests::single_line_if_with_endif ... ok
[INFO] [stdout] test parser::tests::unary_minus_on_variable_is_a_node ... ok
[INFO] [stdout] test parser::tests::unknown_const_is_syntax_error ... ok
[INFO] [stdout] test parser::tests::sole_class1_keyword_rhs_is_bareword_value ... ok
[INFO] [stdout] test parser::tests::print_items ... ok
[INFO] [stdout] test parser::tests::while_and_repeat ... ok
[INFO] [stdout] test parser::tests::word_operators_parse ... ok
[INFO] [stdout] test rng::tests::otya_seeded_sequence_is_bit_exact ... ok
[INFO] [stdout] test parser::tests::value_function_in_statement_form_is_classified ... ok
[INFO] [stdout] test rng::tests::rnd_one_is_zero_and_consumes_a_draw ... ok
[INFO] [stdout] test storage::tests::bare_prefix_is_index_zero_and_case_folds ... ok
[INFO] [stdout] test storage::tests::extdata_footer_matches_real_sb ... ok
[INFO] [stdout] test storage::tests::extdata_key_is_64_bytes ... ok
[INFO] [stdout] test storage::tests::extdata_marker_folders ... ok
[INFO] [stdout] test rng::tests::rndf_in_unit_interval ... ok
[INFO] [stdout] test storage::tests::files_filter_parsing ... ok
[INFO] [stdout] test storage::tests::extdata_rejects_corruption ... ok
[INFO] [stdout] test rng::tests::reseeding_restarts_the_sequence ... ok
[INFO] [stdout] test storage::tests::mem_storage_delete_and_rename ... ok
[INFO] [stdout] test storage::tests::mem_storage_deserialize_rejects_truncated ... ok
[INFO] [stdout] test storage::tests::hmac_sha1_rfc2202_vector ... ok
[INFO] [stdout] test storage::tests::mem_storage_list_is_sorted ... ok
[INFO] [stdout] test storage::tests::mem_storage_missing_is_not_found ... ok
[INFO] [stdout] test storage::tests::extdata_wrap_unwrap_roundtrip ... ok
[INFO] [stdout] test storage::tests::mem_storage_empty_serialize_roundtrip ... ok
[INFO] [stdout] test storage::tests::mem_storage_folders_are_independent ... ok
[INFO] [stdout] test rng::tests::seeded_series_are_independent_but_identical_for_equal_seed ... ok
[INFO] [stdout] test storage::tests::no_prefix_is_bare ... ok
[INFO] [stdout] test storage::tests::mem_storage_write_read_roundtrip ... ok
[INFO] [stdout] test storage::tests::resource_errnum_mapping ... ok
[INFO] [stdout] test storage::tests::resource_kind_folder_mapping ... ok
[INFO] [stdout] test storage::tests::sha1_known_vector ... ok
[INFO] [stdout] test sysvars::tests::names_round_trip ... ok
[INFO] [stdout] test storage::tests::parses_typed_resources ... ok
[INFO] [stdout] test builtins::prg::tests::char_and_free_counts ... ok
[INFO] [stdout] test builtins::files::tests::dat_pcbn_gsave_flag1_ushort_reuses_tag5 ... ok
[INFO] [stdout] test builtins::string::tests::format_directives ... ok
[INFO] [stdout] test builtins::string::tests::str_conversion_and_width ... ok
[INFO] [stdout] test parser::tests::command_calls ... ok
[INFO] [stdout] test sysvars::tests::non_sysvars_and_suffixed_names_are_not_matched ... ok
[INFO] [stdout] test value::tests::coerce_across_string_numeric_divide_is_type_mismatch ... ok
[INFO] [stdout] test value::tests::coerce_to_suffix_int_truncates ... ok
[INFO] [stdout] test value::tests::defaults_match_suffix ... ok
[INFO] [stdout] test value::tests::int_widens_to_real ... ok
[INFO] [stdout] test sysvars::tests::only_tabstep_and_sysbeep_are_writable ... ok
[INFO] [stdout] test value::tests::double_to_int_truncates_toward_zero ... ok
[INFO] [stdout] test value::tests::references_alias_and_assign_through ... ok
[INFO] [stdout] test value::tests::to_int_store_overflow_raises_errnum_9 ... ok
[INFO] [stdout] test value::tests::swap_exchanges_cells ... ok
[INFO] [stdout] test value::tests::numeric_equality_crosses_int_real ... ok
[INFO] [stdout] test storage::tests::mem_storage_serialize_roundtrip ... ok
[INFO] [stdout] test value::tests::coerce_no_suffix_keeps_runtime_type ... ok
[INFO] [stdout] test sysvars::tests::only_time_and_date_are_strings ... ok
[INFO] [stdout] test value::tests::non_numeric_to_number_is_type_mismatch ... ok
[INFO] [stdout] test value::tests::coerce_no_suffix_defint_truncates ... ok
[INFO] [stdout] test builtins::graphics::tests::bitmap_ops_reject_bad_shapes ... ok
[INFO] [stdout] test vm::tests::assert_string_condition_is_type_mismatch ... ok
[INFO] [stdout] test array::tests::over_budget_array_is_errnum_11 ... ok
[INFO] [stdout] test vm::tests::assert_message_is_optional ... ok
[INFO] [stdout] test vm::tests::array_2d_row_major ... ok
[INFO] [stdout] test vm::tests::bare_pi_resolves_with_registry ... ok
[INFO] [stdout] test vm::tests::array_element_ref_out_of_range_is_31 ... ok
[INFO] [stdout] test vm::tests::backcolor_round_trips ... ok
[INFO] [stdout] test vm::tests::array_subscript_out_of_range_is_errnum_31 ... ok
[INFO] [stdout] test vm::tests::acls_resets_grp_command_target_to_upper ... ok
[INFO] [stdout] test vm::tests::arithmetic_with_precedence ... ok
[INFO] [stdout] test vm::tests::array_declare_store_read ... ok
[INFO] [stdout] test vm::tests::bare_restore_is_type_mismatch_8 ... ok
[INFO] [stdout] test vm::tests::assert_false_halts_with_message_and_line ... ok
[INFO] [stdout] test vm::tests::assert_bad_arg_count_is_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::assert_true_is_a_noop ... ok
[INFO] [stdout] test vm::tests::beep_runs_and_skips_args ... ok
[INFO] [stdout] test vm::tests::acls_resets_consoles_to_default_dimensions ... ok
[INFO] [stdout] test vm::tests::acls_resets_fader ... ok
[INFO] [stdout] test vm::tests::bganim_data_label_form ... ok
[INFO] [stdout] test vm::tests::beep_sound_gap_is_out_of_range ... ok
[INFO] [stdout] test vm::tests::bganim_inline_advances_scroll_on_vsync ... ok
[INFO] [stdout] test vm::tests::bganim_array_form_drives_rotation ... ok
[INFO] [stdout] test vm::tests::bgfunc_binds_callback_name ... ok
[INFO] [stdout] test vm::tests::bgmset_then_play_user_tune ... ok
[INFO] [stdout] test vm::tests::acls_runs_and_resets ... ok
[INFO] [stdout] test vm::tests::bgmchk_tracks_play_and_stop ... ok
[INFO] [stdout] test vm::tests::acls_reloads_the_default_sprite_bg_and_font_pages ... ok
[INFO] [stdout] test vm::tests::bgmvar_round_trips_while_playing ... ok
[INFO] [stdout] test vm::tests::bganim_too_few_args_errors ... ok
[INFO] [stdout] test vm::tests::bgmsetd_missing_label_is_noerr ... ok
[INFO] [stdout] test vm::tests::bgm_malformed_mml_is_errnum_47 ... ok
[INFO] [stdout] test vm::tests::button_feature_edges_through_vm ... ok
[INFO] [stdout] test vm::tests::bgchk_reflects_running_animation ... ok
[INFO] [stdout] test vm::tests::bgmsetd_gathers_mml_from_data ... ok
[INFO] [stdout] test vm::tests::bgfunc_leading_at_does_not_fall_back_to_a_def ... ok
[INFO] [stdout] test vm::tests::bgsave_load_round_trip_through_vm ... ok
[INFO] [stdout] test vm::tests::backcolor_get_strips_alpha ... ok
[INFO] [stdout] test vm::tests::bgvar_round_trip_through_vm ... ok
[INFO] [stdout] test vm::tests::button_reads_held_mask_through_vm ... ok
[INFO] [stdout] test vm::tests::call_function_form_is_syntax_error ... ok
[INFO] [stdout] test vm::tests::bitwise_truncates_doubles ... ok
[INFO] [stdout] test vm::tests::break_and_continue ... ok
[INFO] [stdout] test vm::tests::brepeat_then_button_feature1_refires ... ok
[INFO] [stdout] test vm::tests::call_bg_runs_bound_layer_callback_with_callidx ... ok
[INFO] [stdout] test vm::tests::bg_layers_are_per_display_screen ... ok
[INFO] [stdout] test vm::tests::button_wireless_terminal_is_comms_error ... ok
[INFO] [stdout] test vm::tests::button_statement_use_is_errnum_4 ... ok
[INFO] [stdout] test vm::tests::call_bg_leaves_callidx_one_past_the_layer_table ... ok
[INFO] [stdout] test vm::tests::call_sprite_runs_bound_def_process ... FAILED
[INFO] [stdout] test vm::tests::bgfunc_errors ... ok
[INFO] [stdout] test vm::tests::call_sprite_dispatches_all_bound_in_ascending_order ... ok
[INFO] [stdout] test vm::tests::call_by_name_runs_the_def ... ok
[INFO] [stdout] test vm::tests::call_bg_dispatches_all_bound_layers_in_order ... ok
[INFO] [stdout] test vm::tests::call_passes_value_args ... ok
[INFO] [stdout] test vm::tests::acls_resets_draw_settings_but_not_tabstep ... ok
[INFO] [stdout] test vm::tests::builtin_value_arg_errors_propagate ... ok
[INFO] [stdout] test vm::tests::call_name_is_case_insensitive ... ok
[INFO] [stdout] test vm::tests::callidx_reads_zero_before_any_callback_sweep ... ok
[INFO] [stdout] test vm::tests::call_undefined_name_is_undefined_function ... ok
[INFO] [stdout] test vm::tests::cls_clears_console ... ok
[INFO] [stdout] test vm::tests::call_returns_out_args ... ok
[INFO] [stdout] test vm::tests::call_with_a_non_string_name_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::call_name_from_a_string_variable ... ok
[INFO] [stdout] test vm::tests::brepeat_reserved_id_is_errnum_4 ... ok
[INFO] [stdout] test vm::tests::common_def_is_callable_cross_slot_after_use ... ok
[INFO] [stdout] test vm::tests::call_sprite_runs_bound_label_callback_with_callidx ... ok
[INFO] [stdout] test vm::tests::color_sets_cell_palette ... ok
[INFO] [stdout] test vm::tests::comparisons_yield_one_or_zero ... ok
[INFO] [stdout] test vm::tests::call_sprite_with_no_bound_callbacks_is_noop ... ok
[INFO] [stdout] test vm::tests::copy_auto_extends_a_1d_destination ... ok
[INFO] [stdout] test vm::tests::console_sysvars_and_chkchr_follow_active_screen ... ok
[INFO] [stdout] test vm::tests::copy_array_to_array ... ok
[INFO] [stdout] test vm::tests::copy_from_data_label ... ok
[INFO] [stdout] test vm::tests::bgstart_bgstop_isolate_named_layer ... ok
[INFO] [stdout] test vm::tests::copy_five_arg_form_uses_src_offset ... ok
[INFO] [stdout] test vm::tests::copy_numeric_into_string_array_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::copy_from_data_short_is_out_of_data ... ok
[INFO] [stdout] test vm::tests::cross_slot_call_restores_caller_context ... ok
[INFO] [stdout] test vm::tests::copy_from_undefined_label_is_undefined_label ... ok
[INFO] [stdout] test vm::tests::cross_slot_common_def_global_write_isolated_from_caller ... ok
[INFO] [stdout] test vm::tests::cross_slot_call_without_use_is_undefined ... ok
[INFO] [stdout] test vm::tests::copy_dest_offset_and_count ... ok
[INFO] [stdout] test vm::tests::console_commands_as_functions_error ... ok
[INFO] [stdout] test vm::tests::cross_slot_common_def_can_return_a_value ... ok
[INFO] [stdout] test vm::tests::cross_slot_non_common_def_is_private ... ok
[INFO] [stdout] test vm::tests::cross_slot_common_def_passes_args_and_out ... ok
[INFO] [stdout] test vm::tests::csrx_csry_track_the_text_cursor ... ok
[INFO] [stdout] test vm::tests::cross_slot_common_def_global_binds_to_defining_slot ... ok
[INFO] [stdout] test vm::tests::data_read_restore ... ok
[INFO] [stdout] test vm::tests::dec_array_element ... ok
[INFO] [stdout] test vm::tests::date_and_time_need_the_dollar_suffix ... ok
[INFO] [stdout] test vm::tests::def_recursion_factorial ... ok
[INFO] [stdout] test vm::tests::def_value_function ... ok
[INFO] [stdout] test vm::tests::def_with_out_param ... ok
[INFO] [stdout] test vm::tests::div_mod_truncate_operands_first ... ok
[INFO] [stdout] test vm::tests::error_sysvars_are_read_only ... ok
[INFO] [stdout] test vm::tests::chkchr_reads_console_grid ... ok
[INFO] [stdout] test vm::tests::console_state_is_per_display_screen ... ok
[INFO] [stdout] test vm::tests::errnum_reads_zero_on_a_clean_run ... ok
[INFO] [stdout] test vm::tests::def_recursion_trip_depth_matches_real_sb ... ok
[INFO] [stdout] test vm::tests::errline_points_at_failing_line ... ok
[INFO] [stdout] test vm::tests::display_routes_grp_commands_to_the_selected_screen ... ok
[INFO] [stdout] test vm::tests::error_state_persists_after_halt ... ok
[INFO] [stdout] test vm::tests::display_get_round_trips ... ok
[INFO] [stdout] test vm::tests::exec_numeric_transfers_to_loaded_slot ... ok
[INFO] [stdout] test vm::tests::exec_cross_slot_end_returns_to_launcher ... ok
[INFO] [stdout] test vm::tests::date_and_time_strings_are_deterministic_under_the_injected_clock ... ok
[INFO] [stdout] test vm::tests::display_error_conditions ... ok
[INFO] [stdout] test vm::tests::exec_string_bare_name_from_variable ... ok
[INFO] [stdout] test vm::tests::exec_resource_string_missing_file_is_load_failed ... ok
[INFO] [stdout] test vm::tests::exec_string_into_running_slot_loads_and_runs_from_top ... ok
[INFO] [stdout] test vm::tests::exec_running_slot_restart_reruns_from_top ... ok
[INFO] [stdout] test vm::tests::exec_cross_slot_return_preserves_caller_gosub_state ... ok
[INFO] [stdout] test vm::tests::exec_cross_slot_nesting_returns_in_lifo_order ... ok
[INFO] [stdout] test vm::tests::exec_resource_string_bad_resource_is_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::exec_empty_slot_is_syntax_error ... ok
[INFO] [stdout] test vm::tests::divide_by_zero_is_errnum_7 ... ok
[INFO] [stdout] test vm::tests::exec_string_non_running_slot_0_missing_file_is_load_failed ... ok
[INFO] [stdout] test vm::tests::exec_string_bare_name_loads_into_running_slot ... ok
[INFO] [stdout] test vm::tests::exec_out_of_range_slot_is_out_of_range ... ok
[INFO] [stdout] test vm::tests::exec_string_running_slot_missing_file_is_load_failed ... ok
[INFO] [stdout] test vm::tests::exec_string_into_non_running_slot_0 ... ok
[INFO] [stdout] test vm::tests::exec_string_running_slot_runs_against_fresh_globals ... ok
[INFO] [stdout] test vm::tests::exec_string_missing_file_is_load_failed ... ok
[INFO] [stdout] test vm::tests::exec_string_prg_slot_loads_from_storage_and_transfers ... ok
[INFO] [stdout] test vm::tests::exec_unloaded_non_running_slot_is_syntax_error ... ok
[INFO] [stdout] test vm::tests::exec_string_bare_name_runs_against_fresh_globals ... ok
[INFO] [stdout] test vm::tests::exec_string_loads_prg_slot_and_transfers_control ... ok
[INFO] [stdout] test vm::tests::exec_string_non_running_slot_0_runs_against_its_own_globals ... ok
[INFO] [stdout] test vm::tests::fade_round_trips_argb ... ok
[INFO] [stdout] test vm::tests::exec_string_runs_loaded_program_against_its_own_globals ... ok
[INFO] [stdout] test vm::tests::exec_transfer_error_reports_target_slot ... ok
[INFO] [stdout] test vm::tests::fill_string_array ... ok
[INFO] [stdout] test vm::tests::fade_zero_disables ... ok
[INFO] [stdout] test vm::tests::exec_transfer_runs_target_against_its_own_globals ... ok
[INFO] [stdout] test vm::tests::for_loop_sums ... ok
[INFO] [stdout] test vm::tests::for_counter_promotes_on_overflow_and_terminates ... ok
[INFO] [stdout] test vm::tests::hardware_is_read_only ... ok
[INFO] [stdout] test vm::tests::fill_value_type_mismatch_is_8 ... ok
[INFO] [stdout] test vm::tests::dialog_sets_result_and_returns_headless_outcome ... ok
[INFO] [stdout] test vm::tests::errnum_for_documented_error_cases ... ok
[INFO] [stdout] test vm::tests::fill_past_the_end_is_out_of_range ... ok
[INFO] [stdout] test vm::tests::fade_animation_advances_per_tick ... ok
[INFO] [stdout] test vm::tests::for_loop_negative_step ... ok
[INFO] [stdout] test vm::tests::gosub_return ... ok
[INFO] [stdout] test vm::tests::freemem_and_stub_sysvars_read_their_model_values ... ok
[INFO] [stdout] test vm::tests::fill_all_and_subrange ... ok
[INFO] [stdout] test vm::tests::hardware_reports_the_model ... ok
[INFO] [stdout] test vm::tests::input_literal_receiver_is_syntax_error ... ok
[INFO] [stdout] test vm::tests::inc_and_dec ... ok
[INFO] [stdout] test vm::tests::inc_array_element ... ok
[INFO] [stdout] test vm::tests::gosub_nesting_trip_depth_matches_real_sb ... ok
[INFO] [stdout] test vm::tests::effector_set_on_off_wet ... ok
[INFO] [stdout] test vm::tests::inkey_drains_queued_keys_through_vm ... ok
[INFO] [stdout] test vm::tests::input_array_element_among_scalar_receivers ... ok
[INFO] [stdout] test vm::tests::fadechk_reports_animation_state ... ok
[INFO] [stdout] test vm::tests::input_into_array_element_receiver ... ok
[INFO] [stdout] test vm::tests::if_then_else ... ok
[INFO] [stdout] test vm::tests::input_numeric_and_string ... ok
[INFO] [stdout] test vm::tests::input_prompt_is_printed ... ok
[INFO] [stdout] test vm::tests::input_multiple_comma_fields ... ok
[INFO] [stdout] test vm::tests::integer_suffix_truncates_toward_zero ... ok
[INFO] [stdout] test vm::tests::fade_error_conditions ... ok
[INFO] [stdout] test vm::tests::console_command_error_conditions ... ok
[INFO] [stdout] test vm::tests::maincnt_is_read_only ... ok
[INFO] [stdout] test vm::tests::input_real_field ... ok
[INFO] [stdout] test vm::tests::integer_typed_arithmetic_wraps ... ok
[INFO] [stdout] test vm::tests::interactive_input_yields_until_enter ... ok
[INFO] [stdout] test vm::tests::key_assign_and_function_read_round_trip ... ok
[INFO] [stdout] test vm::tests::key_nonstring_value_is_errnum_8 ... ok
[INFO] [stdout] test vm::tests::inkey_is_empty_headless ... ok
[INFO] [stdout] test vm::tests::linput_keeps_commas ... ok
[INFO] [stdout] test vm::tests::maincnt_difference_measures_elapsed_frames ... ok
[INFO] [stdout] test vm::tests::maincnt_starts_at_zero ... ok
[INFO] [stdout] test vm::tests::key_unset_slot_reads_empty ... ok
[INFO] [stdout] test vm::tests::locate_then_print_positions_text ... ok
[INFO] [stdout] test vm::tests::function_as_statement_raises_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::math_builtins_via_assignment ... ok
[INFO] [stdout] test vm::tests::nested_builtin_calls ... ok
[INFO] [stdout] test vm::tests::niladic_pi_call ... ok
[INFO] [stdout] test vm::tests::on_goto_out_of_range_falls_through ... ok
[INFO] [stdout] test vm::tests::on_gosub_returns ... ok
[INFO] [stdout] test vm::tests::key_number_out_of_range_is_errnum_10 ... ok
[INFO] [stdout] test vm::tests::pop_on_numeric_scalar_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::on_goto_selects_branch ... ok
[INFO] [stdout] test vm::tests::out_of_data_is_errnum_13 ... ok
[INFO] [stdout] test vm::tests::pop_real_array_keeps_double ... ok
[INFO] [stdout] test vm::tests::prg_round_trip_edits_a_slot ... ok
[INFO] [stdout] test vm::tests::prg_multiline_insert_splits_on_lf ... ok
[INFO] [stdout] test vm::tests::inc_dec_follow_scalar_overflow_rule ... ok
[INFO] [stdout] test vm::tests::prg_seeded_slot_reads_size_and_name ... ok
[INFO] [stdout] test vm::tests::heavy_program_is_deterministic ... ok
[INFO] [stdout] test vm::tests::prg_del_removes_lines ... ok
[INFO] [stdout] test vm::tests::pop_empty_array_is_subscript_out_of_range ... ok
[INFO] [stdout] test vm::tests::print_comma_from_tab_boundary_advances_full_step ... ok
[INFO] [stdout] test vm::tests::print_bare_emits_one_blank_line ... ok
[INFO] [stdout] test vm::tests::prg_no_prgedit_check_precedes_arg_check ... ok
[INFO] [stdout] test vm::tests::print_question_alias ... ok
[INFO] [stdout] test vm::tests::locate_range_matches_active_console_dimensions ... ok
[INFO] [stdout] test vm::tests::print_comma_tabs_to_next_tabstep ... ok
[INFO] [stdout] test vm::tests::print_trailing_comma_suppresses_newline ... ok
[INFO] [stdout] test vm::tests::print_multiple_lines ... ok
[INFO] [stdout] test vm::tests::push_appends_at_end ... ok
[INFO] [stdout] test vm::tests::print_integer_and_string ... ok
[INFO] [stdout] test vm::tests::print_semicolon_concatenates_with_no_gap ... ok
[INFO] [stdout] test vm::tests::print_type_mismatch_is_errnum_8 ... ok
[INFO] [stdout] test vm::tests::read_only_sysvars_reject_assignment ... ok
[INFO] [stdout] test vm::tests::print_trailing_semicolon_suppresses_newline ... ok
[INFO] [stdout] test vm::tests::push_string_onto_numeric_array_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::repeat_until ... ok
[INFO] [stdout] test vm::tests::print_negative_number_has_no_leading_space ... ok
[INFO] [stdout] test vm::tests::prg_cold_state_needs_prgedit ... ok
[INFO] [stdout] test vm::tests::push_grows_and_pop_is_lifo ... ok
[INFO] [stdout] test vm::tests::push_onto_numeric_scalar_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::mp_offline_session_reads ... ok
[INFO] [stdout] test vm::tests::rnd_one_is_zero ... ok
[INFO] [stdout] test vm::tests::print_semicolon_number_then_string_no_gap ... ok
[INFO] [stdout] test vm::tests::run_frame_maincnt_advances_without_vsync ... ok
[INFO] [stdout] test vm::tests::print_real_uses_g_format ... ok
[INFO] [stdout] test vm::tests::rpg_textbox_bottom_border_not_scrolled ... ok
[INFO] [stdout] test vm::tests::return_without_gosub_is_errnum_30 ... ok
[INFO] [stdout] test vm::tests::prg_mutator_arg_and_range_guards ... ok
[INFO] [stdout] test vm::tests::run_frame_vsync_loop_holds_steady_rate ... ok
[INFO] [stdout] test vm::tests::rsort_is_descending ... ok
[INFO] [stdout] test vm::tests::rnd_two_arg_selects_series ... ok
[INFO] [stdout] test vm::tests::run_frame_vsync_1_loop_advances_maincnt_one_per_tick ... ok
[INFO] [stdout] test vm::tests::short_circuit_and_or ... ok
[INFO] [stdout] test vm::tests::prgname_and_prgsize_guards ... ok
[INFO] [stdout] test vm::tests::runtime_operators_via_variables ... ok
[INFO] [stdout] test vm::tests::sort_count_past_end_is_out_of_range ... ok
[INFO] [stdout] test vm::tests::sort_non_array_key_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::run_frame_wait_blocks_one_frame_at_a_time ... ok
[INFO] [stdout] test vm::tests::run_frame_wait_zero_does_not_yield ... ok
[INFO] [stdout] test vm::tests::sort_leading_start_count_restricts_the_range ... ok
[INFO] [stdout] test vm::tests::prgedit_guards ... ok
[INFO] [stdout] test vm::tests::sort_string_key_is_lexical ... ok
[INFO] [stdout] test vm::tests::out_of_range_double_to_int_store_is_overflow ... ok
[INFO] [stdout] test vm::tests::slash_is_real_division ... ok
[INFO] [stdout] test vm::tests::shift_count_is_not_masked ... ok
[INFO] [stdout] test vm::tests::named_constants_resolve_to_their_values ... ok
[INFO] [stdout] test vm::tests::seeded_rnd_sequence_matches_otya_golden ... ok
[INFO] [stdout] test vm::tests::shift_is_fifo_and_compacts ... ok
[INFO] [stdout] test vm::tests::spdef_is_shared_across_screens ... ok
[INFO] [stdout] test vm::tests::stick_writes_axes_through_vm ... ok
[INFO] [stdout] test vm::tests::string_builtins_via_assignment ... ok
[INFO] [stdout] test vm::tests::sprites_are_per_display_screen ... ok
[INFO] [stdout] test vm::tests::string_concatenation ... ok
[INFO] [stdout] test vm::tests::sort_reorders_key_and_parallel_arrays ... ok
[INFO] [stdout] test vm::tests::sort_without_a_key_array_is_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::stickex_reads_right_stick_through_vm ... ok
[INFO] [stdout] test vm::tests::string_in_arithmetic_is_type_mismatch ... ok
[INFO] [stdout] test vm::tests::swap_array_elements ... ok
[INFO] [stdout] test vm::tests::swap_scalar_and_array_element ... ok
[INFO] [stdout] test vm::tests::sort_is_stable_rsort_is_its_reverse ... ok
[INFO] [stdout] test vm::tests::swap_array_element_no_suffix_is_real ... ok
[INFO] [stdout] test vm::tests::swap_2d_array_elements ... ok
[INFO] [stdout] test vm::tests::swap_string_array_elements ... ok
[INFO] [stdout] test vm::tests::swap_scalars ... ok
[INFO] [stdout] test vm::tests::swap_aliased_array_element_is_noop ... ok
[INFO] [stdout] test vm::tests::swap_string_array_element_with_numeric_scalar_is_type_mismatch_8 ... ok
[INFO] [stdout] test vm::tests::swap_string_numeric_is_type_mismatch_8 ... ok
[INFO] [stdout] test vm::tests::swap_untyped_var_keeps_value_verbatim ... ok
[INFO] [stdout] test vm::tests::touch_no_touch_baseline_is_zero ... ok
[INFO] [stdout] test vm::tests::swap_typed_var_truncates_to_declared_type ... ok
[INFO] [stdout] test vm::tests::push_pop_on_string_variable_is_char_array ... ok
[INFO] [stdout] test vm::tests::tick_frame_advances_maincnt_and_animation ... ok
[INFO] [stdout] test vm::tests::touch_wrong_out_count_is_errnum_4 ... ok
[INFO] [stdout] test vm::tests::talk_then_talkchk_then_stop ... ok
[INFO] [stdout] test vm::tests::talk_in_value_context_is_errnum_4 ... ok
[INFO] [stdout] test vm::tests::string_repetition ... ok
[INFO] [stdout] test vm::tests::sysbeep_is_writable_and_round_trips ... ok
[INFO] [stdout] test vm::tests::touch_writes_three_out_vars_through_vm ... ok
[INFO] [stdout] test vm::tests::touch_wireless_terminal_is_comms_error ... ok
[INFO] [stdout] test vm::tests::unbounded_recursion_is_stack_overflow ... ok
[INFO] [stdout] test vm::tests::touch_empty_out_slots_discard_results ... ok
[INFO] [stdout] test vm::tests::unary_neg_and_not ... ok
[INFO] [stdout] test vm::tests::unshift_inserts_at_front ... ok
[INFO] [stdout] test vm::tests::use_resource_string_existing_file_marks_slot ... ok
[INFO] [stdout] test vm::tests::use_resource_string_missing_file_is_load_failed ... ok
[INFO] [stdout] test vm::tests::tick_frames_advances_multiple_frames_at_once ... ok
[INFO] [stdout] test vm::tests::use_running_slot_is_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::use_resource_string_running_slot_is_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::tabstep_is_writable_and_takes_effect ... ok
[INFO] [stdout] test vm::tests::use_string_loaded_common_def_passes_args_and_out ... ok
[INFO] [stdout] test vm::tests::use_out_of_range_slot_is_out_of_range ... ok
[INFO] [stdout] test vm::tests::use_string_missing_file_is_load_failed ... ok
[INFO] [stdout] test vm::tests::use_string_into_non_running_slot_0_marks_common_def_callable ... ok
[INFO] [stdout] test vm::tests::rng_error_conditions ... ok
[INFO] [stdout] test vm::tests::vm_boots_with_default_sprite_bg_and_font_pages ... ok
[INFO] [stdout] test vm::tests::suffixless_arithmetic_promotes_on_overflow ... ok
[INFO] [stdout] test vm::tests::use_string_loads_common_def_callable_cross_slot ... ok
[INFO] [stdout] test vm::tests::use_numeric_marks_a_slot_executable ... ok
[INFO] [stdout] test vm::tests::use_string_bare_name_defaults_to_running_slot_errnum_4 ... ok
[INFO] [stdout] test vm::tests::xon_expad_sets_result_true ... ok
[INFO] [stdout] test vm::tests::vsync_loop_advances_maincnt_one_per_frame ... ok
[INFO] [stdout] test vm::tests::x_edge_50_wraps_not_panics ... ok
[INFO] [stdout] test vm::tests::while_loop ... ok
[INFO] [stdout] test vm::tests::xscreen_resizes_the_active_console_to_match_resolution ... ok
[INFO] [stdout] test vm::tests::version_encodes_3_6_0 ... ok
[INFO] [stdout] test vm::tests::writable_sysvars_reject_a_string ... ok
[INFO] [stdout] test vm::tests::xon_motion_enables_then_sensors_run ... ok
[INFO] [stdout] test vm::tests::use_resource_string_bad_resource_is_illegal_function_call ... ok
[INFO] [stdout] test vm::tests::visible_runs_and_gates_layers ... ok
[INFO] [stdout] test vm::tests::wavset_defines_user_instrument ... ok
[INFO] [stdout] test vm::tests::wait_advances_maincnt_by_the_count ... ok
[INFO] [stdout] test vm::tests::xscreen_tracks_per_screen_allocations ... ok
[INFO] [stdout] test vm::tests::xscreen_runs_documented_forms ... ok
[INFO] [stdout] test vm::tests::xon_mic_enables_then_mic_commands_run ... ok
[INFO] [stdout] test vm::tests::xscreen_error_conditions ... ok
[INFO] [stdout] test vm::tests::whole_paren_command_form_raises_each_handlers_own_errnum ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- vm::tests::call_sprite_runs_bound_def_process stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'vm::tests::call_sprite_runs_bound_def_process' (2655) panicked at crates/sb-core/src/vm.rs:4304:18:
[INFO] [stdout] run: Sb { errnum: 4, line: 2 }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6103b1c3d238 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x6103b1c3d238 - std[10d71ac2c42b111e]::backtrace_rs::backtrace::trace_unsynchronized::<std[10d71ac2c42b111e]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x6103b1c3d238 - std[10d71ac2c42b111e]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x6103b1c3d238 - <<std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[8fb95b77d25ab6c8]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x6103b1c5275a - <core[8fb95b77d25ab6c8]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x6103b1c5275a - core[8fb95b77d25ab6c8]::fmt::write
[INFO] [stdout]    6:     0x6103b1c41a52 - std[10d71ac2c42b111e]::io::default_write_fmt::<alloc[a7ee25936407c584]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x6103b1c41a52 - <alloc[a7ee25936407c584]::vec::Vec<u8> as std[10d71ac2c42b111e]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x6103b1c1c3f6 - <std[10d71ac2c42b111e]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x6103b1c1c3f6 - std[10d71ac2c42b111e]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x6103b1c35789 - std[10d71ac2c42b111e]::panicking::default_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x6103b1b8040c - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   12:     0x6103b1b8040c - test[213991da798b1c03]::test_main_inner::<test[213991da798b1c03]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x6103b1c35942 - <alloc[a7ee25936407c584]::boxed::Box<dyn for<'a, 'b> core[8fb95b77d25ab6c8]::ops::function::Fn<(&'a std[10d71ac2c42b111e]::panic::PanicHookInfo<'b>,), Output = ()> + core[8fb95b77d25ab6c8]::marker::Sync + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::Fn<(&std[10d71ac2c42b111e]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2289:9
[INFO] [stdout]   14:     0x6103b1c35942 - std[10d71ac2c42b111e]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x6103b1c1c4b2 - std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x6103b1c116b9 - std[10d71ac2c42b111e]::sys::backtrace::__rust_end_short_backtrace::<std[10d71ac2c42b111e]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x6103b1c1d1cd - __rustc[3be9ba60078d4c86]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x6103b1c52f1c - core[8fb95b77d25ab6c8]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x6103b1c52c92 - core[8fb95b77d25ab6c8]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x6103b1a21451 - <core[8fb95b77d25ab6c8]::result::Result<sb_core[f7896d4afdb770d1]::vm::Halt, sb_core[f7896d4afdb770d1]::vm::VmError>>::expect
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x6103b19528d6 - sb_core[f7896d4afdb770d1]::vm::tests::run_b
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/sb-core/src/vm.rs:4304:18
[INFO] [stdout]   22:     0x6103b1965232 - sb_core[f7896d4afdb770d1]::vm::tests::call_sprite_runs_bound_def_process
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/sb-core/src/vm.rs:6725:18
[INFO] [stdout]   23:     0x6103b194eae7 - sb_core[f7896d4afdb770d1]::vm::tests::call_sprite_runs_bound_def_process::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/sb-core/src/vm.rs:6722:44
[INFO] [stdout]   24:     0x6103b1b013d6 - <sb_core[f7896d4afdb770d1]::vm::tests::call_sprite_runs_bound_def_process::{closure#0} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x6103b1b734eb - <fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x6103b1b734eb - test[213991da798b1c03]::__rust_begin_short_backtrace::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, fn() -> core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x6103b1b80d65 - test[213991da798b1c03]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x6103b1b80d65 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x6103b1b80d65 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x6103b1b80d65 - std[10d71ac2c42b111e]::panicking::catch_unwind::<core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>, core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x6103b1b80d65 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<test[213991da798b1c03]::run_test_in_process::{closure#0}>, core[8fb95b77d25ab6c8]::result::Result<(), alloc[a7ee25936407c584]::string::String>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x6103b1b80d65 - test[213991da798b1c03]::run_test_in_process
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x6103b1b80d65 - test[213991da798b1c03]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x6103b1b7a674 - test[213991da798b1c03]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x6103b1b7a674 - std[10d71ac2c42b111e]::sys::backtrace::__rust_begin_short_backtrace::<test[213991da798b1c03]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x6103b1b83de2 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x6103b1b83de2 - <core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x6103b1b83de2 - std[10d71ac2c42b111e]::panicking::catch_unwind::do_call::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x6103b1b83de2 - std[10d71ac2c42b111e]::panicking::catch_unwind::<(), core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x6103b1b83de2 - std[10d71ac2c42b111e]::panic::catch_unwind::<core[8fb95b77d25ab6c8]::panic::unwind_safe::AssertUnwindSafe<std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x6103b1b83de2 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x6103b1b83de2 - <std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked<test[213991da798b1c03]::run_test::{closure#1}, ()>::{closure#1} as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x6103b1c3cb9f - <alloc[a7ee25936407c584]::boxed::Box<dyn core[8fb95b77d25ab6c8]::ops::function::FnOnce<(), Output = ()> + core[8fb95b77d25ab6c8]::marker::Send> as core[8fb95b77d25ab6c8]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/alloc/src/boxed.rs:2275:9
[INFO] [stdout]   44:     0x6103b1c3cb9f - <std[10d71ac2c42b111e]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x7217fc0fcaa4 - <unknown>
[INFO] [stdout]   46:     0x7217fc189a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     vm::tests::call_sprite_runs_bound_def_process
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 621 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 37.46s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p sb-core --lib`
[INFO] running `Command { std: "docker" "inspect" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570", kill_on_drop: false }`
[INFO] [stdout] 17664ffb5a0aa36157df62ef6c99a2cf5c18b29443c5d6bb41a184ad42225570
