[INFO] cloning repository https://github.com/Barandis/rust-c64
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Barandis/rust-c64" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBarandis%2Frust-c64", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBarandis%2Frust-c64'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 329274f78de06da61ffdb70f7bbb16d2f2f78e9e
[INFO] testing Barandis/rust-c64 against 1.60.0 for beta-1.61-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBarandis%2Frust-c64" "/workspace/builds/worker-33/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-33/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/Barandis/rust-c64 on toolchain 1.60.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/Barandis/rust-c64
[INFO] finished tweaking git repo https://github.com/Barandis/rust-c64
[INFO] tweaked toml for git repo https://github.com/Barandis/rust-c64 written to /workspace/builds/worker-33/source/Cargo.toml
[INFO] crate git repo https://github.com/Barandis/rust-c64 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] fb8c6b47650e562adcb037129458f7faf5a3150833e8b6dabbd0aff61402c5ee
[INFO] running `Command { std: "docker" "start" "-a" "fb8c6b47650e562adcb037129458f7faf5a3150833e8b6dabbd0aff61402c5ee", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fb8c6b47650e562adcb037129458f7faf5a3150833e8b6dabbd0aff61402c5ee", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fb8c6b47650e562adcb037129458f7faf5a3150833e8b6dabbd0aff61402c5ee", kill_on_drop: false }`
[INFO] [stdout] fb8c6b47650e562adcb037129458f7faf5a3150833e8b6dabbd0aff61402c5ee
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e2eb391726d52cf31690c4013de3070e2c3d305e9a1b9ad4ef1077cf4035806b
[INFO] running `Command { std: "docker" "start" "-a" "e2eb391726d52cf31690c4013de3070e2c3d305e9a1b9ad4ef1077cf4035806b", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling c64 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.60s
[INFO] running `Command { std: "docker" "inspect" "e2eb391726d52cf31690c4013de3070e2c3d305e9a1b9ad4ef1077cf4035806b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e2eb391726d52cf31690c4013de3070e2c3d305e9a1b9ad4ef1077cf4035806b", kill_on_drop: false }`
[INFO] [stdout] e2eb391726d52cf31690c4013de3070e2c3d305e9a1b9ad4ef1077cf4035806b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7d40877e5db0b27fc6c3ace79187f0dd078998ed918f0599aae4cb08bd8be499
[INFO] running `Command { std: "docker" "start" "-a" "7d40877e5db0b27fc6c3ace79187f0dd078998ed918f0599aae4cb08bd8be499", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.69
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.10
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.4
[INFO] [stderr]    Compiling c64 v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 51.06s
[INFO] running `Command { std: "docker" "inspect" "7d40877e5db0b27fc6c3ace79187f0dd078998ed918f0599aae4cb08bd8be499", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d40877e5db0b27fc6c3ace79187f0dd078998ed918f0599aae4cb08bd8be499", kill_on_drop: false }`
[INFO] [stdout] 7d40877e5db0b27fc6c3ace79187f0dd078998ed918f0599aae4cb08bd8be499
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-33/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" "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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ea6d651149617443dd15dbdb8a554dc136e88e53208e5db5644fbb7a382bc1c2
[INFO] running `Command { std: "docker" "start" "-a" "ea6d651149617443dd15dbdb8a554dc136e88e53208e5db5644fbb7a382bc1c2", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/c64-3233010993e9c6fa)
[INFO] [stdout] 
[INFO] [stdout] running 141 tests
[INFO] [stdout] test components::pin::test::has_name ... ok
[INFO] [stdout] test components::pin::test::has_number ... ok
[INFO] [stdout] test components::pin::test::level_bidirectional ... ok
[INFO] [stdout] test components::pin::test::level_output ... ok
[INFO] [stdout] test components::pin::test::level_input ... ok
[INFO] [stdout] test components::pin::test::level_no_trace ... ok
[INFO] [stdout] test components::pin::test::level_toggle_high ... ok
[INFO] [stdout] test components::pin::test::level_toggle_float ... ok
[INFO] [stdout] test components::pin::test::level_toggle_low ... ok
[INFO] [stdout] test components::pin::test::level_unconnected ... ok
[INFO] [stdout] test components::pin::test::level_update_no_trace ... ok
[INFO] [stdout] test components::pin::test::mode_bidi_to_in ... ok
[INFO] [stdout] test components::pin::test::mode_bidi_to_out ... ok
[INFO] [stdout] test components::pin::test::mode_change ... ok
[INFO] [stdout] test components::pin::test::mode_initial ... ok
[INFO] [stdout] test components::pin::test::mode_in_to_unc ... ok
[INFO] [stdout] test components::pin::test::mode_out_to_in ... ok
[INFO] [stdout] test components::pin::test::mode_unc_to_in ... ok
[INFO] [stdout] test components::pin::test::mode_unc_to_out ... ok
[INFO] [stdout] test components::pin::test::observer_bidirectional ... ok
[INFO] [stdout] test components::pin::test::observer_detach ... ok
[INFO] [stdout] test components::pin::test::observer_direct ... ok
[INFO] [stdout] test components::pin::test::observer_input ... ok
[INFO] [stdout] test components::pin::test::observer_non_existent ... ok
[INFO] [stdout] test components::pin::test::observer_output ... ok
[INFO] [stdout] test components::pin::test::observer_unconnected ... ok
[INFO] [stdout] test components::pin::test::pull_down_after ... ok
[INFO] [stdout] test components::pin::test::pull_down_bidirectional ... ok
[INFO] [stdout] test components::pin::test::pull_down_initial ... ok
[INFO] [stdout] test components::pin::test::pull_down_input ... ok
[INFO] [stdout] test components::pin::test::pull_down_output ... ok
[INFO] [stdout] test components::pin::test::pull_down_unconnected ... ok
[INFO] [stdout] test components::pin::test::pull_off_initial ... ok
[INFO] [stdout] test components::pin::test::pull_off_pull_down ... ok
[INFO] [stdout] test components::pin::test::pull_off_pull_up ... ok
[INFO] [stdout] test components::pin::test::pull_up_after ... ok
[INFO] [stdout] test components::pin::test::pull_up_bidirectional ... ok
[INFO] [stdout] test components::pin::test::pull_up_initial ... ok
[INFO] [stdout] test components::pin::test::pull_up_input ... ok
[INFO] [stdout] test components::pin::test::pull_up_output ... ok
[INFO] [stdout] test components::pin::test::pull_up_unconnected ... ok
[INFO] [stdout] test components::trace::test::level_direct_input ... ok
[INFO] [stdout] test components::trace::test::level_direct_output_float ... ok
[INFO] [stdout] test components::trace::test::level_direct_output_high ... ok
[INFO] [stdout] test components::trace::test::level_direct_output_low ... ok
[INFO] [stdout] test components::trace::test::level_direct_unconnected ... ok
[INFO] [stdout] test components::trace::test::level_pin_bidirectional ... ok
[INFO] [stdout] test components::trace::test::level_pin_input ... ok
[INFO] [stdout] test components::trace::test::level_pin_ouput ... ok
[INFO] [stdout] test components::trace::test::level_pin_outputs_high ... ok
[INFO] [stdout] test components::trace::test::level_pin_outputs_low ... ok
[INFO] [stdout] test components::trace::test::level_pin_unconnected ... ok
[INFO] [stdout] test components::trace::test::no_add_twice ... ok
[INFO] [stdout] test components::trace::test::pull_down_initial ... ok
[INFO] [stdout] test components::trace::test::pull_down_input ... ok
[INFO] [stdout] test components::trace::test::pull_down_output_floating ... ok
[INFO] [stdout] test components::trace::test::pull_down_output_high ... ok
[INFO] [stdout] test components::trace::test::pull_down_output_low ... ok
[INFO] [stdout] test components::trace::test::pull_down_output_none ... ok
[INFO] [stdout] test components::trace::test::pull_off_input ... ok
[INFO] [stdout] test components::trace::test::pull_off_output_floating ... ok
[INFO] [stdout] test components::trace::test::pull_off_output_high ... ok
[INFO] [stdout] test components::trace::test::pull_off_initial ... ok
[INFO] [stdout] test components::trace::test::pull_off_output_low ... ok
[INFO] [stdout] test components::trace::test::pull_off_output_none ... ok
[INFO] [stdout] test components::trace::test::pull_up_initial ... ok
[INFO] [stdout] test components::trace::test::pull_up_input ... ok
[INFO] [stdout] test components::trace::test::pull_up_output_floating ... ok
[INFO] [stdout] test components::trace::test::pull_up_output_high ... ok
[INFO] [stdout] test components::trace::test::pull_up_output_none ... ok
[INFO] [stdout] test components::trace::test::pull_up_output_low ... ok
[INFO] [stdout] test devices::chips::ic4066::test::disconnect_on_high_x ... ok
[INFO] [stdout] test devices::chips::ic4066::test::last_set_a ... ok
[INFO] [stdout] test devices::chips::ic4066::test::last_set_b ... ok
[INFO] [stdout] test devices::chips::ic4066::test::no_pass_a_to_b_on_high_x ... ok
[INFO] [stdout] test devices::chips::ic4066::test::no_pass_b_to_a_on_high_x ... ok
[INFO] [stdout] test devices::chips::ic4066::test::pass_a_to_b ... ok
[INFO] [stdout] test devices::chips::ic4066::test::unset_before_high_x ... ok
[INFO] [stdout] test devices::chips::ic4066::test::pass_b_to_a ... ok
[INFO] [stdout] test devices::chips::ic4164::test::read_mode_enable_q ... ok
[INFO] [stdout] test devices::chips::ic4164::test::read_write_one_bit ... ok
[INFO] [stdout] test devices::chips::ic4164::test::rmw_mode_enable_q ... ok
[INFO] [stdout] test devices::chips::ic4164::test::write_mode_disable_q ... ok
[INFO] [stdout] test devices::chips::ic7406::test::input_low_no_macro ... ok
[INFO] [stdout] test devices::chips::ic7406::test::input_high_no_macro ... ok
[INFO] [stdout] test devices::chips::ic7406::test::input_high ... ok
[INFO] [stdout] test devices::chips::ic7406::test::input_low ... ok
[INFO] [stdout] test devices::chips::ic7408::test::gate_1 ... ok
[INFO] [stdout] test devices::chips::ic4164::test::rmw_one_bit ... ok
[INFO] [stdout] test devices::chips::ic7408::test::gate_2 ... ok
[INFO] [stdout] test devices::chips::ic7408::test::gate_3 ... ok
[INFO] [stdout] test devices::chips::ic7408::test::gate_4 ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_1_high_high ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_1_g_high ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_1_high_low ... ok
[INFO] [stdout] test devices::chips::ic4164::test::read_write_no_rmw_q ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_1_low_high ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_1_low_low ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_2_g_high ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_2_high_high ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_2_high_low ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_2_low_high ... ok
[INFO] [stdout] test devices::chips::ic74139::test::demux_2_low_low ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_1_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_1_select_a ... ok
[INFO] [stdout] test devices::chips::ic4164::test::read_write_rmw_q ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_2_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_1_select_b ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_2_select_a ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_2_select_b ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_3_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_3_select_a ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_3_select_b ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_4_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_4_select_a ... ok
[INFO] [stdout] test devices::chips::ic74257::test::mux_4_select_b ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_1_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_1_select_b ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_1_select_a ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_2_oe_high ... ok
[INFO] [stdout] test devices::chips::ic4164::test::read_write_page ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_2_select_b ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_3_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_3_select_a ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_2_select_a ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_4_select_a ... ok
[INFO] [stdout] test devices::chips::ic74373::test::latch_low_le ... ok
[INFO] [stdout] test devices::chips::ic74373::test::float_high_oe ... ok
[INFO] [stdout] test devices::chips::ic74373::test::pass_high_le ... ok
[INFO] [stdout] test devices::chips::ic82s100::test::disable_out_on_high_oe ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_4_select_b ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_3_select_b ... ok
[INFO] [stdout] test devices::chips::ic74373::test::recall_latch_high_oe ... ok
[INFO] [stdout] test devices::chips::ic74258::test::mux_4_oe_high ... ok
[INFO] [stdout] test devices::chips::ic74373::test::pass_return_to_high_le ... ok
[INFO] [stdout] test devices::chips::ic2114::test::read_and_write ... ok
[INFO] [stdout] test devices::chips::ic2332::test::read_full ... ok
[INFO] [stdout] test devices::chips::ic2364::test::read_full_basic ... ok
[INFO] [stdout] test devices::chips::ic2364::test::read_full_kernal ... ok
[INFO] [stdout] test devices::chips::ic82s100::test::logic_combinations ... ok
[INFO] [stdout] test devices::chips::ic4164::test::read_write_full ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 141 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.40s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ea6d651149617443dd15dbdb8a554dc136e88e53208e5db5644fbb7a382bc1c2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ea6d651149617443dd15dbdb8a554dc136e88e53208e5db5644fbb7a382bc1c2", kill_on_drop: false }`
[INFO] [stdout] ea6d651149617443dd15dbdb8a554dc136e88e53208e5db5644fbb7a382bc1c2