[INFO] cloning repository https://github.com/moriyoshi/vgmck-rs
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/moriyoshi/vgmck-rs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmoriyoshi%2Fvgmck-rs", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmoriyoshi%2Fvgmck-rs'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7e405aeaa543d426f6ac102982e9a47ed49fc5be
[INFO] testing moriyoshi/vgmck-rs against 1.95.0 for beta-1.96-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmoriyoshi%2Fvgmck-rs" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/moriyoshi/vgmck-rs
[INFO] finished tweaking git repo https://github.com/moriyoshi/vgmck-rs
[INFO] tweaked toml for git repo https://github.com/moriyoshi/vgmck-rs written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/moriyoshi/vgmck-rs on toolchain 1.95.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/moriyoshi/vgmck-rs already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded clap v4.5.54
[INFO] [stderr]   Downloaded clap_builder v4.5.54
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d5cf638d03087d3337e02f4d963bd72d233006920d8e8b4810a2a2ff6996ff41
[INFO] running `Command { std: "docker" "start" "-a" "d5cf638d03087d3337e02f4d963bd72d233006920d8e8b4810a2a2ff6996ff41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d5cf638d03087d3337e02f4d963bd72d233006920d8e8b4810a2a2ff6996ff41", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d5cf638d03087d3337e02f4d963bd72d233006920d8e8b4810a2a2ff6996ff41", kill_on_drop: false }`
[INFO] [stdout] d5cf638d03087d3337e02f4d963bd72d233006920d8e8b4810a2a2ff6996ff41
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7c3f07b496243f3da7f1e303dfc7bda4bb829e19194ad3603cfc5b28bf8ce7ea
[INFO] running `Command { std: "docker" "start" "-a" "7c3f07b496243f3da7f1e303dfc7bda4bb829e19194ad3603cfc5b28bf8ce7ea", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.105
[INFO] [stderr]    Compiling quote v1.0.43
[INFO] [stderr]    Compiling zmij v1.0.14
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling clap_lex v0.7.7
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling clap_builder v4.5.54
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling flate2 v1.1.8
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling clap_derive v4.5.49
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling clap v4.5.54
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling vgmck v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 17.00s
[INFO] running `Command { std: "docker" "inspect" "7c3f07b496243f3da7f1e303dfc7bda4bb829e19194ad3603cfc5b28bf8ce7ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7c3f07b496243f3da7f1e303dfc7bda4bb829e19194ad3603cfc5b28bf8ce7ea", kill_on_drop: false }`
[INFO] [stdout] 7c3f07b496243f3da7f1e303dfc7bda4bb829e19194ad3603cfc5b28bf8ce7ea
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5bc7f964aab5abf95f2432da55488e7aee407aadf9b6d70954f01ff165c819f9
[INFO] running `Command { std: "docker" "start" "-a" "5bc7f964aab5abf95f2432da55488e7aee407aadf9b6d70954f01ff165c819f9", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling vgmck v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.18s
[INFO] running `Command { std: "docker" "inspect" "5bc7f964aab5abf95f2432da55488e7aee407aadf9b6d70954f01ff165c819f9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5bc7f964aab5abf95f2432da55488e7aee407aadf9b6d70954f01ff165c819f9", kill_on_drop: false }`
[INFO] [stdout] 5bc7f964aab5abf95f2432da55488e7aee407aadf9b6d70954f01ff165c819f9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] e1ef5a5fccf1467a0518be82efa3d53513cc02eafdc325bd9bf4866e5dfccd89
[INFO] running `Command { std: "docker" "start" "-a" "e1ef5a5fccf1467a0518be82efa3d53513cc02eafdc325bd9bf4866e5dfccd89", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vgmck-0b479757b9278cac)
[INFO] [stderr]      Running unittests src/bin/vgm2json.rs (/opt/rustwide/target/debug/deps/vgm2json-6669b6cd65b3fb6b)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test vgm::delay::tests::test_16bit_delay ... ok
[INFO] [stdout] test vgm::delay::tests::test_50th_delay ... ok
[INFO] [stdout] test vgm::delay::tests::test_60th_delay ... ok
[INFO] [stdout] test vgm::gd3::tests::test_utf16_ascii ... ok
[INFO] [stdout] test vgm::delay::tests::test_short_delay ... ok
[INFO] [stdout] test vgm::gd3::tests::test_utf16_japanese ... ok
[INFO] [stdout] test vgm::json::tests::test_format_version ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/vgmck-2c555a663c61174d)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_test.rs (/opt/rustwide/target/debug/deps/integration_test-820099ab25ba153b)
[INFO] [stdout] 
[INFO] [stdout] running 52 tests
[INFO] [stdout] test test_direct_register_write_ay8910 ... ok
[INFO] [stdout] test test_include_basic ... ok
[INFO] [stdout] test test_ay8910_multichannel_routing ... ok
[INFO] [stdout] test test_huc6280_basic_note ... ok
[INFO] [stdout] test test_octave_changes ... ok
[INFO] [stdout] test test_mml_loop ... ok
[INFO] [stdout] test test_loop_point ... ok
[INFO] [stdout] test test_multiple_chips ... ok
[INFO] [stdout] test test_ay8930_basic_note ... ok
[INFO] [stdout] test test_opl3_basic_note ... ok
[INFO] [stdout] test test_opl2_fm_operator_registers_written ... ok
[INFO] [stdout] test test_ay8910_tone_registers ... ok
[INFO] [stdout] test test_ay8910_basic_note ... ok
[INFO] [stdout] test test_opll_instrument_registers_written ... ok
[INFO] [stdout] test test_opl2_multichannel_routing ... ok
[INFO] [stdout] test test_opl2_basic_note ... ok
[INFO] [stdout] test test_opll_instrument_selection ... ok
[INFO] [stdout] test test_opll_basic_note ... ok
[INFO] [stdout] test test_opn2_all_six_channels ... ok
[INFO] [stdout] test test_opn2_multichannel_keyon_routing ... ok
[INFO] [stdout] test test_dmg_basic_note ... ok
[INFO] [stdout] test test_opll_multichannel_routing ... ok
[INFO] [stdout] test test_opn2_basic_note ... ok
[INFO] [stdout] test test_opn2_multichannel_operator_routing ... ok
[INFO] [stdout] test test_include_envelope ... ok
[INFO] [stdout] test test_opn2_multichannel_routing_def ... ok
[INFO] [stdout] test test_opn2_fm_operator_registers_written ... ok
[INFO] [stdout] test test_opn2_port1_frequency_registers ... ok
[INFO] [stdout] test test_custom_clock ... ok
[INFO] [stdout] test test_include_metadata ... ok
[INFO] [stdout] test test_opn2_multichannel_routing_abc ... ok
[INFO] [stdout] test test_psg_multiple_channels ... ok
[INFO] [stdout] test test_opn2_port1_operator_registers ... ok
[INFO] [stdout] test test_opn2_volume_updates_operators ... ok
[INFO] [stdout] test test_psg_multichannel_routing ... ok
[INFO] [stdout] test test_nes_apu_basic_note ... ok
[INFO] [stdout] test test_opn2_tone_change_updates_operators ... ok
[INFO] [stdout] test test_include_text_macro ... ok
[INFO] [stdout] test test_include_subdirectory ... ok
[INFO] [stdout] test test_text_macro ... ok
[INFO] [stdout] test test_opn2_multiple_channels ... ok
[INFO] [stdout] test test_qsound_basic_note ... ok
[INFO] [stdout] test test_rest ... ok
[INFO] [stdout] test test_t6w28_basic_note ... ok
[INFO] [stdout] test test_tempo_change ... ok
[INFO] [stdout] test test_volume_envelope ... ok
[INFO] [stdout] test test_gd3_all_fields ... ok
[INFO] [stdout] test test_vgm_version ... ok
[INFO] [stdout] test test_psg_basic_note ... ok
[INFO] [stdout] test test_pokey_basic_note ... ok
[INFO] [stdout] test test_gd3_title ... ok
[INFO] [stdout] test test_timing_basic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.33s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vgmck
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "e1ef5a5fccf1467a0518be82efa3d53513cc02eafdc325bd9bf4866e5dfccd89", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e1ef5a5fccf1467a0518be82efa3d53513cc02eafdc325bd9bf4866e5dfccd89", kill_on_drop: false }`
[INFO] [stdout] e1ef5a5fccf1467a0518be82efa3d53513cc02eafdc325bd9bf4866e5dfccd89
