Nov 18 08:36:40.416 INFO testing iridium-0.0.30 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 08:36:40.417 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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.859 INFO [stdout] 4217b421ee81f03300ee38de03279dc6aef5cda441798387b9fc5d61b84e981c
Nov 18 08:36:40.865 INFO running `"docker" "start" "-a" "4217b421ee81f03300ee38de03279dc6aef5cda441798387b9fc5d61b84e981c"`
Nov 18 08:36:41.914 INFO [stderr] usermod: no changes
Nov 18 08:36:42.113 INFO [stderr]    Compiling iridium v0.0.30 (/source)
Nov 18 08:36:49.637 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.68s
Nov 18 08:36:49.663 INFO [stderr] su: No module specific data is present
Nov 18 08:36:50.416 INFO running `"docker" "inspect" "4217b421ee81f03300ee38de03279dc6aef5cda441798387b9fc5d61b84e981c"`
Nov 18 08:36:50.697 INFO running `"docker" "rm" "-f" "4217b421ee81f03300ee38de03279dc6aef5cda441798387b9fc5d61b84e981c"`
Nov 18 08:36:51.011 INFO [stdout] 4217b421ee81f03300ee38de03279dc6aef5cda441798387b9fc5d61b84e981c
Nov 18 08:36:51.021 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:51.419 INFO [stdout] 0fade519cbeb1f0312929e232295cd7b88f4ffa3eec62cfb521f50cbfe828c1a
Nov 18 08:36:51.427 INFO running `"docker" "start" "-a" "0fade519cbeb1f0312929e232295cd7b88f4ffa3eec62cfb521f50cbfe828c1a"`
Nov 18 08:36:52.556 INFO [stderr] usermod: no changes
Nov 18 08:36:52.779 INFO [stderr]    Compiling criterion v0.2.5
Nov 18 08:37:03.105 INFO [stderr]    Compiling iridium v0.0.30 (/source)
Nov 18 08:37:08.150 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.56s
Nov 18 08:37:08.163 INFO [stderr] su: No module specific data is present
Nov 18 08:37:08.476 INFO running `"docker" "inspect" "0fade519cbeb1f0312929e232295cd7b88f4ffa3eec62cfb521f50cbfe828c1a"`
Nov 18 08:37:08.579 INFO running `"docker" "rm" "-f" "0fade519cbeb1f0312929e232295cd7b88f4ffa3eec62cfb521f50cbfe828c1a"`
Nov 18 08:37:08.740 INFO [stdout] 0fade519cbeb1f0312929e232295cd7b88f4ffa3eec62cfb521f50cbfe828c1a
Nov 18 08:37:08.744 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:09.101 INFO [stdout] 08b267088f1db82860e3b59ba50b3cb41b4c7e75f0d158a159ea14e63ce89645
Nov 18 08:37:09.103 INFO running `"docker" "start" "-a" "08b267088f1db82860e3b59ba50b3cb41b4c7e75f0d158a159ea14e63ce89645"`
Nov 18 08:37:09.409 INFO [stderr] usermod: no changes
Nov 18 08:37:09.508 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Nov 18 08:37:09.513 INFO [stderr]      Running /target/debug/deps/iridium-b9b2a054e4a9ea61
Nov 18 08:37:09.515 INFO [stdout] 
Nov 18 08:37:09.515 INFO [stdout] running 77 tests
Nov 18 08:37:09.516 INFO [stdout] test assembler::directive_parsers::tests::test_parser_directive ... ok
Nov 18 08:37:09.516 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_cloop ... ok
Nov 18 08:37:09.516 INFO [stdout] test assembler::directive_parsers::tests::test_string_directive ... ok
Nov 18 08:37:09.516 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_one ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_three ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_two ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_with_comment_two ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_with_comment_one ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::label_parsers::tests::test_parse_label_declaration ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::opcode_parsers::tests::test_opcode ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::label_parsers::tests::test_parse_label_usage ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::operand_parsers::tests::test_parse_float_operand ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::instruction_parsers::tests::test_parse_instruction_form_one_with_label ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::operand_parsers::tests::test_parse_string_operand ... ok
Nov 18 08:37:09.517 INFO [stdout] test assembler::operand_parsers::tests::test_parse_integer_operand ... ok
Nov 18 08:37:09.518 INFO [stdout] test assembler::program_parsers::tests::test_complete_program ... ok
Nov 18 08:37:09.518 INFO [stdout] test assembler::program_parsers::tests::test_parse_program ... ok
Nov 18 08:37:09.518 INFO [stdout] test assembler::register_parsers::tests::test_parse_register ... ok
Nov 18 08:37:09.519 INFO [stdout] test assembler::program_parsers::tests::test_program_to_bytes ... ok
Nov 18 08:37:09.519 INFO [stdout] test assembler::program_parsers::tests::test_parse_load_greater_than_i16 ... ok
Nov 18 08:37:09.519 INFO [stdout] test assembler::tests::test_first_phase_inside_segment ... ok
Nov 18 08:37:09.519 INFO [stdout] test assembler::tests::test_bad_ro_data ... ok
Nov 18 08:37:09.519 INFO [stdout] test assembler::tests::test_first_phase_no_segment ... ok
Nov 18 08:37:09.521 INFO [stdout] test assembler::tests::test_code_start_offset_written ... ok
Nov 18 08:37:09.521 INFO [stdout] test assembler::tests::test_symbol_table ... ok
Nov 18 08:37:09.521 INFO [stdout] test instruction::tests::test_create_hlt ... ok
Nov 18 08:37:09.521 INFO [stdout] test assembler::tests::test_ro_data_i32 ... ok
Nov 18 08:37:09.521 INFO [stdout] test assembler::program_parsers::tests::test_parse_cloop ... ok
Nov 18 08:37:09.521 INFO [stdout] test assembler::tests::test_assemble_program ... ok
Nov 18 08:37:09.522 INFO [stdout] test assembler::tests::test_ro_data_asciiz ... ok
Nov 18 08:37:09.522 INFO [stdout] test instruction::tests::test_int_to_opcode ... ok
Nov 18 08:37:09.522 INFO [stdout] test instruction::tests::test_str_to_opcode ... ok
Nov 18 08:37:09.522 INFO [stdout] test scheduler::tests::test_make_scheduler ... ok
Nov 18 08:37:09.522 INFO [stdout] test instruction::tests::test_create_instruction ... ok
Nov 18 08:37:09.523 INFO [stdout] test vm::tests::test_create_vm ... ok
Nov 18 08:37:09.523 INFO [stdout] test vm::tests::test_div_opcode ... ok
Nov 18 08:37:09.523 INFO [stdout] test vm::tests::test_and_opcode ... ok
Nov 18 08:37:09.525 INFO [stdout] test vm::tests::test_cloop_opcode ... ok
Nov 18 08:37:09.525 INFO [stdout] test vm::tests::test_gt_floating_point_opcode ... ok
Nov 18 08:37:09.525 INFO [stdout] test vm::tests::test_add_floating_point_opcode ... ok
Nov 18 08:37:09.525 INFO [stdout] test vm::tests::test_add_opcode ... ok
Nov 18 08:37:09.526 INFO [stdout] test vm::tests::test_aloc_opcode ... ok
Nov 18 08:37:09.526 INFO [stdout] test vm::tests::test_hlt_opcode ... ok
Nov 18 08:37:09.526 INFO [stdout] test vm::tests::test_div_floating_point_opcode ... ok
Nov 18 08:37:09.526 INFO [stdout] test vm::tests::test_jmp_opcode ... ok
Nov 18 08:37:09.527 INFO [stdout] test vm::tests::test_gte_floating_point_opcode ... ok
Nov 18 08:37:09.527 INFO [stdout] test vm::tests::test_igl_opcode ... ok
Nov 18 08:37:09.527 INFO [stdout] test vm::tests::test_gte_opcode ... ok
Nov 18 08:37:09.527 INFO [stdout] test vm::tests::test_jmpe_opcode ... ok
Nov 18 08:37:09.529 INFO [stdout] test vm::tests::test_jmpf_opcode ... ok
Nov 18 08:37:09.529 INFO [stdout] test vm::tests::test_gt_opcode ... ok
Nov 18 08:37:09.529 INFO [stdout] test vm::tests::test_load_opcode ... ok
Nov 18 08:37:09.530 INFO [stdout] test vm::tests::test_lt_floating_point_opcode ... ok
Nov 18 08:37:09.530 INFO [stdout] test vm::tests::test_eq_opcode ... ok
Nov 18 08:37:09.530 INFO [stdout] test vm::tests::test_loadm_opcode ... ok
Nov 18 08:37:09.530 INFO [stdout] test vm::tests::test_load_floating_point_opcode ... ok
Nov 18 08:37:09.532 INFO [stdout] test vm::tests::test_jmpb_opcode ... ok
Nov 18 08:37:09.532 INFO [stdout] test vm::tests::test_lte_opcode ... ok
Nov 18 08:37:09.532 INFO [stdout] test vm::tests::test_lte_floating_point_opcode ... ok
Nov 18 08:37:09.533 INFO [stdout] test vm::tests::test_lt_opcode ... ok
Nov 18 08:37:09.533 INFO [stdout] test vm::tests::test_mul_floating_point_opcode ... ok
Nov 18 08:37:09.533 INFO [stdout] test vm::tests::test_neq_floating_point_opcode ... ok
Nov 18 08:37:09.534 INFO [stdout] test vm::tests::test_prts_opcode ... ok
Nov 18 08:37:09.534 INFO [stdout] test vm::tests::test_lui_opcode ... ok
Nov 18 08:37:09.534 INFO [stdout] test vm::tests::test_eq_floating_point_opcode ... ok
Nov 18 08:37:09.544 INFO [stdout] test vm::tests::test_not_opcode ... ok
Nov 18 08:37:09.544 INFO [stdout] test vm::tests::test_setm_opcode ... ok
Nov 18 08:37:09.544 INFO [stdout] test vm::tests::test_shl_opcode ... ok
Nov 18 08:37:09.546 INFO [stdout] test vm::tests::test_push_opcode ... ok
Nov 18 08:37:09.546 INFO [stdout] test vm::tests::test_or_opcode ... ok
Nov 18 08:37:09.546 INFO [stdout] test vm::tests::test_mul_opcode ... ok
Nov 18 08:37:09.546 INFO [stdout] test vm::tests::test_pop_opcode ... ok
Nov 18 08:37:09.547 INFO [stdout] test vm::tests::test_xor_opcode ... ok
Nov 18 08:37:09.547 INFO [stdout] test vm::tests::test_shr_opcode ... ok
Nov 18 08:37:09.547 INFO [stdout] test vm::tests::test_sub_opcode ... ok
Nov 18 08:37:09.547 INFO [stdout] test vm::tests::test_neq_opcode ... ok
Nov 18 08:37:09.547 INFO [stdout] test vm::tests::test_sub_floating_point_opcode ... ok
Nov 18 08:37:09.547 INFO [stdout] 
Nov 18 08:37:09.547 INFO [stdout] test result: ok. 77 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:09.547 INFO [stdout] 
Nov 18 08:37:09.551 INFO [stderr]      Running /target/debug/deps/iridium-09fe5964d7ac33a3
Nov 18 08:37:09.555 INFO [stdout] 
Nov 18 08:37:09.555 INFO [stdout] running 0 tests
Nov 18 08:37:09.555 INFO [stdout] 
Nov 18 08:37:09.555 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:09.555 INFO [stdout] 
Nov 18 08:37:09.555 INFO [stderr]      Running /target/debug/deps/integration_tests-a9f04175a96a3d29
Nov 18 08:37:09.557 INFO [stdout] 
Nov 18 08:37:09.557 INFO [stdout] running 4 tests
Nov 18 08:37:09.558 INFO [stdout] test test_hlt ... ok
Nov 18 08:37:09.558 INFO [stdout] test test_inc_loop ... ok
Nov 18 08:37:09.559 INFO [stdout] test create_vm ... ok
Nov 18 08:37:09.561 INFO [stderr]    Doc-tests iridium
Nov 18 08:37:09.561 INFO [stdout] test test_call_return ... ok
Nov 18 08:37:09.561 INFO [stdout] 
Nov 18 08:37:09.561 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:09.561 INFO [stdout] 
Nov 18 08:37:10.971 INFO [stdout] 
Nov 18 08:37:10.971 INFO [stdout] running 0 tests
Nov 18 08:37:10.971 INFO [stdout] 
Nov 18 08:37:10.971 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 08:37:10.971 INFO [stdout] 
Nov 18 08:37:10.978 INFO [stderr] su: No module specific data is present
Nov 18 08:37:11.252 INFO running `"docker" "inspect" "08b267088f1db82860e3b59ba50b3cb41b4c7e75f0d158a159ea14e63ce89645"`
Nov 18 08:37:11.319 INFO running `"docker" "rm" "-f" "08b267088f1db82860e3b59ba50b3cb41b4c7e75f0d158a159ea14e63ce89645"`
Nov 18 08:37:11.381 INFO [stdout] 08b267088f1db82860e3b59ba50b3cb41b4c7e75f0d158a159ea14e63ce89645