[INFO] fetching crate vrm-runtime 0.1.0...
[INFO] testing vrm-runtime-0.1.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate vrm-runtime 0.1.0 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate vrm-runtime 0.1.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate vrm-runtime 0.1.0
[INFO] tweaked toml for crates.io crate vrm-runtime 0.1.0 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vrm-runtime 0.1.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate vrm-runtime 0.1.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded urlencoding v2.1.3
[INFO] [stderr]   Downloaded gltf v1.4.1
[INFO] [stderr]   Downloaded bytemuck v1.25.2
[INFO] [stderr]   Downloaded vrm-spec v0.1.1
[INFO] [stderr]   Downloaded gltf-json v1.4.1
[INFO] [stderr]   Downloaded inflections v1.1.1
[INFO] [stderr]   Downloaded gltf-derive v1.4.1
[INFO] [stderr]   Downloaded mikktspace v0.3.0
[INFO] [stderr]   Downloaded glam v0.15.2
[INFO] [stderr]   Downloaded glam v0.29.3
[INFO] [stderr]   Downloaded pxfm v0.1.30
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72
[INFO] running `Command { std: "docker" "start" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", 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" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", 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" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling simd-adler32 v0.3.10
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling inflections v1.1.1
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling pxfm v0.1.30
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling byteorder-lite v0.1.0
[INFO] [stderr]    Compiling bytemuck v1.25.2
[INFO] [stderr]    Compiling urlencoding v2.1.3
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling base64 v0.13.1
[INFO] [stderr]    Compiling rustc-hash v2.1.3
[INFO] [stderr]    Compiling glam v0.15.2
[INFO] [stderr]    Compiling glam v0.29.3
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling fdeflate v0.3.7
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling png v0.18.1
[INFO] [stderr]    Compiling mikktspace v0.3.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling moxcms v0.8.1
[INFO] [stderr]    Compiling gltf-derive v1.4.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling gltf-json v1.4.1
[INFO] [stderr]    Compiling image v0.25.10
[INFO] [stderr]    Compiling gltf v1.4.1
[INFO] [stderr]    Compiling vrm-spec v0.1.1
[INFO] [stderr]    Compiling vrm-runtime v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 35.60s
[INFO] running `Command { std: "docker" "inspect" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", 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" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling vrm-runtime v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 13.17s
[INFO] running `Command { std: "docker" "inspect" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", 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" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vrm_runtime-b2d6a71ce931e83d)
[INFO] [stdout] 
[INFO] [stdout] running 80 tests
[INFO] [stdout] test first_person::tests::head_node_indices_includes_head_and_descendants ... ok
[INFO] [stdout] test constraint::tests::aim_constraint_points_axis_at_source ... ok
[INFO] [stdout] test instance::tests::instance_stores_expression_weight ... ok
[INFO] [stdout] test first_person::tests::visible_indices_apply_camera_annotations ... ok
[INFO] [stdout] test constraint::tests::rotation_constraint_weight_slerps ... ok
[INFO] [stdout] test constraint::tests::self_referencing_constraint_is_ignored ... ok
[INFO] [stdout] test instance::tests::instance_evaluates_happy_expression ... ok
[INFO] [stdout] test constraint::tests::rotation_constraint_copies_source_local_rotation ... ok
[INFO] [stdout] test constraint::tests::roll_constraint_transfers_axis_rotation ... ok
[INFO] [stdout] test constraint::tests::circular_dependencies_are_skipped ... ok
[INFO] [stdout] test constraint::tests::dependency_chain_resolves_in_order ... ok
[INFO] [stdout] test first_person::tests::auto_split_maps_joint_slots_through_the_skin ... ok
[INFO] [stdout] test constraint::tests::no_constraints_yield_no_updates ... ok
[INFO] [stdout] test material::tests::khr_emissive_strength_takes_precedence_over_the_archived_multiplier ... ok
[INFO] [stdout] test material::tests::loads_unlit_and_common_pbr_textures ... ok
[INFO] [stdout] test instance::tests::look_at_space_follows_the_current_head_translation ... ok
[INFO] [stdout] test material::tests::mtoon_takes_precedence_over_unlit_without_losing_the_unlit_flag ... ok
[INFO] [stdout] test material::tests::rejects_negative_emissive_strength_values ... ok
[INFO] [stdout] test material::tests::archived_emissive_multiplier_is_used_as_a_fallback ... ok
[INFO] [stdout] test first_person::tests::auto_split_does_not_treat_a_slot_number_as_a_node_index ... ok
[INFO] [stdout] test first_person::tests::auto_split_treats_unskinned_mesh_as_both ... ok
[INFO] [stdout] test first_person::tests::auto_split_separates_head_related_triangles ... ok
[INFO] [stdout] test instance::tests::look_at_space_follows_the_current_head_rotation ... ok
[INFO] [stdout] test instance::tests::look_at_uses_the_head_node_when_the_head_has_no_mesh ... ok
[INFO] [stdout] test primitive::tests::morphing_recomputes_flat_normals_from_morphed_positions ... ok
[INFO] [stdout] test primitive::tests::morphing_renormalizes_smooth_normals_with_deltas ... ok
[INFO] [stdout] test primitive::tests::triangle_strip_is_normalized_with_gl_tf_winding ... ok
[INFO] [stdout] test primitive::tests::mikkts_skips_without_normals_or_uv_set ... ok
[INFO] [stdout] test primitive::tests::flat_normals_are_unit_length ... ok
[INFO] [stdout] test primitive::tests::loaded_morph_weights_must_be_finite ... ok
[INFO] [stdout] test render::tests::malformed_runtime_palette_does_not_panic_or_partially_skin ... ok
[INFO] [stdout] test primitive::tests::line_loop_becomes_closed_line_strip ... ok
[INFO] [stdout] test primitive::tests::morphing_moves_positions_by_weighted_deltas ... ok
[INFO] [stdout] test render::tests::morph_weights_are_applied ... ok
[INFO] [stdout] test primitive::tests::base_color_shortcut_uses_texture_id_and_transformed_uv_set ... ok
[INFO] [stdout] test primitive::tests::degenerate_triangle_yields_zero_normal ... ok
[INFO] [stdout] test node::tests::cyclic_raw_nodes_use_a_deterministic_finite_fallback ... ok
[INFO] [stdout] test primitive::tests::flat_normals_follow_triangle_winding ... ok
[INFO] [stdout] test render::tests::skinned_vertex_with_missing_palette_stays_put ... ok
[INFO] [stdout] test runtime::tests::expression_controls_persist_between_updates ... ok
[INFO] [stdout] test material::tests::shading_shift_texture_preserves_khr_texture_transform ... ok
[INFO] [stdout] test primitive::tests::normal_texture_transform_uv_override_drives_tangent_generation ... ok
[INFO] [stdout] test runtime::tests::cpu_and_gpu_paths_use_the_same_morph_weights ... ok
[INFO] [stdout] test runtime::tests::expression_morphs_start_at_zero_instead_of_gltf_weights ... ok
[INFO] [stdout] test render::tests::non_skinned_uses_world_transform ... ok
[INFO] [stdout] test runtime::tests::play_rejects_malformed_keyframes ... ok
[INFO] [stdout] test runtime::tests::rejects_material_clock_overflow_without_mutating_time ... ok
[INFO] [stdout] test runtime::tests::rejects_invalid_runtime_inputs ... ok
[INFO] [stdout] test runtime::tests::reset_clears_controls_and_cached_morphs ... ok
[INFO] [stdout] test render::tests::skinned_primitive_moves_by_joint_matrix ... ok
[INFO] [stdout] test render::tests::skinned_vertex_with_zero_weight_stays_put ... ok
[INFO] [stdout] test runtime::tests::shared_asset_does_not_share_instance_state ... ok
[INFO] [stdout] test render::tests::tangent_handedness_is_preserved ... ok
[INFO] [stdout] test runtime::tests::frame_exposes_expression_evaluated_material_values ... ok
[INFO] [stdout] test primitive::tests::loaded_tangent_policy_ignores_the_stored_stream ... ok
[INFO] [stdout] test spring_bone::tests::outside_and_inside_spheres_restrict_opposite_domains ... ok
[INFO] [stdout] test spring_bone::tests::outside_and_inside_capsules_restrict_opposite_domains ... ok
[INFO] [stdout] test runtime::tests::rejects_animation_step_overflow_without_mutating_any_clock ... ok
[INFO] [stdout] test spring_bone::tests::programmatic_plane_requires_a_finite_normalized_normal ... ok
[INFO] [stdout] test spring_bone::tests::stiffness_holds_tail_on_axis ... ok
[INFO] [stdout] test spring_bone::tests::collider_deflects_tail_away ... ok
[INFO] [stdout] test runtime::tests::update_advances_animation_before_producing_the_frame ... ok
[INFO] [stdout] test spring_bone::tests::extended_inside_sphere_overrides_the_core_fallback ... ok
[INFO] [stdout] test spring_bone::tests::extended_plane_overrides_the_core_fallback_during_loading ... ok
[INFO] [stdout] test spring_bone::tests::core_collider_fallback_loads_as_an_outside_shape ... ok
[INFO] [stdout] test spring_bone::tests::perpendicular_gravity_swings_tail ... ok
[INFO] [stdout] test spring_bone::tests::extended_inside_capsule_preserves_head_tail_and_radius ... ok
[INFO] [stdout] test spring_bone::tests::plane_keeps_the_joint_sphere_in_the_positive_half_space ... ok
[INFO] [stdout] test spring_bone::tests::initialize_orders_springs_by_joint_ancestry ... ok
[INFO] [stdout] test primitive::tests::mikkts_generates_tangent_from_uv_layout ... ok
[INFO] [stdout] test spring_bone::tests::no_springs_yields_no_updates ... ok
[INFO] [stdout] test spring_bone::tests::world_collider_transforms_radius_and_plane_normal ... ok
[INFO] [stdout] test spring_bone::tests::validates_extended_collider_version_and_shape_cardinality ... ok
[INFO] [stdout] test tests::test_avatar_default ... ok
[INFO] [stdout] test tests::test_avatar_from_invalid_bytes ... ok
[INFO] [stdout] test tests::test_corrupted_meta_json_deserialization ... ok
[INFO] [stdout] test spring_bone::tests::update_is_deterministic ... ok
[INFO] [stdout] test spring_bone::tests::validates_extended_collider_numbers_and_plane_normal ... ok
[INFO] [stdout] test node::tests::computes_a_deep_reverse_ordered_hierarchy_without_recursion ... ok
[INFO] [stdout] test primitive::tests::primitive_traversal_handles_a_deep_node_chain_iteratively ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 80 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.30s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/animation_test.rs (/opt/rustwide/target/debug/deps/animation_test-4196761c8ac2fbe0)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test interpolates_bone_translation_delta ... ok
[INFO] [stdout] test programmatic_clip_uses_typed_tracks_as_its_only_state ... ok
[INFO] [stdout] test exposes_and_replaces_a_programmatic_look_at_track ... ok
[INFO] [stdout] test runtime_rejects_programmatic_translation_on_a_non_hips_bone ... ok
[INFO] [stdout] test cubic_quaternion_sampling_survives_an_exact_zero_midpoint ... ok
[INFO] [stdout] test applies_programmatic_animation_through_the_avatar_aware_path ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/avatar_load_test.rs (/opt/rustwide/target/debug/deps/avatar_load_test-63ba1e86d9ae2dfa)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_load_sample_vrm_file ... ok
[INFO] [stdout] test test_load_invalid_bytes ... ok
[INFO] [stdout] test test_load_non_existent_file ... ok
[INFO] [stderr]      Running tests/builder_validation.rs (/opt/rustwide/target/debug/deps/builder_validation-c354f0ca585cf73b)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test builder_rejects_non_uniform_humanoid_scale ... ok
[INFO] [stdout] test builder_rejects_invalid_skin_and_spring_references ... ok
[INFO] [stdout] test builder_rejects_invalid_humanoid_hierarchy ... ok
[INFO] [stdout] test builder_rejects_invalid_constraint_references_and_cycles ... ok
[INFO] [stdout] test builder_rejects_optional_bones_with_invalid_parent_relationships ... ok
[INFO] [stdout] test builder_rejects_duplicate_humanoid_assignments ... ok
[INFO] [stdout] test builder_rejects_invalid_scale_on_an_unmapped_node ... ok
[INFO] [stdout] test builder_reports_a_transitively_invalid_parent_without_panicking ... ok
[INFO] [stderr]      Running tests/conformance_core.rs (/opt/rustwide/target/debug/deps/conformance_core-e09f23081a562734)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 40 tests
[INFO] [stdout] test accepts_a_minimal_vrm_1_0_document ... ok
[INFO] [stdout] test rejects_a_broken_humanoid_parent_relationship ... ok
[INFO] [stdout] test rejects_a_node_constraint_cycle ... ok
[INFO] [stdout] test permissive_mode_reports_empty_meta_references ... ok
[INFO] [stdout] test rejects_a_cyclic_node_graph ... ok
[INFO] [stdout] test permissive_mode_does_not_accept_invalid_look_at_schema_values ... ok
[INFO] [stdout] test rejects_an_extension_object_missing_from_extensions_used ... ok
[INFO] [stdout] test rejects_an_optional_humanoid_bone_without_its_required_parent ... ok
[INFO] [stdout] test rejects_another_vrmc_vrm_spec_version ... ok
[INFO] [stdout] test rejects_a_node_with_multiple_parents ... ok
[INFO] [stdout] test permissive_mode_reports_an_unknown_optional_extension ... ok
[INFO] [stdout] test accepts_supported_vrm_ecosystem_extensions_when_required ... ok
[INFO] [stdout] test rejects_an_optional_humanoid_bone_object_without_a_node ... ok
[INFO] [stdout] test permissive_mode_still_rejects_an_unknown_required_extension ... ok
[INFO] [stdout] test rejects_an_optional_humanoid_bone_outside_its_parent_branch ... ok
[INFO] [stdout] test binary_expression_is_fully_suppressed_by_any_override_effect ... ok
[INFO] [stdout] test rejects_beta_vrmc_vrm_spec_version ... ok
[INFO] [stdout] test binary_expression_at_threshold_uses_the_enabled_output ... ok
[INFO] [stdout] test rejects_a_malformed_mtoon_extension_instead_of_ignoring_it ... ok
[INFO] [stdout] test rejects_duplicate_extensions_required_entries ... ok
[INFO] [stdout] test rejects_a_malformed_node_constraint_extension ... ok
[INFO] [stdout] test rejects_duplicate_extensions_used_entries ... ok
[INFO] [stdout] test rejects_humanoid_bones_that_reference_missing_nodes ... ok
[INFO] [stdout] test rejects_empty_meta_references ... ok
[INFO] [stdout] test rejects_expression_vectors_with_invalid_lengths ... ok
[INFO] [stdout] test rejects_duplicate_humanoid_node_assignments ... ok
[INFO] [stdout] test permissive_mode_normalizes_an_extended_plane_collider ... ok
[INFO] [stdout] test rejects_empty_expression_bind_arrays ... ok
[INFO] [stdout] test rejects_expression_numbers_not_representable_as_f32 ... ok
[INFO] [stdout] test rejects_invalid_first_person_annotation_structure ... ok
[INFO] [stdout] test rejects_invalid_spring_bone_references ... ok
[INFO] [stdout] test rejects_invalid_look_at_numeric_values ... ok
[INFO] [stdout] test reports_archived_emissive_extension_and_khr_precedence ... ok
[INFO] [stdout] test rejects_undeclared_extensions_in_known_vrm_extension_properties ... ok
[INFO] [stdout] test runtime_advances_mtoon_uv_animation_independently ... ok
[INFO] [stdout] test validates_positive_uniform_scale_on_unmapped_nodes ... ok
[INFO] [stdout] test rejects_missing_required_humanoid_bones ... ok
[INFO] [stdout] test rejects_non_uniform_or_non_positive_node_scale ... ok
[INFO] [stdout] test rejects_custom_expression_names_reserved_for_presets ... ok
[INFO] [stdout] test rejects_preset_expressions_that_override_their_own_category ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conformance_samples.rs (/opt/rustwide/target/debug/deps/conformance_samples-3ee2077bb0a42e5e)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test loads_every_official_vrm_1_0_sample ... ok
[INFO] [stdout] test official_feature_samples_expose_their_declared_extensions ... ok
[INFO] [stdout] test seed_san_preserves_gltf_material_indices ... ok
[INFO] [stdout] test official_mtoon_uv_sample_preserves_material_and_texture_mapping ... ok
[INFO] [stderr]      Running tests/constraint_test.rs (/opt/rustwide/target/debug/deps/constraint_test-46f06472aa155309)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_load_sample_vrm_constraints ... ok
[INFO] [stdout] test test_default_avatar_has_no_constraints ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/expression_load_test.rs (/opt/rustwide/target/debug/deps/expression_load_test-73762e6a7075f316)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test evaluates_and_clamps_a_morph_bind ... ok
[INFO] [stdout] test public_evaluation_applies_binary_thresholding ... ok
[INFO] [stdout] test expands_material_and_texture_binds_without_losing_values ... ok
[INFO] [stdout] test finds_expression_by_name ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/first_person_test.rs (/opt/rustwide/target/debug/deps/first_person_test-bd033eed65337fbd)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_default_avatar_has_empty_first_person ... ok
[INFO] [stdout] test test_flag_for_returns_explicit_flag ... ok
[INFO] [stdout] test test_flag_for_returns_auto_for_unannotated_node ... ok
[INFO] [stdout] test test_load_sample_vrm_first_person ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/humanoid_test.rs (/opt/rustwide/target/debug/deps/humanoid_test-ee10f6ad2e6b46fd)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_load_humanoid_bones_from_sample_vrm ... ok
[INFO] [stdout] test test_avatar_instance_set_and_get_bone_rotation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/image_loading.rs (/opt/rustwide/target/debug/deps/image_loading-befe5a51107fd9d5)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test malformed_image_sources_return_errors_instead_of_panicking ... ok
[INFO] [stdout] test data_uri_images_preserve_their_encoded_bytes_and_mime_type ... ok
[INFO] [stdout] test file_loading_resolves_relative_image_uris ... ok
[INFO] [stdout] test thumbnail_must_be_square_png_or_jpeg ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/look_at_test.rs (/opt/rustwide/target/debug/deps/look_at_test-4286fbb6f50a247b)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test test_look_at_offset_from_head_bone_override ... ok
[INFO] [stdout] test test_load_look_at_metadata_from_sample_vrm ... ok
[INFO] [stdout] test test_look_at_calculation_yaw_pitch ... ok
[INFO] [stdout] test test_look_at_target_storage_in_instance ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/material_api.rs (/opt/rustwide/target/debug/deps/material_api-6c49f4c7a844e83e)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test materials_distinguish_common_gltf_data_from_mtoon ... ok
[INFO] [stdout] test textures_resolve_to_images_and_samplers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/material_profile.rs (/opt/rustwide/target/debug/deps/material_profile-d56711f78d804416)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test malformed_mtoon_shading_shift_transform_is_not_silently_ignored ... ok
[INFO] [stdout] test archived_vrm_emissive_multiplier_is_a_supported_fallback ... ok
[INFO] [stdout] test modern_emissive_strength_overrides_the_archived_vrm_multiplier ... ok
[INFO] [stdout] test mtoon_texture_references_are_validated ... ok
[INFO] [stdout] test invalid_emissive_multipliers_are_rejected ... ok
[INFO] [stdout] test mtoon_has_rendering_precedence_but_retains_the_unlit_declaration ... ok
[INFO] [stdout] test invalid_mtoon_numeric_values_are_rejected_before_narrowing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/programmatic_assets.rs (/opt/rustwide/target/debug/deps/programmatic_assets-febb0d451763e695)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test asset_builder_rejects_weighted_joint_indices_outside_the_selected_skin ... ok
[INFO] [stdout] test asset_builder_validates_every_static_material_texture_slot ... ok
[INFO] [stdout] test asset_builder_rejects_expressions_that_override_their_own_category ... ok
[INFO] [stdout] test asset_builder_rejects_invalid_look_at_values ... ok
[INFO] [stdout] test public_builders_create_a_renderable_asset_with_typed_ids ... ok
[INFO] [stdout] test primitive_builder_rejects_invalid_local_geometry ... ok
[INFO] [stdout] test primitive_builder_can_construct_skinned_and_morphed_vertex_data ... ok
[INFO] [stdout] test image_constructor_accepts_owned_programmatic_data ... ok
[INFO] [stdout] test texture_sampler_defaults_match_gltf ... ok
[INFO] [stderr]      Running tests/public_api.rs (/opt/rustwide/target/debug/deps/public_api-3d5c17351f17cce3)
[INFO] [stdout] test asset_builder_rejects_out_of_range_static_material_values ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test strict_validation_is_the_default ... ok
[INFO] [stdout] test runtime_exposes_persistent_controls_and_dual_render_paths ... ok
[INFO] [stdout] test programmatic_expressions_expose_all_vrm_controls ... ok
[INFO] [stdout] test owned_frames_expose_the_same_dynamic_outputs_as_borrowed_frames ... ok
[INFO] [stdout] test runtime_owns_a_shared_asset ... ok
[INFO] [stdout] test an_empty_programmatic_asset_is_rejected ... ok
[INFO] [stdout] test runtime_owns_animation_playback_state ... ok
[INFO] [stdout] test capabilities_make_known_limitations_discoverable ... ok
[INFO] [stdout] test typed_ids_do_not_share_a_type ... ok
[INFO] [stdout] test typed_ids_resolve_asset_objects ... ok
[INFO] [stdout] test concise_loader_names_are_available ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/spring_bone_test.rs (/opt/rustwide/target/debug/deps/spring_bone_test-a1598b0a8bf162b7)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_load_sample_vrm_spring_bone ... ok
[INFO] [stdout] test test_default_avatar_has_empty_spring_bone ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/spring_conformance.rs (/opt/rustwide/target/debug/deps/spring_conformance-710fa6e7e56a67dc)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test loaded_springs_reject_an_overlap_at_a_skipped_node ... ok
[INFO] [stdout] test loaded_spring_center_must_be_the_first_joint_or_its_ancestor ... ok
[INFO] [stdout] test centers_on_unrelated_branches_are_valid_for_loaded_and_programmatic_springs ... ok
[INFO] [stdout] test programmatic_spring_numbers_must_be_finite ... ok
[INFO] [stdout] test programmatic_spring_center_must_be_the_first_joint_or_its_ancestor ... ok
[INFO] [stdout] test programmatic_springs_reject_an_overlap_at_a_skipped_node ... ok
[INFO] [stdout] test programmatic_empty_collider_group_is_rejected ... ok
[INFO] [stdout] test programmatic_spring_center_cannot_be_at_or_below_another_chain_joint ... ok
[INFO] [stdout] test programmatic_empty_top_level_spring_collections_equal_the_default ... ok
[INFO] [stdout] test loaded_spring_center_cannot_be_at_or_below_another_chain_joint ... ok
[INFO] [stdout] test loaded_spring_arrays_honor_json_schema_min_items ... ok
[INFO] [stdout] test loaded_core_spring_numbers_must_remain_finite_as_f32 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/vertex_attribute_sets.rs (/opt/rustwide/target/debug/deps/vertex_attribute_sets-506a72fb2df82edc)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test first_person_auto_considers_head_weights_in_later_sets ... ok
[INFO] [stdout] test loader_rejects_morph_weight_counts_that_do_not_match_each_primitive ... ok
[INFO] [stdout] test loader_rejects_an_element_index_outside_the_position_buffer ... ok
[INFO] [stdout] test loader_rejects_too_few_inverse_bind_matrices ... ok
[INFO] [stdout] test loader_rejects_a_non_finite_vertex_attribute ... ok
[INFO] [stdout] test loader_checks_effective_morph_weights_against_every_mesh_primitive ... ok
[INFO] [stdout] test loader_rejects_a_weighted_out_of_range_joint_in_a_later_set ... ok
[INFO] [stdout] test loader_rejects_a_skinning_set_count_mismatch ... ok
[INFO] [stdout] test loader_defaults_missing_morph_weights_to_zero_per_target ... ok
[INFO] [stdout] test loader_rejects_a_missing_joint_weight_pair ... ok
[INFO] [stdout] test loader_normalizes_weights_across_sets_and_preserves_zero_weight_vertices ... ok
[INFO] [stdout] test loader_inherits_mesh_morph_weights_and_prefers_node_weights ... ok
[INFO] [stdout] test loader_rejects_a_referenced_primitive_without_position ... ok
[INFO] [stdout] test loader_preserves_every_texcoord_and_skinning_set ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/vrma_loading.rs (/opt/rustwide/target/debug/deps/vrma_loading-47639e5864917691)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test rejects_required_extensions_not_declared_as_used ... ok
[INFO] [stdout] test rejects_duplicate_extensions_required_entries ... ok
[INFO] [stdout] test runtime_retargets_rotation_and_scales_hips_translation ... ok
[INFO] [stdout] test strict_rejects_an_optional_bone_outside_its_humanoid_parent_branch ... ok
[INFO] [stdout] test typed_tracks_sample_step_linear_and_cubic_spline ... ok
[INFO] [stdout] test rejects_an_extension_object_missing_from_extensions_used ... ok
[INFO] [stdout] test strict_rejects_an_optional_bone_without_its_required_parent_bone ... ok
[INFO] [stdout] test playback_can_disable_hips_translation_scaling ... ok
[INFO] [stdout] test rejects_non_1_0_vrma_versions ... ok
[INFO] [stdout] test permissive_mode_reports_an_unknown_optional_extension ... ok
[INFO] [stdout] test strict_rejects_and_permissive_ignores_forbidden_bone_translation ... ok
[INFO] [stdout] test loads_first_vrma_animation_and_maps_vrm_channels ... ok
[INFO] [stdout] test rejects_an_animation_output_accessor_before_the_reader_can_panic ... ok
[INFO] [stdout] test rejects_an_optional_bone_object_without_a_node ... ok
[INFO] [stdout] test strict_rejects_and_permissive_reports_invalid_humanoid_hierarchy ... ok
[INFO] [stdout] test rejects_duplicate_extensions_used_entries ... ok
[INFO] [stdout] test rejects_an_undeclared_extension_in_a_vrma_expression_property ... ok
[INFO] [stdout] test loads_only_the_first_gltf_animation_and_reports_the_rest ... ok
[INFO] [stdout] test runtime_composes_a_missing_optional_bone_into_next_mapped_descendants ... ok
[INFO] [stdout] test validates_scale_on_every_node_in_the_vrma_t_pose ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vrm_runtime
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test src/../README.md - (line 88) - compile ... ok
[INFO] [stdout] test src/avatar.rs - avatar::AvatarAsset::load (line 421) - compile ... ok
[INFO] [stdout] test src/../README.md - (line 218) ... ok
[INFO] [stdout] test src/animation.rs - animation::VrmAnimation::load (line 564) - compile ... ok
[INFO] [stdout] test src/../README.md - (line 151) - compile ... ok
[INFO] [stdout] test src/../README.md - (line 50) - compile ... ok
[INFO] [stdout] test src/../README.md - (line 185) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.02s; merged doctests compilation took 0.91s
[INFO] running `Command { std: "docker" "inspect" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72", kill_on_drop: false }`
[INFO] [stdout] 963a14d0a1831a6e7d7bde7814178ff18c93a569ab317f99c5d5ada308566d72
