[INFO] fetching crate olympia_derive 0.3.0...
[INFO] testing olympia_derive-0.3.0 against master#432e145bd5a974c5b6f4dd9b352891bd7502b69d for pr-87041
[INFO] extracting crate olympia_derive 0.3.0 into /workspace/builds/worker-14/source
[INFO] validating manifest of crates.io crate olympia_derive 0.3.0 on toolchain 432e145bd5a974c5b6f4dd9b352891bd7502b69d
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate olympia_derive 0.3.0
[INFO] finished tweaking crates.io crate olympia_derive 0.3.0
[INFO] tweaked toml for crates.io crate olympia_derive 0.3.0 written to /workspace/builds/worker-14/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 68959aa2a2a36c581b84f71640b708e4ef3ec10a51909a9e7338cfadaac61d97
[INFO] running `Command { std: "docker" "start" "-a" "68959aa2a2a36c581b84f71640b708e4ef3ec10a51909a9e7338cfadaac61d97", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "68959aa2a2a36c581b84f71640b708e4ef3ec10a51909a9e7338cfadaac61d97", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68959aa2a2a36c581b84f71640b708e4ef3ec10a51909a9e7338cfadaac61d97", kill_on_drop: false }`
[INFO] [stdout] 68959aa2a2a36c581b84f71640b708e4ef3ec10a51909a9e7338cfadaac61d97
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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=forbid" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d0d97d78f730950c82f038dfb307aa90a122083b68b831308653146bcbb8784b
[INFO] running `Command { std: "docker" "start" "-a" "d0d97d78f730950c82f038dfb307aa90a122083b68b831308653146bcbb8784b", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.27
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling syn v1.0.73
[INFO] [stderr]    Compiling convert_case v0.4.0
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling derive_more v0.99.16
[INFO] [stderr]    Compiling olympia_core v0.3.0
[INFO] [stderr]    Compiling olympia_derive v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.08s
[INFO] running `Command { std: "docker" "inspect" "d0d97d78f730950c82f038dfb307aa90a122083b68b831308653146bcbb8784b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d0d97d78f730950c82f038dfb307aa90a122083b68b831308653146bcbb8784b", kill_on_drop: false }`
[INFO] [stdout] d0d97d78f730950c82f038dfb307aa90a122083b68b831308653146bcbb8784b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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=forbid" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 53b9fa134b908da0a8b1d942bebd154a8a5dc846fc071c29f44b69d89d2db260
[INFO] running `Command { std: "docker" "start" "-a" "53b9fa134b908da0a8b1d942bebd154a8a5dc846fc071c29f44b69d89d2db260", kill_on_drop: false }`
[INFO] [stderr]    Compiling runtime-macros-derive v0.4.0
[INFO] [stderr]    Compiling olympia_derive v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 5.27s
[INFO] running `Command { std: "docker" "inspect" "53b9fa134b908da0a8b1d942bebd154a8a5dc846fc071c29f44b69d89d2db260", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "53b9fa134b908da0a8b1d942bebd154a8a5dc846fc071c29f44b69d89d2db260", kill_on_drop: false }`
[INFO] [stdout] 53b9fa134b908da0a8b1d942bebd154a8a5dc846fc071c29f44b69d89d2db260
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/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=forbid" "-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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0aae169e4073425c5c9584e1d7308ff9d2aa29cac97dc6516904b452d1e7c6e3
[INFO] running `Command { std: "docker" "start" "-a" "0aae169e4073425c5c9584e1d7308ff9d2aa29cac97dc6516904b452d1e7c6e3", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stdout] running 10 tests
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/olympia_derive-e3b8afbdbe8a4c8d)
[INFO] [stdout] test test::test_build_opcodes ... ok
[INFO] [stdout] test params::test::test_build_param_shift ... ok
[INFO] [stdout] test params::test::test_build_param_mask ... ok
[INFO] [stdout] test coverage_tests::basic_instruction_coverage ... ok
[INFO] [stdout] test coverage_tests::one_inner_param_coverage ... ok
[INFO] [stdout] test coverage_tests::extended_one_inner_coverage ... ok
[INFO] [stdout] test coverage_tests::two_constant_params_coverage ... ok
[INFO] [stdout] test coverage_tests::three_params_coverage ... ok
[INFO] [stdout] test coverage_tests::inner_and_appended_coverage ... ok
[INFO] [stderr]      Running tests/basic_instruction.rs (/opt/rustwide/target/debug/deps/basic_instruction-52d09d7b5291fe97)
[INFO] [stdout] test coverage_tests::one_appended_param_coverage ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test simple_opcode ... ok
[INFO] [stdout] test simple_opcode_disasm ... ok
[INFO] [stdout] test simple_opcode_bytes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/extended_one_inner.rs (/opt/rustwide/target/debug/deps/extended_one_inner-28724b10861388de)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/inner_and_appended.rs (/opt/rustwide/target/debug/deps/inner_and_appended-4182be185b91db24)
[INFO] [stdout] running 3 tests
[INFO] [stdout] test inner_arg_bytes ... ok
[INFO] [stdout] test one_arg_extended_definition ... ok
[INFO] [stdout] test inner_arg_disasm ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test mixed_inner_appended_as_bytes ... ok
[INFO] [stdout] test mixed_inner_appended_definition ... ok
[INFO] [stdout] test mixed_inner_appended_expansion ... ok
[INFO] [stdout] test mixed_inner_appended_disasm ... 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/one_appended_param.rs (/opt/rustwide/target/debug/deps/one_appended_param-d07107cac8517ec7)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test appended_16_disasm ... ok
[INFO] [stdout] test appended_parsing ... ok
[INFO] [stdout] test appended_definition ... ok
[INFO] [stdout] test appended_16_as_bytes ... ok
[INFO] [stdout] test appended_as_bytes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/one_inner_param.rs (/opt/rustwide/target/debug/deps/one_inner_param-3f5a68098bc30462)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test one_arg ... ok
[INFO] [stdout] test one_arg_diasm ... ok
[INFO] [stdout] test one_arg_opcode ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/three_params.rs (/opt/rustwide/target/debug/deps/three_params-a7cf097bbb5c97fa)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test three_arg_definition ... ok
[INFO] [stdout] test three_arg_disasm ... ok
[INFO] [stdout] test three_arg_expansion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/two_constant_params.rs (/opt/rustwide/target/debug/deps/two_constant_params-61025b5b21c1c52f)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test constant_args_definition ... ok
[INFO] [stdout] test constant_args_disasm ... ok
[INFO] [stdout] test constant_args_expansion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests olympia_derive
[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" "0aae169e4073425c5c9584e1d7308ff9d2aa29cac97dc6516904b452d1e7c6e3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0aae169e4073425c5c9584e1d7308ff9d2aa29cac97dc6516904b452d1e7c6e3", kill_on_drop: false }`
[INFO] [stdout] 0aae169e4073425c5c9584e1d7308ff9d2aa29cac97dc6516904b452d1e7c6e3