[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:: [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 - <::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 - ::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::> [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:622:11 [INFO] [stdout] 7: 0x6103b1c41a52 - as std[10d71ac2c42b111e]::io::Write>::write_fmt [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/io/mod.rs:1977:13 [INFO] [stdout] 8: 0x6103b1c1c3f6 - ::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 - 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::::{closure#0} [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/test/src/lib.rs:155:21 [INFO] [stdout] 13: 0x6103b1c35942 - 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:: [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 - >::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 - >::call_once [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x6103b1b734eb - 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::, 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 - 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]::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]::panic::unwind_safe::AssertUnwindSafe> [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/panicking.rs:544:19 [INFO] [stdout] 31: 0x6103b1b80d65 - std[10d71ac2c42b111e]::panic::catch_unwind::, 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:: [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/backtrace.rs:166:18 [INFO] [stdout] 36: 0x6103b1b83de2 - std[10d71ac2c42b111e]::thread::lifecycle::spawn_unchecked::::{closure#1}::{closure#0} [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:70:13 [INFO] [stdout] 37: 0x6103b1b83de2 - ::{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::::{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::{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::::{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::::{closure#1} [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/thread/lifecycle.rs:68:26 [INFO] [stdout] 42: 0x6103b1b83de2 - ::{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 - + 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 - ::new::thread_start [INFO] [stdout] at /rustc/b2282dd5646a57d570fc86fbe8df0e8826665ef6/library/std/src/sys/thread/unix.rs:118:17 [INFO] [stdout] 45: 0x7217fc0fcaa4 - [INFO] [stdout] 46: 0x7217fc189a64 - clone [INFO] [stdout] 47: 0x0 - [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