Dec 14 19:12:15.839 INFO testing solana_rbpf-0.1.5 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 19:12:15.839 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 14 19:12:16.176 INFO [stdout] af904f5214bab5dd2291d23c920b4981b950dc24a482b4e9eccde65600c34388
Dec 14 19:12:16.179 INFO running `"docker" "start" "-a" "af904f5214bab5dd2291d23c920b4981b950dc24a482b4e9eccde65600c34388"`
Dec 14 19:12:17.190 INFO [stderr] usermod: no changes
Dec 14 19:12:17.411 INFO [stderr]    Compiling ar v0.6.2
Dec 14 19:12:17.411 INFO [stderr]    Compiling bloom v0.3.2
Dec 14 19:12:17.419 INFO [stderr]    Compiling pretty_env_logger v0.2.5
Dec 14 19:12:20.464 INFO [stderr]    Compiling elfkit v0.0.6
Dec 14 19:12:31.214 INFO [stderr]    Compiling solana_rbpf v0.1.5 (/source)
Dec 14 19:12:37.183 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.97s
Dec 14 19:12:37.192 INFO [stderr] su: No module specific data is present
Dec 14 19:12:37.777 INFO running `"docker" "inspect" "af904f5214bab5dd2291d23c920b4981b950dc24a482b4e9eccde65600c34388"`
Dec 14 19:12:38.028 INFO running `"docker" "rm" "-f" "af904f5214bab5dd2291d23c920b4981b950dc24a482b4e9eccde65600c34388"`
Dec 14 19:12:38.303 INFO [stdout] af904f5214bab5dd2291d23c920b4981b950dc24a482b4e9eccde65600c34388
Dec 14 19:12:38.308 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 14 19:12:38.693 INFO [stdout] f4430070c7fd388681b7295cbcbc0e2ae9269b20ce44ce0e773f572b5db215e5
Dec 14 19:12:38.699 INFO running `"docker" "start" "-a" "f4430070c7fd388681b7295cbcbc0e2ae9269b20ce44ce0e773f572b5db215e5"`
Dec 14 19:12:40.338 INFO [stderr] usermod: no changes
Dec 14 19:12:40.689 INFO [stderr]    Compiling solana_rbpf v0.1.5 (/source)
Dec 14 19:13:17.879 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 37.51s
Dec 14 19:13:17.913 INFO [stderr] su: No module specific data is present
Dec 14 19:13:19.463 INFO running `"docker" "inspect" "f4430070c7fd388681b7295cbcbc0e2ae9269b20ce44ce0e773f572b5db215e5"`
Dec 14 19:13:19.803 INFO running `"docker" "rm" "-f" "f4430070c7fd388681b7295cbcbc0e2ae9269b20ce44ce0e773f572b5db215e5"`
Dec 14 19:13:20.072 INFO [stdout] f4430070c7fd388681b7295cbcbc0e2ae9269b20ce44ce0e773f572b5db215e5
Dec 14 19:13:20.079 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 14 19:13:20.626 INFO [stdout] 5561c5596a42bbde1de7c9686d4ec38672c922ee4eb58424da2a107da79779a8
Dec 14 19:13:20.632 INFO running `"docker" "start" "-a" "5561c5596a42bbde1de7c9686d4ec38672c922ee4eb58424da2a107da79779a8"`
Dec 14 19:13:21.689 INFO [stderr] usermod: no changes
Dec 14 19:13:21.979 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.26s
Dec 14 19:13:21.985 INFO [stderr]      Running /target/debug/deps/solana_rbpf-11be78d068b961e1
Dec 14 19:13:21.995 INFO [stdout] 
Dec 14 19:13:21.995 INFO [stdout] running 119 tests
Dec 14 19:13:21.995 INFO [stdout] test asm_parser::tests::test_empty ... ok
Dec 14 19:13:21.995 INFO [stdout] test asm_parser::tests::test_error_eof ... ok
Dec 14 19:13:21.995 INFO [stdout] test asm_parser::tests::test_instruction ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_integer ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_ja ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_ldxh ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_initial_whitespace ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_ident ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_exit ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_tcp_sack ... ok
Dec 14 19:13:22.003 INFO [stdout] test elf::test::test_relocate ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_lsh ... ok
Dec 14 19:13:22.003 INFO [stdout] test elf::test::test_validate ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_big_endian_16bits ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_error_unexpected_character ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_big_endian_32bits ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_big_endian_64bit ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_little_endian_16bits ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_little_endian_32bits ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_little_endian_64bit ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_bit_and_with_const_not_equal_zero ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_equals_const ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_or_equals_src_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_or_equals_to_const ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_than_const ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_than_const_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_not_equals_const ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_than_const_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_than_const ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_or_equals_src_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_to_label ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_than_src ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_or_equals_to_src ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_or_equals_src_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_or_equals_to_src ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_than_src ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_or_equals_src_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_not_equals_src ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_byte ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_double_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_half_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_double_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_or_equals_to_const ... ok
Dec 14 19:13:22.003 INFO [stdout] test asm_parser::tests::test_operand ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_than_src_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_double_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_than_src_signed ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_half_word ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::register::load_byte_from_set_src_with_offset ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::register::load_double_word_from_set_src_with_offset ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::register::load_half_word_from_set_src_with_offset ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::load_instructions::register::load_word_from_set_src_with_offset ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_and_add_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_bit_and_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_bit_or_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_bit_xor_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_div_constant_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_left_shift_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_logical_right_shift_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_mod_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_mul_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_negate_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_signed_right_shift_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_sub_const_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_and_add_from_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_bit_and_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_bit_or_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_bit_xor_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_div_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_from_register_to_another_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_left_shift_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_logical_right_shift_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_mod_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_mul_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_signed_right_shift_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_sub_from_register_to_register ... ok
Dec 14 19:13:22.003 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_and_add_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_bit_and_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_bit_or_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_bit_xor_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_div_constant_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_left_shift_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_logical_right_shift_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_mod_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_negate_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_signed_right_shift_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_sub_const_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_and_add_from_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_bit_and_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_bit_or_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_bit_xor_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_div_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_from_register_to_another_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_bit_and_with_src_not_equal_zero ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_byte ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_equals_src ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_left_shift_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_logical_right_shift_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_mod_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_signed_right_shift_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_mul_from_register_to_register ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::special::call_immediate ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::special::exit_operation ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_byte_from_dst_into_immediate_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_byte_from_dst_into_src_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_double_word_from_dst_into_immediate_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_double_word_from_dst_into_src_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_half_word_from_dst_into_immediate_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_half_word_from_dst_into_src_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_word_from_dst_into_immediate_address ... ok
Dec 14 19:13:22.004 INFO [stdout] test insn_builder::tests::store_instructions::store_word_from_dst_into_src_address ... ok
Dec 14 19:13:22.011 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_sub_from_register_to_register ... ok
Dec 14 19:13:22.011 INFO [stderr]      Running /target/debug/deps/assembler-5a5085a0392dfd0e
Dec 14 19:13:22.011 INFO [stdout] test insn_builder::tests::programs::example_from_assembler ... ok
Dec 14 19:13:22.011 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_mul_const_to_register ... ok
Dec 14 19:13:22.011 INFO [stdout] 
Dec 14 19:13:22.011 INFO [stdout] test result: ok. 119 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.011 INFO [stdout] 
Dec 14 19:13:22.011 INFO [stdout] 
Dec 14 19:13:22.011 INFO [stdout] running 32 tests
Dec 14 19:13:22.011 INFO [stdout] test test_call ... ok
Dec 14 19:13:22.019 INFO [stdout] test test_add64 ... ok
Dec 14 19:13:22.019 INFO [stdout] test test_alu_unary ... ok
Dec 14 19:13:22.019 INFO [stdout] test test_error_invalid_instruction ... ok
Dec 14 19:13:22.019 INFO [stdout] test test_be32 ... ok
Dec 14 19:13:22.019 INFO [stdout] test test_exit ... ok
Dec 14 19:13:22.027 INFO [stdout] test test_endian ... ok
Dec 14 19:13:22.027 INFO [stdout] test test_error_too_many_operands ... ok
Dec 14 19:13:22.027 INFO [stdout] test test_empty ... ok
Dec 14 19:13:22.027 INFO [stdout] test test_alu_binary ... ok
Dec 14 19:13:22.027 INFO [stdout] test test_error_unexpected_operands ... ok
Dec 14 19:13:22.035 INFO [stdout] test test_large_immediate ... ok
Dec 14 19:13:22.035 INFO [stdout] test test_ja ... ok
Dec 14 19:13:22.035 INFO [stdout] test test_jeq ... ok
Dec 14 19:13:22.039 INFO [stdout] test test_jump_conditional ... ok
Dec 14 19:13:22.039 INFO [stdout] test test_ldabsw ... ok
Dec 14 19:13:22.060 INFO [stdout] test test_ldxdw ... ok
Dec 14 19:13:22.060 INFO [stdout] test test_lddw ... ok
Dec 14 19:13:22.060 INFO [stdout] test test_ldindw ... ok
Dec 14 19:13:22.060 INFO [stdout] test test_ldxw ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_error_operands_out_of_range ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_load_ind ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_load_abs ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_load_reg ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_neg64 ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_sth ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_store_imm ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_stw ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_stxw ... ok
Dec 14 19:13:22.067 INFO [stdout] test test_store_reg ... ok
Dec 14 19:13:22.075 INFO [stdout] test test_stxh ... ok
Dec 14 19:13:22.079 INFO [stdout] test test_tcp_sack ... ok
Dec 14 19:13:22.079 INFO [stdout] 
Dec 14 19:13:22.079 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.079 INFO [stdout] 
Dec 14 19:13:22.082 INFO [stderr]      Running /target/debug/deps/common-a38efdf9110910ac
Dec 14 19:13:22.112 INFO [stdout] 
Dec 14 19:13:22.112 INFO [stdout] running 0 tests
Dec 14 19:13:22.113 INFO [stdout] 
Dec 14 19:13:22.113 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.113 INFO [stdout] 
Dec 14 19:13:22.115 INFO [stderr]      Running /target/debug/deps/disassembler-1be2fc5af448d743
Dec 14 19:13:22.132 INFO [stdout] 
Dec 14 19:13:22.132 INFO [stdout] running 27 tests
Dec 14 19:13:22.139 INFO [stdout] test test_alu_unary ... ok
Dec 14 19:13:22.139 INFO [stdout] test test_add64 ... ok
Dec 14 19:13:22.139 INFO [stdout] test test_ja ... ok
Dec 14 19:13:22.158 INFO [stdout] test test_call ... ok
Dec 14 19:13:22.158 INFO [stdout] test test_alu_binary ... ok
Dec 14 19:13:22.158 INFO [stdout] test test_exit ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_jump_conditional ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_be32 ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_empty ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_endian ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_ldabsw ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_ldxw ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_large_immediate ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_load_abs ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_jeq ... ok
Dec 14 19:13:22.167 INFO [stdout] test test_load_ind ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_load_reg ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_neg64 ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_sth ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_store_reg ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_ldxdw ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_lddw ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_ldindw ... ok
Dec 14 19:13:22.175 INFO [stdout] test test_stxh ... ok
Dec 14 19:13:22.183 INFO [stdout] test test_stw ... ok
Dec 14 19:13:22.183 INFO [stdout] test test_stxw ... ok
Dec 14 19:13:22.183 INFO [stdout] test test_store_imm ... ok
Dec 14 19:13:22.183 INFO [stdout] 
Dec 14 19:13:22.183 INFO [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.183 INFO [stdout] 
Dec 14 19:13:22.191 INFO [stderr]      Running /target/debug/deps/misc-0500de1f1338d78f
Dec 14 19:13:22.203 INFO [stdout] 
Dec 14 19:13:22.203 INFO [stdout] running 29 tests
Dec 14 19:13:22.221 INFO [stdout] test test_get_last_instruction_count ... ok
Dec 14 19:13:22.223 INFO [stdout] test test_jit_block_port ... ok
Dec 14 19:13:22.223 INFO [stdout] test test_jit_mbuff ... ok
Dec 14 19:13:22.223 INFO [stdout] test test_non_terminate_early ... ok
Dec 14 19:13:22.223 INFO [stdout] test test_non_terminate_capped ... ok
Dec 14 19:13:22.227 INFO [stdout] test test_symbol_relocation ... ok
Dec 14 19:13:22.243 INFO [stdout] test test_vm_block_port ... ok
Dec 14 19:13:22.243 INFO [stdout] test test_load_elf ... ok
Dec 14 19:13:22.268 INFO [stdout] test test_verifier_success ... ok
Dec 14 19:13:22.439 INFO [stdout] test test_jit_call_helper_wo_verifier ... ok
Dec 14 19:13:22.439 INFO [stdout] test test_non_terminating ... ok
Dec 14 19:13:22.439 INFO [stdout] test test_unterminated_string ... ok
Dec 14 19:13:22.443 INFO [stdout] test test_vm_exec_no_program ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_null_string ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_vm_jit_ldabsb ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_vm_jit_ldabsdw ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_vm_err_ldabsb_nomem ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_verifier_fail ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_vm_err_ldabsb_oob ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_vm_err_ldindb_nomem ... ok
Dec 14 19:13:22.464 INFO [stdout] test test_vm_err_ldindb_oob ... ok
Dec 14 19:13:22.465 INFO [stdout] test test_vm_mbuff ... ok
Dec 14 19:13:22.465 INFO [stdout] test test_vm_jit_ldindb ... ok
Dec 14 19:13:22.467 INFO [stdout] test test_vm_jit_ldabsw ... ok
Dec 14 19:13:22.467 INFO [stdout] test test_vm_mbuff_with_rust_api ... ok
Dec 14 19:13:22.467 INFO [stdout] test test_vm_jit_ldindh ... ok
Dec 14 19:13:22.467 INFO [stdout] test test_vm_jit_ldabsh ... ok
Dec 14 19:13:22.467 INFO [stdout] test test_vm_jit_ldindw ... ok
Dec 14 19:13:22.467 INFO [stdout] test test_vm_jit_ldinddw ... ok
Dec 14 19:13:22.467 INFO [stdout] 
Dec 14 19:13:22.467 INFO [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.467 INFO [stdout] 
Dec 14 19:13:22.470 INFO [stderr]      Running /target/debug/deps/ubpf_jit_x86_64-7935ca69aebb8b15
Dec 14 19:13:22.491 INFO [stdout] 
Dec 14 19:13:22.491 INFO [stdout] running 97 tests
Dec 14 19:13:22.491 INFO [stdout] test test_jit_alu_arith ... ok
Dec 14 19:13:22.491 INFO [stdout] test test_jit_alu64_arith ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_be16 ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_arsh32_high_shift ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_arsh64 ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_be16_high ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_arsh_reg ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_add ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_alu_bit ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_alu64_bit ... ok
Dec 14 19:13:22.507 INFO [stdout] test test_jit_arsh ... ok
Dec 14 19:13:22.523 INFO [stdout] test test_jit_be32 ... ok
Dec 14 19:13:22.523 INFO [stdout] test test_jit_call ... ok
Dec 14 19:13:22.523 INFO [stdout] test test_jit_be32_high ... ok
Dec 14 19:13:22.523 INFO [stdout] test test_jit_be64 ... ok
Dec 14 19:13:22.523 INFO [stdout] test test_jit_div32_high_divisor ... ok
Dec 14 19:13:22.531 INFO [stdout] test test_jit_div64_imm ... ok
Dec 14 19:13:22.531 INFO [stdout] test test_jit_call_memfrob ... ok
Dec 14 19:13:22.531 INFO [stdout] test test_jit_div32_reg ... ok
Dec 14 19:13:22.531 INFO [stdout] test test_jit_div32_imm ... ok
Dec 14 19:13:22.531 INFO [stdout] test test_jit_div64_reg ... ok
Dec 14 19:13:22.531 INFO [stdout] test test_jit_early_exit ... ok
Dec 14 19:13:22.543 INFO [stdout] test test_jit_ja ... ok
Dec 14 19:13:22.543 INFO [stdout] test test_jit_exit ... ok
Dec 14 19:13:22.550 INFO [stdout] test test_jit_jgt_imm ... ok
Dec 14 19:13:22.550 INFO [stdout] test test_jit_jge_imm ... ok
Dec 14 19:13:22.550 INFO [stdout] test test_jit_jgt_reg ... ok
Dec 14 19:13:22.550 INFO [stdout] test test_jit_jle_imm ... ok
Dec 14 19:13:22.553 INFO [stdout] test test_jit_jeq_imm ... ok
Dec 14 19:13:22.553 INFO [stdout] test test_jit_jeq_reg ... ok
Dec 14 19:13:22.553 INFO [stdout] test test_jit_jit_bounce ... ok
Dec 14 19:13:22.563 INFO [stdout] test test_jit_jlt_reg ... ok
Dec 14 19:13:22.563 INFO [stdout] test test_jit_jne_reg ... ok
Dec 14 19:13:22.566 INFO [stdout] test test_jit_jle_reg ... ok
Dec 14 19:13:22.566 INFO [stdout] test test_jit_jlt_imm ... ok
Dec 14 19:13:22.566 INFO [stdout] test test_jit_jsge_imm ... ok
Dec 14 19:13:22.571 INFO [stdout] test test_jit_jset_reg ... ok
Dec 14 19:13:22.572 INFO [stdout] test test_jit_jset_imm ... ok
Dec 14 19:13:22.572 INFO [stdout] test test_jit_jslt_imm ... ok
Dec 14 19:13:22.580 INFO [stdout] test test_jit_jsgt_imm ... ok
Dec 14 19:13:22.583 INFO [stdout] test test_jit_jsge_reg ... ok
Dec 14 19:13:22.583 INFO [stdout] test test_jit_jslt_reg ... ok
Dec 14 19:13:22.584 INFO [stdout] test test_jit_jsgt_reg ... ok
Dec 14 19:13:22.584 INFO [stdout] test test_jit_jsle_imm ... ok
Dec 14 19:13:22.587 INFO [stdout] test test_jit_lddw ... ok
Dec 14 19:13:22.587 INFO [stdout] test test_jit_jsle_reg ... ok
Dec 14 19:13:22.607 INFO [stdout] test test_jit_ldxh ... ok
Dec 14 19:13:22.607 INFO [stdout] test test_jit_ldxb_all ... ok
Dec 14 19:13:22.607 INFO [stdout] test test_jit_lddw2 ... ok
Dec 14 19:13:22.615 INFO [stdout] test test_jit_ldxb ... ok
Dec 14 19:13:22.615 INFO [stdout] test test_jit_ldxh_all ... ok
Dec 14 19:13:22.615 INFO [stdout] test test_jit_ldxh_all2 ... ok
Dec 14 19:13:22.615 INFO [stdout] test test_jit_ldxdw ... ok
Dec 14 19:13:22.619 INFO [stdout] test test_jit_ldxh_same_reg ... ok
Dec 14 19:13:22.619 INFO [stdout] test test_jit_ldxw ... ok
Dec 14 19:13:22.619 INFO [stdout] test test_jit_ldxw_all ... ok
Dec 14 19:13:22.651 INFO [stdout] test test_jit_mod ... ok
Dec 14 19:13:22.651 INFO [stdout] test test_jit_le32 ... ok
Dec 14 19:13:22.651 INFO [stdout] test test_jit_le16 ... ok
Dec 14 19:13:22.651 INFO [stdout] test test_jit_le64 ... ok
Dec 14 19:13:22.651 INFO [stdout] test test_jit_lsh_reg ... ok
Dec 14 19:13:22.659 INFO [stdout] test test_jit_mod32 ... ok
Dec 14 19:13:22.671 INFO [stdout] test test_jit_mod64 ... ok
Dec 14 19:13:22.671 INFO [stdout] test test_jit_mul64_imm ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_mul32_reg_overflow ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_mul64_reg ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_mul32_reg ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_mul32_imm ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_mov ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_neg ... ok
Dec 14 19:13:22.683 INFO [stdout] test test_jit_mul_loop ... ok
Dec 14 19:13:22.707 INFO [stdout] test test_jit_stack2 ... ok
Dec 14 19:13:22.707 INFO [stdout] test test_jit_prime ... ok
Dec 14 19:13:22.707 INFO [stdout] test test_jit_neg64 ... ok
Dec 14 19:13:22.707 INFO [stdout] test test_jit_rhs32 ... ok
Dec 14 19:13:22.707 INFO [stdout] test test_jit_rsh_reg ... ok
Dec 14 19:13:22.707 INFO [stdout] test test_jit_stb ... ok
Dec 14 19:13:22.708 INFO [stdout] test test_jit_stack ... ok
Dec 14 19:13:22.709 INFO [stdout] test test_jit_stdw ... ok
Dec 14 19:13:22.709 INFO [stdout] test test_jit_sth ... ok
Dec 14 19:13:22.710 INFO [stdout] test test_jit_stxb_all2 ... ok
Dec 14 19:13:22.719 INFO [stdout] test test_jit_stxb ... ok
Dec 14 19:13:22.719 INFO [stdout] test test_jit_stxb_all ... ok
Dec 14 19:13:22.719 INFO [stdout] test test_jit_stxb_chain ... ok
Dec 14 19:13:22.719 INFO [stdout] test test_jit_stxdw ... ok
Dec 14 19:13:22.723 INFO [stdout] test test_jit_tcp_port80_nomatch ... ok
Dec 14 19:13:22.723 INFO [stdout] test test_jit_tcp_port80_match ... ok
Dec 14 19:13:22.723 INFO [stdout] test test_jit_stxw ... ok
Dec 14 19:13:22.724 INFO [stdout] test test_jit_subnet ... ok
Dec 14 19:13:22.727 INFO [stdout] test test_jit_tcp_port80_nomatch_ethertype ... ok
Dec 14 19:13:22.727 INFO [stdout] test test_jit_tcp_port80_nomatch_proto ... ok
Dec 14 19:13:22.727 INFO [stdout] test test_jit_stxh ... ok
Dec 14 19:13:22.728 INFO [stdout] test test_jit_stw ... ok
Dec 14 19:13:22.730 INFO [stdout] test test_jit_string_stack ... ok
Dec 14 19:13:22.745 INFO [stdout] test test_jit_tcp_sack_match ... ok
Dec 14 19:13:22.745 INFO [stdout] test test_jit_tcp_sack_nomatch ... ok
Dec 14 19:13:22.770 INFO [stdout] test test_jit_err_call_unreg ... ok
Dec 14 19:13:22.770 INFO [stdout] 
Dec 14 19:13:22.770 INFO [stdout] test result: ok. 97 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.770 INFO [stdout] 
Dec 14 19:13:22.773 INFO [stderr]      Running /target/debug/deps/ubpf_verifier-b501555cea7948d0
Dec 14 19:13:22.776 INFO [stdout] 
Dec 14 19:13:22.776 INFO [stdout] running 12 tests
Dec 14 19:13:22.942 INFO [stdout] test test_verifier_err_incomplete_lddw ... ok
Dec 14 19:13:22.942 INFO [stdout] test test_verifier_err_endian_size ... ok
Dec 14 19:13:22.942 INFO [stdout] test test_verifier_err_invalid_reg_dst ... ok
Dec 14 19:13:22.951 INFO [stdout] test test_verifier_err_invalid_reg_src ... ok
Dec 14 19:13:22.951 INFO [stdout] test test_verifier_err_jmp_out ... ok
Dec 14 19:13:22.951 INFO [stdout] test test_verifier_err_jmp_lddw ... ok
Dec 14 19:13:22.951 INFO [stdout] test test_verifier_err_div_by_zero_imm ... ok
Dec 14 19:13:22.951 INFO [stdout] test test_verifier_err_infinite_loop ... ok
Dec 14 19:13:22.964 INFO [stdout] test test_verifier_err_too_many_instructions ... ok
Dec 14 19:13:22.964 INFO [stdout] test test_verifier_err_no_exit ... ok
Dec 14 19:13:22.967 INFO [stdout] test test_verifier_err_unknown_opcode ... ok
Dec 14 19:13:22.967 INFO [stdout] test test_verifier_err_write_r10 ... ok
Dec 14 19:13:22.967 INFO [stdout] 
Dec 14 19:13:22.967 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:22.967 INFO [stdout] 
Dec 14 19:13:22.979 INFO [stderr]      Running /target/debug/deps/ubpf_vm-8faaf6d5d1c4ce7b
Dec 14 19:13:22.983 INFO [stdout] 
Dec 14 19:13:22.983 INFO [stdout] running 102 tests
Dec 14 19:13:22.999 INFO [stdout] test test_vm_add ... ok
Dec 14 19:13:23.003 INFO [stdout] test test_vm_alu_arith ... ok
Dec 14 19:13:23.003 INFO [stdout] test test_vm_alu64_bit ... ok
Dec 14 19:13:23.005 INFO [stdout] test test_vm_arsh32_high_shift ... ok
Dec 14 19:13:23.011 INFO [stdout] test test_vm_be16 ... ok
Dec 14 19:13:23.011 INFO [stdout] test test_vm_alu64_arith ... ok
Dec 14 19:13:23.015 INFO [stdout] test test_vm_alu_bit ... ok
Dec 14 19:13:23.015 INFO [stdout] test test_vm_arsh_reg ... ok
Dec 14 19:13:23.015 INFO [stdout] test test_vm_arsh64 ... ok
Dec 14 19:13:23.023 INFO [stdout] test test_vm_be64 ... ok
Dec 14 19:13:23.023 INFO [stdout] test test_vm_be16_high ... ok
Dec 14 19:13:23.023 INFO [stdout] test test_vm_arsh ... ok
Dec 14 19:13:23.023 INFO [stdout] test test_vm_call ... ok
Dec 14 19:13:23.023 INFO [stdout] test test_vm_call_memfrob ... ok
Dec 14 19:13:23.023 INFO [stdout] test test_vm_div32_high_divisor ... ok
Dec 14 19:13:23.031 INFO [stdout] test test_vm_be32 ... ok
Dec 14 19:13:23.031 INFO [stdout] test test_vm_be32_high ... ok
Dec 14 19:13:23.054 INFO [stdout] test test_vm_div64_imm ... ok
Dec 14 19:13:23.055 INFO [stdout] test test_vm_early_exit ... ok
Dec 14 19:13:23.055 INFO [stdout] test test_vm_div64_reg ... ok
Dec 14 19:13:23.059 INFO [stdout] test test_vm_div32_imm ... ok
Dec 14 19:13:23.059 INFO [stdout] test test_vm_div32_reg ... ok
Dec 14 19:13:23.067 INFO [stdout] test test_vm_ja ... ok
Dec 14 19:13:23.067 INFO [stdout] test test_vm_exit ... ok
Dec 14 19:13:23.071 INFO [stdout] test test_vm_jeq_reg ... ok
Dec 14 19:13:23.075 INFO [stdout] test test_vm_jeq_imm ... ok
Dec 14 19:13:23.075 INFO [stdout] test test_vm_jgt_imm ... ok
Dec 14 19:13:23.083 INFO [stdout] test test_vm_jgt_reg ... ok
Dec 14 19:13:23.083 INFO [stdout] test test_vm_jge_imm ... ok
Dec 14 19:13:23.091 INFO [stdout] test test_vm_jit_bounce ... ok
Dec 14 19:13:23.091 INFO [stdout] test test_vm_jle_imm ... ok
Dec 14 19:13:23.091 INFO [stdout] test test_vm_jle_reg ... ok
Dec 14 19:13:23.091 INFO [stdout] test test_vm_jlt_imm ... ok
Dec 14 19:13:23.103 INFO [stdout] test test_vm_jne_reg ... ok
Dec 14 19:13:23.103 INFO [stdout] test test_vm_jlt_reg ... ok
Dec 14 19:13:23.115 INFO [stdout] test test_vm_jset_imm ... ok
Dec 14 19:13:23.115 INFO [stdout] test test_vm_jset_reg ... ok
Dec 14 19:13:23.124 INFO [stdout] test test_vm_jsge_imm ... ok
Dec 14 19:13:23.124 INFO [stdout] test test_vm_jsge_reg ... ok
Dec 14 19:13:23.124 INFO [stdout] test test_vm_jsgt_imm ... ok
Dec 14 19:13:23.131 INFO [stdout] test test_vm_jsgt_reg ... ok
Dec 14 19:13:23.131 INFO [stdout] test test_vm_jsle_reg ... ok
Dec 14 19:13:23.131 INFO [stdout] test test_vm_jslt_imm ... ok
Dec 14 19:13:23.131 INFO [stdout] test test_vm_jslt_reg ... ok
Dec 14 19:13:23.131 INFO [stdout] test test_vm_lddw ... ok
Dec 14 19:13:23.143 INFO [stdout] test test_vm_lddw2 ... ok
Dec 14 19:13:23.143 INFO [stdout] test test_vm_ldxb ... ok
Dec 14 19:13:23.143 INFO [stdout] test test_vm_ldxb_all ... ok
Dec 14 19:13:23.151 INFO [stdout] test test_vm_jsle_imm ... ok
Dec 14 19:13:23.151 INFO [stdout] test test_vm_ldxdw ... ok
Dec 14 19:13:23.179 INFO [stdout] test test_vm_ldxh_all ... ok
Dec 14 19:13:23.179 INFO [stdout] test test_vm_ldxh ... ok
Dec 14 19:13:23.179 INFO [stdout] test test_vm_ldxh_all2 ... ok
Dec 14 19:13:23.179 INFO [stdout] test test_vm_ldxh_same_reg ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_ldxw ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_ldxw_all ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_le32 ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_le16 ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_err_call_unreg ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_le64 ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_lsh_reg ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_mod32 ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_mod64 ... ok
Dec 14 19:13:23.187 INFO [stdout] test test_vm_mov ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_err_div64_by_zero_reg ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_mul32_imm ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_err_div_by_zero_reg ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_err_mod_by_zero_reg ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_err_stack_out_of_bound ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_mul32_reg ... ok
Dec 14 19:13:23.191 INFO [stdout] test test_vm_err_mod64_by_zero_reg ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_mod ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_mul32_reg_overflow ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_mul64_imm ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_mul64_reg ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_mul_loop ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_neg ... ok
Dec 14 19:13:23.199 INFO [stdout] test test_vm_neg64 ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_prime ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_rhs32 ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_stdw ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_sth ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_string_stack ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_stack ... ok
Dec 14 19:13:23.203 INFO [stdout] test test_vm_stb ... ok
Dec 14 19:13:23.207 INFO [stdout] test test_vm_stack2 ... ok
Dec 14 19:13:23.207 INFO [stdout] test test_vm_rsh_reg ... ok
Dec 14 19:13:23.207 INFO [stdout] test test_vm_stxb_all ... ok
Dec 14 19:13:23.207 INFO [stdout] test test_vm_stw ... ok
Dec 14 19:13:23.207 INFO [stdout] test test_vm_stxb_all2 ... ok
Dec 14 19:13:23.208 INFO [stdout] test test_vm_stxb_chain ... ok
Dec 14 19:13:23.209 INFO [stdout] test test_vm_stxb ... ok
Dec 14 19:13:23.209 INFO [stdout] test test_vm_stxh ... ok
Dec 14 19:13:23.210 INFO [stdout] test test_vm_stxw ... ok
Dec 14 19:13:23.210 INFO [stdout] test test_vm_subnet ... ok
Dec 14 19:13:23.210 INFO [stdout] test test_vm_tcp_port80_match ... ok
Dec 14 19:13:23.210 INFO [stdout] test test_vm_tcp_port80_nomatch ... ok
Dec 14 19:13:23.219 INFO [stdout] test test_vm_tcp_sack_match ... ok
Dec 14 19:13:23.229 INFO [stdout] test test_vm_stxdw ... ok
Dec 14 19:13:23.229 INFO [stdout] test test_vm_tcp_sack_nomatch ... ok
Dec 14 19:13:23.229 INFO [stdout] test test_vm_tcp_port80_nomatch_proto ... ok
Dec 14 19:13:23.229 INFO [stdout] test test_vm_tcp_port80_nomatch_ethertype ... ok
Dec 14 19:13:23.229 INFO [stdout] 
Dec 14 19:13:23.229 INFO [stdout] test result: ok. 102 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:13:23.229 INFO [stdout] 
Dec 14 19:13:23.229 INFO [stderr]    Doc-tests solana_rbpf
Dec 14 19:13:27.123 INFO [stdout] 
Dec 14 19:13:27.123 INFO [stdout] running 55 tests
Dec 14 19:13:51.691 INFO [stdout] test src/ebpf.rs - ebpf::get_insn (line 507) ... ok
Dec 14 19:13:52.440 INFO [stdout] test src/assembler.rs - assembler::assemble (line 210) ... ok
Dec 14 19:13:53.803 INFO [stdout] test src/ebpf.rs - ebpf::get_insn (line 520) ... ok
Dec 14 19:13:54.584 INFO [stdout] test src/disassembler.rs - disassembler::disassemble (line 323) ... ok
Dec 14 19:13:54.595 INFO [stdout] test src/disassembler.rs - disassembler::to_insn_vec (line 122) ... ok
Dec 14 19:13:54.955 INFO [stdout] test src/ebpf.rs - ebpf::Insn::to_array (line 436) ... ok
Dec 14 19:13:55.155 INFO [stdout] test src/ebpf.rs - ebpf::Insn::to_vec (line 468) ... ok
Dec 14 19:13:57.279 INFO [stdout] test src/ebpf.rs - ebpf::to_insn_vec (line 556) ... ok
Dec 14 19:14:12.475 INFO [stdout] test src/helpers.rs - helpers::bpf_time_getns (line 41) ... ok
Dec 14 19:14:17.766 INFO [stdout] test src/helpers.rs - helpers::bpf_trace_printf (line 72) ... ok
Dec 14 19:14:20.547 INFO [stdout] test src/helpers.rs - helpers::gather_bytes (line 122) ... ok
Dec 14 19:14:20.563 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff (line 881) ... ok
Dec 14 19:14:20.967 INFO [stdout] test src/helpers.rs - helpers::rand (line 251) ... ok
Dec 14 19:14:22.759 INFO [stdout] test src/helpers.rs - helpers::strcmp (line 206) ... ok
Dec 14 19:14:23.223 INFO [stdout] test src/helpers.rs - helpers::memfrob (line 142) ... ok
Dec 14 19:14:25.258 INFO [stdout] test src/helpers.rs - helpers::sqrti (line 190) ... ok
Dec 14 19:14:42.542 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::execute_program (line 1168) ... ok
Dec 14 19:14:44.061 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::execute_program_jit (line 1262) ... ok
Dec 14 19:14:46.039 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::jit_compile (line 1208) ... ok
Dec 14 19:14:47.703 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::new (line 926) ... ok
Dec 14 19:14:48.015 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::get_last_instruction_count (line 1058) ... ok
Dec 14 19:14:48.484 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::register_helper (line 1091) ... ok
Dec 14 19:14:49.379 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::set_max_instruction_count (line 1036) ... ok
Dec 14 19:14:55.263 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::set_program (line 962) ... ok
Dec 14 19:15:09.609 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::set_verifier (line 1003) ... ok
Dec 14 19:15:09.995 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::new (line 117) ... ok
Dec 14 19:15:10.966 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::execute_program (line 359) ... ok
Dec 14 19:15:10.975 INFO [stdout] test src/lib.rs - EbpfVmMbuff (line 73) ... ok
Dec 14 19:15:12.116 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::jit_compile (line 744) ... ok
Dec 14 19:15:12.307 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::get_last_instruction_count (line 244) ... ok
Dec 14 19:15:13.219 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::execute_program_jit (line 794) ... ok
Dec 14 19:15:16.515 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::register_helper (line 287) ... ok
Dec 14 19:15:22.519 INFO [stdout] test src/lib.rs - EbpfVmNoData (line 1669) ... ok
Dec 14 19:15:29.631 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::set_max_instruction_count (line 221) ... ok
Dec 14 19:15:31.690 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::get_last_instruction_count (line 1828) ... ok
Dec 14 19:15:31.924 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::set_program (line 147) ... ok
Dec 14 19:15:32.975 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::execute_program_jit (line 1971) ... ok
Dec 14 19:15:33.331 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::set_verifier (line 182) ... ok
Dec 14 19:15:37.707 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::execute_program (line 1940) ... ok
Dec 14 19:15:42.835 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::jit_compile (line 1920) ... ok
Dec 14 19:15:45.051 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::new (line 1714) ... ok
Dec 14 19:16:01.291 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::set_program (line 1735) ... ok
Dec 14 19:16:01.855 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::register_helper (line 1857) ... ok
Dec 14 19:16:02.011 INFO [stdout] test src/lib.rs - EbpfVmRaw (line 1319) ... ok
Dec 14 19:16:02.275 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::set_max_instruction_count (line 1806) ... ok
Dec 14 19:16:07.527 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::set_verifier (line 1773) ... ok
Dec 14 19:16:12.371 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::execute_program (line 1562) ... ok
Dec 14 19:16:14.635 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::execute_program_jit (line 1635) ... ok
Dec 14 19:16:17.092 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::get_last_instruction_count (line 1464) ... ok
Dec 14 19:16:25.835 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::jit_compile (line 1590) ... ok
Dec 14 19:16:27.899 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::set_max_instruction_count (line 1442) ... ok
Dec 14 19:16:28.647 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::new (line 1348) ... ok
Dec 14 19:16:28.831 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::register_helper (line 1497) ... ok
Dec 14 19:16:29.251 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::set_program (line 1370) ... ok
Dec 14 19:16:29.807 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::set_verifier (line 1409) ... ok
Dec 14 19:16:29.811 INFO [stdout] 
Dec 14 19:16:29.811 INFO [stdout] test result: ok. 55 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 19:16:29.811 INFO [stdout] 
Dec 14 19:16:29.839 INFO [stderr] su: No module specific data is present
Dec 14 19:16:30.771 INFO running `"docker" "inspect" "5561c5596a42bbde1de7c9686d4ec38672c922ee4eb58424da2a107da79779a8"`
Dec 14 19:16:31.029 INFO running `"docker" "rm" "-f" "5561c5596a42bbde1de7c9686d4ec38672c922ee4eb58424da2a107da79779a8"`
Dec 14 19:16:31.310 INFO [stdout] 5561c5596a42bbde1de7c9686d4ec38672c922ee4eb58424da2a107da79779a8