Nov 18 08:36:40.311 INFO testing iridium-0.0.30 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 08:36:40.313 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 08:36:40.779 INFO [stdout] cc6d2f8bc8b153bb91f15eaa7ddbd9c9cd6ebc2aa052472b77d59f841066986b
Nov 18 08:36:40.782 INFO running `"docker" "start" "-a" "cc6d2f8bc8b153bb91f15eaa7ddbd9c9cd6ebc2aa052472b77d59f841066986b"`
Nov 18 08:36:41.929 INFO [stderr] usermod: no changes
Nov 18 08:36:42.131 INFO [stderr]    Compiling iridium v0.0.30 (/source)
Nov 18 08:36:50.141 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.18s
Nov 18 08:36:50.155 INFO [stderr] su: No module specific data is present
Nov 18 08:36:51.031 INFO running `"docker" "inspect" "cc6d2f8bc8b153bb91f15eaa7ddbd9c9cd6ebc2aa052472b77d59f841066986b"`
Nov 18 08:36:51.295 INFO running `"docker" "rm" "-f" "cc6d2f8bc8b153bb91f15eaa7ddbd9c9cd6ebc2aa052472b77d59f841066986b"`
Nov 18 08:36:51.671 INFO [stdout] cc6d2f8bc8b153bb91f15eaa7ddbd9c9cd6ebc2aa052472b77d59f841066986b
Nov 18 08:36:51.677 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 08:36:52.156 INFO [stdout] 3ddb40509763b615822aa13e1751c32d7a2c2e48fb9e11e83c81b2b9d703ef4a
Nov 18 08:36:52.158 INFO running `"docker" "start" "-a" "3ddb40509763b615822aa13e1751c32d7a2c2e48fb9e11e83c81b2b9d703ef4a"`
Nov 18 08:36:53.758 INFO [stderr] usermod: no changes
Nov 18 08:36:54.007 INFO [stderr]    Compiling criterion v0.2.5
Nov 18 08:37:05.326 INFO [stderr]    Compiling iridium v0.0.30 (/source)
Nov 18 08:37:09.456 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.68s
Nov 18 08:37:09.466 INFO [stderr] su: No module specific data is present
Nov 18 08:37:09.792 INFO running `"docker" "inspect" "3ddb40509763b615822aa13e1751c32d7a2c2e48fb9e11e83c81b2b9d703ef4a"`
Nov 18 08:37:09.853 INFO running `"docker" "rm" "-f" "3ddb40509763b615822aa13e1751c32d7a2c2e48fb9e11e83c81b2b9d703ef4a"`
Nov 18 08:37:09.914 INFO [stdout] 3ddb40509763b615822aa13e1751c32d7a2c2e48fb9e11e83c81b2b9d703ef4a
Nov 18 08:37:09.915 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 08:37:10.052 INFO [stdout] b77d5773e01c78057d630f62e1885f1edd838c727b8a11c4ac541b4a2440a3c2
Nov 18 08:37:10.054 INFO running `"docker" "start" "-a" "b77d5773e01c78057d630f62e1885f1edd838c727b8a11c4ac541b4a2440a3c2"`
Nov 18 08:37:10.415 INFO [stderr] usermod: no changes
Nov 18 08:37:10.553 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Nov 18 08:37:10.560 INFO [stderr]      Running /target/debug/deps/iridium-701245e58f7098fc
Nov 18 08:37:10.562 INFO [stdout] 
Nov 18 08:37:10.562 INFO [stdout] running 77 tests
Nov 18 08:37:10.563 INFO [stdout] test assembler::directive_parsers::tests::test_parser_directive ... ok
Nov 18 08:37:10.563 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_one_with_label ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_cloop ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_three ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_two ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::label_parsers::tests::test_parse_label_usage ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_one ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::opcode_parsers::tests::test_opcode ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_with_comment_one ... ok
Nov 18 08:37:10.564 INFO [stdout] test assembler::directive_parsers::tests::test_string_directive ... ok
Nov 18 08:37:10.565 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_with_comment_two ... ok
Nov 18 08:37:10.565 INFO [stdout] test assembler::label_parsers::tests::test_parse_label_declaration ... ok
Nov 18 08:37:10.565 INFO [stdout] test assembler::operand_parsers::tests::test_parse_float_operand ... ok
Nov 18 08:37:10.566 INFO [stdout] test assembler::program_parsers::tests::test_parse_program ... ok
Nov 18 08:37:10.566 INFO [stdout] test assembler::program_parsers::tests::test_parse_cloop ... ok
Nov 18 08:37:10.566 INFO [stdout] test assembler::program_parsers::tests::test_complete_program ... ok
Nov 18 08:37:10.567 INFO [stdout] test assembler::register_parsers::tests::test_parse_register ... ok
Nov 18 08:37:10.567 INFO [stdout] test assembler::operand_parsers::tests::test_parse_string_operand ... ok
Nov 18 08:37:10.568 INFO [stdout] test assembler::program_parsers::tests::test_program_to_bytes ... ok
Nov 18 08:37:10.568 INFO [stdout] test assembler::program_parsers::tests::test_parse_load_greater_than_i16 ... ok
Nov 18 08:37:10.568 INFO [stdout] test assembler::tests::test_code_start_offset_written ... ok
Nov 18 08:37:10.569 INFO [stdout] test assembler::tests::test_assemble_program ... ok
Nov 18 08:37:10.569 INFO [stdout] test assembler::tests::test_first_phase_inside_segment ... ok
Nov 18 08:37:10.570 INFO [stdout] test assembler::tests::test_first_phase_no_segment ... ok
Nov 18 08:37:10.570 INFO [stdout] test assembler::tests::test_ro_data_asciiz ... ok
Nov 18 08:37:10.570 INFO [stdout] test assembler::tests::test_ro_data_i32 ... ok
Nov 18 08:37:10.571 INFO [stdout] test assembler::tests::test_symbol_table ... ok
Nov 18 08:37:10.571 INFO [stdout] test assembler::tests::test_bad_ro_data ... ok
Nov 18 08:37:10.571 INFO [stdout] test instruction::tests::test_create_hlt ... ok
Nov 18 08:37:10.572 INFO [stdout] test instruction::tests::test_create_instruction ... ok
Nov 18 08:37:10.572 INFO [stdout] test instruction::tests::test_int_to_opcode ... ok
Nov 18 08:37:10.573 INFO [stdout] test instruction::tests::test_str_to_opcode ... ok
Nov 18 08:37:10.573 INFO [stdout] test scheduler::tests::test_make_scheduler ... ok
Nov 18 08:37:10.573 INFO [stdout] test vm::tests::test_add_floating_point_opcode ... ok
Nov 18 08:37:10.574 INFO [stdout] test vm::tests::test_add_opcode ... ok
Nov 18 08:37:10.574 INFO [stdout] test vm::tests::test_aloc_opcode ... ok
Nov 18 08:37:10.574 INFO [stdout] test vm::tests::test_and_opcode ... ok
Nov 18 08:37:10.575 INFO [stdout] test vm::tests::test_cloop_opcode ... ok
Nov 18 08:37:10.575 INFO [stdout] test vm::tests::test_create_vm ... ok
Nov 18 08:37:10.576 INFO [stdout] test vm::tests::test_div_floating_point_opcode ... ok
Nov 18 08:37:10.576 INFO [stdout] test assembler::operand_parsers::tests::test_parse_integer_operand ... ok
Nov 18 08:37:10.576 INFO [stdout] test vm::tests::test_div_opcode ... ok
Nov 18 08:37:10.577 INFO [stdout] test vm::tests::test_eq_floating_point_opcode ... ok
Nov 18 08:37:10.577 INFO [stdout] test vm::tests::test_eq_opcode ... ok
Nov 18 08:37:10.577 INFO [stdout] test vm::tests::test_gt_floating_point_opcode ... ok
Nov 18 08:37:10.578 INFO [stdout] test vm::tests::test_gt_opcode ... ok
Nov 18 08:37:10.578 INFO [stdout] test vm::tests::test_gte_floating_point_opcode ... ok
Nov 18 08:37:10.579 INFO [stdout] test vm::tests::test_gte_opcode ... ok
Nov 18 08:37:10.579 INFO [stdout] test vm::tests::test_hlt_opcode ... ok
Nov 18 08:37:10.580 INFO [stdout] test vm::tests::test_igl_opcode ... ok
Nov 18 08:37:10.580 INFO [stdout] test vm::tests::test_loadm_opcode ... ok
Nov 18 08:37:10.581 INFO [stdout] test vm::tests::test_jmp_opcode ... ok
Nov 18 08:37:10.581 INFO [stdout] test vm::tests::test_lt_floating_point_opcode ... ok
Nov 18 08:37:10.582 INFO [stdout] test vm::tests::test_lt_opcode ... ok
Nov 18 08:37:10.582 INFO [stdout] test vm::tests::test_lte_floating_point_opcode ... ok
Nov 18 08:37:10.583 INFO [stdout] test vm::tests::test_lte_opcode ... ok
Nov 18 08:37:10.583 INFO [stdout] test vm::tests::test_lui_opcode ... ok
Nov 18 08:37:10.584 INFO [stdout] test vm::tests::test_mul_floating_point_opcode ... ok
Nov 18 08:37:10.584 INFO [stdout] test vm::tests::test_neq_floating_point_opcode ... ok
Nov 18 08:37:10.585 INFO [stdout] test vm::tests::test_neq_opcode ... ok
Nov 18 08:37:10.586 INFO [stdout] test vm::tests::test_not_opcode ... ok
Nov 18 08:37:10.586 INFO [stdout] test vm::tests::test_or_opcode ... ok
Nov 18 08:37:10.587 INFO [stdout] test vm::tests::test_jmpb_opcode ... ok
Nov 18 08:37:10.587 INFO [stdout] test vm::tests::test_mul_opcode ... ok
Nov 18 08:37:10.588 INFO [stdout] test vm::tests::test_jmpe_opcode ... ok
Nov 18 08:37:10.588 INFO [stdout] test vm::tests::test_pop_opcode ... ok
Nov 18 08:37:10.589 INFO [stdout] test vm::tests::test_prts_opcode ... ok
Nov 18 08:37:10.589 INFO [stdout] test vm::tests::test_push_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_load_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_jmpf_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_setm_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_shl_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_load_floating_point_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_sub_floating_point_opcode ... ok
Nov 18 08:37:10.591 INFO [stdout] test vm::tests::test_shr_opcode ... ok
Nov 18 08:37:10.592 INFO [stdout] test vm::tests::test_sub_opcode ... ok
Nov 18 08:37:10.593 INFO [stdout] test vm::tests::test_xor_opcode ... ok
Nov 18 08:37:10.593 INFO [stdout] 
Nov 18 08:37:10.593 INFO [stdout] test result: ok. 77 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:10.593 INFO [stdout] 
Nov 18 08:37:10.594 INFO [stderr]      Running /target/debug/deps/iridium-3f01d708f0de9584
Nov 18 08:37:10.596 INFO [stdout] 
Nov 18 08:37:10.596 INFO [stdout] running 0 tests
Nov 18 08:37:10.596 INFO [stdout] 
Nov 18 08:37:10.596 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:10.596 INFO [stdout] 
Nov 18 08:37:10.597 INFO [stderr]      Running /target/debug/deps/integration_tests-ab97f9f857a31bc2
Nov 18 08:37:10.600 INFO [stdout] 
Nov 18 08:37:10.600 INFO [stdout] running 4 tests
Nov 18 08:37:10.601 INFO [stdout] test create_vm ... ok
Nov 18 08:37:10.601 INFO [stdout] test test_hlt ... ok
Nov 18 08:37:10.602 INFO [stdout] test test_call_return ... ok
Nov 18 08:37:10.603 INFO [stdout] test test_inc_loop ... ok
Nov 18 08:37:10.603 INFO [stdout] 
Nov 18 08:37:10.603 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:10.603 INFO [stdout] 
Nov 18 08:37:10.603 INFO [stderr]    Doc-tests iridium
Nov 18 08:37:11.993 INFO [stdout] 
Nov 18 08:37:11.994 INFO [stdout] running 0 tests
Nov 18 08:37:11.994 INFO [stdout] 
Nov 18 08:37:11.994 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:11.994 INFO [stdout] 
Nov 18 08:37:12.003 INFO [stderr] su: No module specific data is present
Nov 18 08:37:12.881 INFO running `"docker" "inspect" "b77d5773e01c78057d630f62e1885f1edd838c727b8a11c4ac541b4a2440a3c2"`
Nov 18 08:37:13.143 INFO running `"docker" "rm" "-f" "b77d5773e01c78057d630f62e1885f1edd838c727b8a11c4ac541b4a2440a3c2"`
Nov 18 08:37:13.359 INFO [stdout] b77d5773e01c78057d630f62e1885f1edd838c727b8a11c4ac541b4a2440a3c2