Dec 02 16:11:29.377 INFO testing solana_rbpf-0.1.4 against 1.30.1 for beta-1.31-5
Dec 02 16:11:29.377 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:11:29.632 INFO [stdout] 7f83d9898ac80eb311226b1eb108069b46a9f512f9cda2886d449b1790efef3d
Dec 02 16:11:29.634 INFO running `"docker" "start" "-a" "7f83d9898ac80eb311226b1eb108069b46a9f512f9cda2886d449b1790efef3d"`
Dec 02 16:11:30.421 INFO [stderr] usermod: no changes
Dec 02 16:11:30.686 INFO [stderr]    Compiling bloom v0.3.2
Dec 02 16:11:30.686 INFO [stderr]    Compiling ar v0.6.1
Dec 02 16:11:30.686 INFO [stderr]    Compiling combine v2.5.2
Dec 02 16:11:30.695 INFO [stderr]    Compiling num-integer v0.1.39
Dec 02 16:11:30.695 INFO [stderr]    Compiling enum-primitive-derive v0.1.2
Dec 02 16:11:34.291 INFO [stderr]    Compiling chrono v0.4.6
Dec 02 16:11:39.389 INFO [stderr]    Compiling pretty_env_logger v0.2.5
Dec 02 16:11:41.843 INFO [stderr]    Compiling elfkit v0.0.6
Dec 02 16:11:50.729 INFO [stderr]    Compiling solana_rbpf v0.1.4 (/source)
Dec 02 16:11:57.214 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 26.76s
Dec 02 16:11:57.223 INFO [stderr] su: No module specific data is present
Dec 02 16:11:58.129 INFO running `"docker" "inspect" "7f83d9898ac80eb311226b1eb108069b46a9f512f9cda2886d449b1790efef3d"`
Dec 02 16:11:58.393 INFO running `"docker" "rm" "-f" "7f83d9898ac80eb311226b1eb108069b46a9f512f9cda2886d449b1790efef3d"`
Dec 02 16:11:58.743 INFO [stdout] 7f83d9898ac80eb311226b1eb108069b46a9f512f9cda2886d449b1790efef3d
Dec 02 16:11:58.751 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:11:59.265 INFO [stdout] 9c0a663439ba39e734b9241f73f4336e33e457752332d4f0b9829ca8c69902ed
Dec 02 16:11:59.267 INFO running `"docker" "start" "-a" "9c0a663439ba39e734b9241f73f4336e33e457752332d4f0b9829ca8c69902ed"`
Dec 02 16:12:00.500 INFO [stderr] usermod: no changes
Dec 02 16:12:00.676 INFO [stderr]    Compiling solana_rbpf v0.1.4 (/source)
Dec 02 16:12:29.500 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 28.97s
Dec 02 16:12:29.535 INFO [stderr] su: No module specific data is present
Dec 02 16:12:30.256 INFO running `"docker" "inspect" "9c0a663439ba39e734b9241f73f4336e33e457752332d4f0b9829ca8c69902ed"`
Dec 02 16:12:30.505 INFO running `"docker" "rm" "-f" "9c0a663439ba39e734b9241f73f4336e33e457752332d4f0b9829ca8c69902ed"`
Dec 02 16:12:30.830 INFO [stdout] 9c0a663439ba39e734b9241f73f4336e33e457752332d4f0b9829ca8c69902ed
Dec 02 16:12:30.835 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 16:12:31.221 INFO [stdout] 3bbac694b4217ad9ea3dc4d10156245bee93e607da29f1b9e8224bb25c5c8e8d
Dec 02 16:12:31.223 INFO running `"docker" "start" "-a" "3bbac694b4217ad9ea3dc4d10156245bee93e607da29f1b9e8224bb25c5c8e8d"`
Dec 02 16:12:32.650 INFO [stderr] usermod: no changes
Dec 02 16:12:32.923 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.25s
Dec 02 16:12:32.932 INFO [stderr]      Running /target/debug/deps/solana_rbpf-e16a590756a88748
Dec 02 16:12:32.934 INFO [stdout] 
Dec 02 16:12:32.934 INFO [stdout] running 119 tests
Dec 02 16:12:32.934 INFO [stdout] test asm_parser::tests::test_integer ... ok
Dec 02 16:12:32.935 INFO [stdout] test asm_parser::tests::test_instruction ... ok
Dec 02 16:12:32.951 INFO [stdout] test asm_parser::tests::test_initial_whitespace ... ok
Dec 02 16:12:32.975 INFO [stdout] test asm_parser::tests::test_ident ... ok
Dec 02 16:12:32.991 INFO [stdout] test asm_parser::tests::test_exit ... ok
Dec 02 16:12:33.011 INFO [stdout] test asm_parser::tests::test_error_eof ... ok
Dec 02 16:12:33.015 INFO [stdout] test asm_parser::tests::test_ja ... ok
Dec 02 16:12:33.015 INFO [stdout] test asm_parser::tests::test_ldxh ... ok
Dec 02 16:12:33.015 INFO [stdout] test asm_parser::tests::test_empty ... ok
Dec 02 16:12:33.016 INFO [stdout] test elf::test::test_relocate ... ok
Dec 02 16:12:33.016 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_big_endian_16bits ... ok
Dec 02 16:12:33.016 INFO [stdout] test asm_parser::tests::test_error_unexpected_character ... ok
Dec 02 16:12:33.016 INFO [stdout] test elf::test::test_validate ... ok
Dec 02 16:12:33.016 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_big_endian_64bit ... ok
Dec 02 16:12:33.016 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_little_endian_32bits ... ok
Dec 02 16:12:33.016 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_big_endian_32bits ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_little_endian_64bit ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_equals_const ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_bit_and_with_const_not_equal_zero ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::byte_swap_instructions::convert_host_to_little_endian_16bits ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_or_equals_to_const ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_or_equals_src_signed ... ok
Dec 02 16:12:33.017 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_or_equals_src_signed ... ok
Dec 02 16:12:33.018 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_than_const ... ok
Dec 02 16:12:33.018 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_greater_than_const_signed ... ok
Dec 02 16:12:33.018 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_or_equals_to_const ... ok
Dec 02 16:12:33.018 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_than_const ... ok
Dec 02 16:12:33.023 INFO [stdout] test asm_parser::tests::test_lsh ... ok
Dec 02 16:12:33.023 INFO [stdout] test asm_parser::tests::test_operand ... ok
Dec 02 16:12:33.023 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_to_label ... ok
Dec 02 16:12:33.023 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_bit_and_with_src_not_equal_zero ... ok
Dec 02 16:12:33.024 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_equals_src ... ok
Dec 02 16:12:33.024 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_or_equals_src_signed ... ok
Dec 02 16:12:33.024 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_than_src ... ok
Dec 02 16:12:33.024 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_than_src_signed ... ok
Dec 02 16:12:33.024 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_or_equals_src_signed ... ok
Dec 02 16:12:33.025 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_or_equals_to_src ... ok
Dec 02 16:12:33.026 INFO [stdout] test asm_parser::tests::test_register ... ok
Dec 02 16:12:33.027 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_greater_or_equals_to_src ... ok
Dec 02 16:12:33.027 INFO [stdout] test asm_parser::tests::test_tcp_sack ... ok
Dec 02 16:12:33.027 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_double_word ... ok
Dec 02 16:12:33.027 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_half_word ... ok
Dec 02 16:12:33.028 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_than_src ... ok
Dec 02 16:12:33.035 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_not_equals_src ... ok
Dec 02 16:12:33.039 INFO [stdout] test insn_builder::tests::jump_instructions::register::jump_on_dst_lower_than_src_signed ... ok
Dec 02 16:12:33.039 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_byte ... ok
Dec 02 16:12:33.039 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_double_word ... ok
Dec 02 16:12:33.040 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_lower_than_const_signed ... ok
Dec 02 16:12:33.040 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_byte ... ok
Dec 02 16:12:33.040 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_abs_word ... ok
Dec 02 16:12:33.041 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_double_word ... ok
Dec 02 16:12:33.041 INFO [stdout] test insn_builder::tests::jump_instructions::immediate::jump_on_dst_not_equals_const ... ok
Dec 02 16:12:33.041 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_half_word ... ok
Dec 02 16:12:33.041 INFO [stdout] test insn_builder::tests::load_instructions::immediate::load_indirect_word ... ok
Dec 02 16:12:33.042 INFO [stdout] test insn_builder::tests::load_instructions::register::load_byte_from_set_src_with_offset ... ok
Dec 02 16:12:33.042 INFO [stdout] test insn_builder::tests::load_instructions::register::load_word_from_set_src_with_offset ... ok
Dec 02 16:12:33.042 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_and_add_const_to_register ... ok
Dec 02 16:12:33.042 INFO [stdout] test insn_builder::tests::load_instructions::register::load_half_word_from_set_src_with_offset ... ok
Dec 02 16:12:33.043 INFO [stdout] test insn_builder::tests::load_instructions::register::load_double_word_from_set_src_with_offset ... ok
Dec 02 16:12:33.043 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_bit_and_const_to_register ... ok
Dec 02 16:12:33.043 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_bit_or_const_to_register ... ok
Dec 02 16:12:33.043 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_bit_xor_const_to_register ... ok
Dec 02 16:12:33.043 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_const_to_register ... ok
Dec 02 16:12:33.044 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_div_constant_to_register ... ok
Dec 02 16:12:33.044 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_left_shift_const_to_register ... ok
Dec 02 16:12:33.044 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_logical_right_shift_const_to_register ... ok
Dec 02 16:12:33.044 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_mod_const_to_register ... ok
Dec 02 16:12:33.044 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_mul_const_to_register ... ok
Dec 02 16:12:33.045 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_negate_register ... ok
Dec 02 16:12:33.045 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_signed_right_shift_const_to_register ... ok
Dec 02 16:12:33.045 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::immediate::move_sub_const_to_register ... ok
Dec 02 16:12:33.045 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_and_add_from_register ... ok
Dec 02 16:12:33.045 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_bit_and_from_register_to_register ... ok
Dec 02 16:12:33.046 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_bit_or_from_register_to_register ... ok
Dec 02 16:12:33.046 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_bit_xor_from_register_to_register ... ok
Dec 02 16:12:33.046 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_div_from_register_to_register ... ok
Dec 02 16:12:33.046 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_from_register_to_another_register ... ok
Dec 02 16:12:33.046 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_left_shift_from_register_to_register ... ok
Dec 02 16:12:33.047 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_logical_right_shift_from_register_to_register ... ok
Dec 02 16:12:33.047 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_mod_from_register_to_register ... ok
Dec 02 16:12:33.047 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_mul_from_register_to_register ... ok
Dec 02 16:12:33.047 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_signed_right_shift_from_register_to_register ... ok
Dec 02 16:12:33.048 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x32::register::move_sub_from_register_to_register ... ok
Dec 02 16:12:33.048 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_and_add_const_to_register ... ok
Dec 02 16:12:33.048 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_bit_and_const_to_register ... ok
Dec 02 16:12:33.048 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_bit_or_const_to_register ... ok
Dec 02 16:12:33.048 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_bit_xor_const_to_register ... ok
Dec 02 16:12:33.049 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_const_to_register ... ok
Dec 02 16:12:33.049 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_div_constant_to_register ... ok
Dec 02 16:12:33.049 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_left_shift_const_to_register ... ok
Dec 02 16:12:33.049 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_logical_right_shift_const_to_register ... ok
Dec 02 16:12:33.049 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_mod_const_to_register ... ok
Dec 02 16:12:33.050 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_mul_const_to_register ... ok
Dec 02 16:12:33.050 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_negate_register ... ok
Dec 02 16:12:33.050 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_signed_right_shift_const_to_register ... ok
Dec 02 16:12:33.050 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::immediate::move_sub_const_to_register ... ok
Dec 02 16:12:33.050 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_and_add_from_register ... ok
Dec 02 16:12:33.051 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_bit_and_from_register_to_register ... ok
Dec 02 16:12:33.051 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_bit_or_from_register_to_register ... ok
Dec 02 16:12:33.051 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_bit_xor_from_register_to_register ... ok
Dec 02 16:12:33.051 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_div_from_register_to_register ... ok
Dec 02 16:12:33.051 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_from_register_to_another_register ... ok
Dec 02 16:12:33.052 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_left_shift_from_register_to_register ... ok
Dec 02 16:12:33.052 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_logical_right_shift_from_register_to_register ... ok
Dec 02 16:12:33.052 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_mod_from_register_to_register ... ok
Dec 02 16:12:33.052 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_mul_from_register_to_register ... ok
Dec 02 16:12:33.052 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_signed_right_shift_from_register_to_register ... ok
Dec 02 16:12:33.052 INFO [stdout] test insn_builder::tests::moves_instructions::arch_x64::register::move_sub_from_register_to_register ... ok
Dec 02 16:12:33.053 INFO [stdout] test insn_builder::tests::programs::example_from_assembler ... ok
Dec 02 16:12:33.053 INFO [stdout] test insn_builder::tests::store_instructions::store_byte_from_dst_into_immediate_address ... ok
Dec 02 16:12:33.053 INFO [stdout] test insn_builder::tests::store_instructions::store_byte_from_dst_into_src_address ... ok
Dec 02 16:12:33.053 INFO [stdout] test insn_builder::tests::special::call_immediate ... ok
Dec 02 16:12:33.053 INFO [stdout] test insn_builder::tests::store_instructions::store_double_word_from_dst_into_immediate_address ... ok
Dec 02 16:12:33.054 INFO [stdout] test insn_builder::tests::store_instructions::store_half_word_from_dst_into_immediate_address ... ok
Dec 02 16:12:33.054 INFO [stdout] test insn_builder::tests::store_instructions::store_double_word_from_dst_into_src_address ... ok
Dec 02 16:12:33.054 INFO [stdout] test insn_builder::tests::store_instructions::store_half_word_from_dst_into_src_address ... ok
Dec 02 16:12:33.054 INFO [stdout] test insn_builder::tests::store_instructions::store_word_from_dst_into_src_address ... ok
Dec 02 16:12:33.054 INFO [stdout] test insn_builder::tests::store_instructions::store_word_from_dst_into_immediate_address ... ok
Dec 02 16:12:33.059 INFO [stdout] test insn_builder::tests::special::exit_operation ... ok
Dec 02 16:12:33.059 INFO [stdout] 
Dec 02 16:12:33.059 INFO [stdout] test result: ok. 119 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.059 INFO [stdout] 
Dec 02 16:12:33.060 INFO [stderr]      Running /target/debug/deps/assembler-478df8bb894ecf04
Dec 02 16:12:33.068 INFO [stdout] 
Dec 02 16:12:33.068 INFO [stdout] running 32 tests
Dec 02 16:12:33.069 INFO [stdout] test test_call ... ok
Dec 02 16:12:33.070 INFO [stdout] test test_alu_unary ... ok
Dec 02 16:12:33.079 INFO [stdout] test test_add64 ... ok
Dec 02 16:12:33.079 INFO [stdout] test test_endian ... ok
Dec 02 16:12:33.079 INFO [stdout] test test_be32 ... ok
Dec 02 16:12:33.079 INFO [stdout] test test_empty ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_error_operands_out_of_range ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_error_too_many_operands ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_error_unexpected_operands ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_error_invalid_instruction ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_exit ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_jeq ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_alu_binary ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_ja ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_ldxdw ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_ldindw ... ok
Dec 02 16:12:33.091 INFO [stdout] test test_lddw ... ok
Dec 02 16:12:33.095 INFO [stdout] test test_ldabsw ... ok
Dec 02 16:12:33.095 INFO [stdout] test test_large_immediate ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_jump_conditional ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_neg64 ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_load_reg ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_load_ind ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_store_imm ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_ldxw ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_load_abs ... ok
Dec 02 16:12:33.107 INFO [stdout] test test_sth ... ok
Dec 02 16:12:33.115 INFO [stderr]      Running /target/debug/deps/common-ac0c3e0e5b0e2bf9
Dec 02 16:12:33.115 INFO [stderr]      Running /target/debug/deps/disassembler-47a3663a5997fa8f
Dec 02 16:12:33.115 INFO [stdout] test test_tcp_sack ... ok
Dec 02 16:12:33.115 INFO [stdout] test test_stxw ... ok
Dec 02 16:12:33.115 INFO [stdout] test test_stxh ... ok
Dec 02 16:12:33.115 INFO [stdout] test test_stw ... ok
Dec 02 16:12:33.115 INFO [stdout] test test_store_reg ... ok
Dec 02 16:12:33.115 INFO [stdout] 
Dec 02 16:12:33.115 INFO [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.115 INFO [stdout] 
Dec 02 16:12:33.115 INFO [stdout] 
Dec 02 16:12:33.115 INFO [stdout] running 0 tests
Dec 02 16:12:33.115 INFO [stdout] 
Dec 02 16:12:33.115 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.115 INFO [stdout] 
Dec 02 16:12:33.119 INFO [stdout] 
Dec 02 16:12:33.119 INFO [stdout] running 27 tests
Dec 02 16:12:33.119 INFO [stdout] test test_endian ... ok
Dec 02 16:12:33.119 INFO [stdout] test test_empty ... ok
Dec 02 16:12:33.131 INFO [stdout] test test_jeq ... ok
Dec 02 16:12:33.155 INFO [stdout] test test_call ... ok
Dec 02 16:12:33.164 INFO [stdout] test test_exit ... ok
Dec 02 16:12:33.164 INFO [stdout] test test_alu_unary ... ok
Dec 02 16:12:33.165 INFO [stdout] test test_add64 ... ok
Dec 02 16:12:33.165 INFO [stdout] test test_ja ... ok
Dec 02 16:12:33.166 INFO [stdout] test test_be32 ... ok
Dec 02 16:12:33.167 INFO [stdout] test test_lddw ... ok
Dec 02 16:12:33.167 INFO [stdout] test test_ldxw ... ok
Dec 02 16:12:33.171 INFO [stdout] test test_ldabsw ... ok
Dec 02 16:12:33.171 INFO [stdout] test test_load_abs ... ok
Dec 02 16:12:33.175 INFO [stdout] test test_large_immediate ... ok
Dec 02 16:12:33.175 INFO [stdout] test test_alu_binary ... ok
Dec 02 16:12:33.179 INFO [stdout] test test_ldindw ... ok
Dec 02 16:12:33.179 INFO [stdout] test test_jump_conditional ... ok
Dec 02 16:12:33.180 INFO [stdout] test test_ldxdw ... ok
Dec 02 16:12:33.183 INFO [stdout] test test_neg64 ... ok
Dec 02 16:12:33.183 INFO [stdout] test test_load_ind ... ok
Dec 02 16:12:33.183 INFO [stdout] test test_sth ... ok
Dec 02 16:12:33.183 INFO [stdout] test test_load_reg ... ok
Dec 02 16:12:33.191 INFO [stdout] test test_stw ... ok
Dec 02 16:12:33.191 INFO [stdout] test test_store_reg ... ok
Dec 02 16:12:33.191 INFO [stdout] test test_store_imm ... ok
Dec 02 16:12:33.191 INFO [stdout] test test_stxw ... ok
Dec 02 16:12:33.191 INFO [stdout] test test_stxh ... ok
Dec 02 16:12:33.191 INFO [stdout] 
Dec 02 16:12:33.191 INFO [stdout] test result: ok. 27 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.191 INFO [stdout] 
Dec 02 16:12:33.192 INFO [stderr]      Running /target/debug/deps/misc-06330d02f286b193
Dec 02 16:12:33.200 INFO [stdout] 
Dec 02 16:12:33.200 INFO [stdout] running 30 tests
Dec 02 16:12:33.201 INFO [stdout] test test_non_terminate_capped ... ok
Dec 02 16:12:33.203 INFO [stdout] test test_get_last_instruction_count ... ok
Dec 02 16:12:33.203 INFO [stdout] test test_load_elf ... ok
Dec 02 16:12:33.204 INFO [stdout] test test_jit_mbuff ... ok
Dec 02 16:12:33.204 INFO [stdout] test test_jit_block_port ... ok
Dec 02 16:12:33.205 INFO [stdout] test test_symbol_relocation ... ok
Dec 02 16:12:33.215 INFO [stdout] test test_verifier_success ... ok
Dec 02 16:12:33.215 INFO [stdout] test test_non_terminate_early ... ok
Dec 02 16:12:33.223 INFO [stdout] test test_vm_block_port ... ok
Dec 02 16:12:33.293 INFO [stdout] test test_load_elf_multiple_text ... ok
Dec 02 16:12:33.293 INFO [stdout] test test_unterminated_string ... ok
Dec 02 16:12:33.299 INFO [stdout] test test_non_terminating ... ok
Dec 02 16:12:33.299 INFO [stdout] test test_null_string ... ok
Dec 02 16:12:33.299 INFO [stdout] test test_verifier_fail ... ok
Dec 02 16:12:33.299 INFO [stdout] test test_jit_call_helper_wo_verifier ... ok
Dec 02 16:12:33.300 INFO [stdout] test test_vm_err_ldabsb_nomem ... ok
Dec 02 16:12:33.300 INFO [stdout] test test_vm_jit_ldabsdw ... ok
Dec 02 16:12:33.300 INFO [stdout] test test_vm_err_ldabsb_oob ... ok
Dec 02 16:12:33.300 INFO [stdout] test test_vm_jit_ldindb ... ok
Dec 02 16:12:33.300 INFO [stdout] test test_vm_jit_ldinddw ... ok
Dec 02 16:12:33.303 INFO [stdout] test test_vm_jit_ldabsb ... ok
Dec 02 16:12:33.323 INFO [stdout] test test_vm_err_ldindb_nomem ... ok
Dec 02 16:12:33.323 INFO [stdout] test test_vm_err_ldindb_oob ... ok
Dec 02 16:12:33.339 INFO [stdout] test test_vm_exec_no_program ... ok
Dec 02 16:12:33.339 INFO [stdout] test test_vm_jit_ldabsh ... ok
Dec 02 16:12:33.339 INFO [stdout] test test_vm_jit_ldabsw ... ok
Dec 02 16:12:33.339 INFO [stdout] test test_vm_mbuff_with_rust_api ... ok
Dec 02 16:12:33.347 INFO [stdout] test test_vm_jit_ldindh ... ok
Dec 02 16:12:33.347 INFO [stdout] test test_vm_mbuff ... ok
Dec 02 16:12:33.347 INFO [stdout] test test_vm_jit_ldindw ... ok
Dec 02 16:12:33.347 INFO [stdout] 
Dec 02 16:12:33.347 INFO [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.347 INFO [stdout] 
Dec 02 16:12:33.349 INFO [stderr]      Running /target/debug/deps/ubpf_jit_x86_64-0b713f16f75ac1ba
Dec 02 16:12:33.359 INFO [stdout] 
Dec 02 16:12:33.359 INFO [stdout] running 97 tests
Dec 02 16:12:33.380 INFO [stdout] test test_jit_alu64_arith ... ok
Dec 02 16:12:33.381 INFO [stdout] test test_jit_alu64_bit ... ok
Dec 02 16:12:33.381 INFO [stdout] test test_jit_add ... ok
Dec 02 16:12:33.381 INFO [stdout] test test_jit_alu_arith ... ok
Dec 02 16:12:33.381 INFO [stdout] test test_jit_alu_bit ... ok
Dec 02 16:12:33.382 INFO [stdout] test test_jit_be16 ... ok
Dec 02 16:12:33.392 INFO [stdout] test test_jit_arsh64 ... ok
Dec 02 16:12:33.393 INFO [stdout] test test_jit_arsh_reg ... ok
Dec 02 16:12:33.403 INFO [stdout] test test_jit_arsh32_high_shift ... ok
Dec 02 16:12:33.405 INFO [stdout] test test_jit_be32 ... ok
Dec 02 16:12:33.405 INFO [stdout] test test_jit_be16_high ... ok
Dec 02 16:12:33.406 INFO [stdout] test test_jit_call ... ok
Dec 02 16:12:33.407 INFO [stdout] test test_jit_be64 ... ok
Dec 02 16:12:33.407 INFO [stdout] test test_jit_arsh ... ok
Dec 02 16:12:33.408 INFO [stdout] test test_jit_call_memfrob ... ok
Dec 02 16:12:33.409 INFO [stdout] test test_jit_be32_high ... ok
Dec 02 16:12:33.409 INFO [stdout] test test_jit_div32_imm ... ok
Dec 02 16:12:33.410 INFO [stdout] test test_jit_div32_reg ... ok
Dec 02 16:12:33.410 INFO [stdout] test test_jit_div64_imm ... ok
Dec 02 16:12:33.411 INFO [stdout] test test_jit_div64_reg ... ok
Dec 02 16:12:33.412 INFO [stdout] test test_jit_early_exit ... ok
Dec 02 16:12:33.415 INFO [stdout] test test_jit_exit ... ok
Dec 02 16:12:33.416 INFO [stdout] test test_jit_div32_high_divisor ... ok
Dec 02 16:12:33.416 INFO [stdout] test test_jit_ja ... ok
Dec 02 16:12:33.417 INFO [stdout] test test_jit_jeq_imm ... ok
Dec 02 16:12:33.418 INFO [stdout] test test_jit_jeq_reg ... ok
Dec 02 16:12:33.419 INFO [stdout] test test_jit_jge_imm ... ok
Dec 02 16:12:33.420 INFO [stdout] test test_jit_jgt_imm ... ok
Dec 02 16:12:33.423 INFO [stdout] test test_jit_jgt_reg ... ok
Dec 02 16:12:33.424 INFO [stdout] test test_jit_jit_bounce ... ok
Dec 02 16:12:33.425 INFO [stdout] test test_jit_jle_imm ... ok
Dec 02 16:12:33.426 INFO [stdout] test test_jit_jle_reg ... ok
Dec 02 16:12:33.427 INFO [stdout] test test_jit_jlt_imm ... ok
Dec 02 16:12:33.427 INFO [stdout] test test_jit_jlt_reg ... ok
Dec 02 16:12:33.428 INFO [stdout] test test_jit_jne_reg ... ok
Dec 02 16:12:33.436 INFO [stdout] test test_jit_jset_imm ... ok
Dec 02 16:12:33.436 INFO [stdout] test test_jit_jset_reg ... ok
Dec 02 16:12:33.437 INFO [stdout] test test_jit_jsge_imm ... ok
Dec 02 16:12:33.438 INFO [stdout] test test_jit_jsge_reg ... ok
Dec 02 16:12:33.439 INFO [stdout] test test_jit_jsgt_imm ... ok
Dec 02 16:12:33.440 INFO [stdout] test test_jit_jsgt_reg ... ok
Dec 02 16:12:33.440 INFO [stdout] test test_jit_jsle_imm ... ok
Dec 02 16:12:33.442 INFO [stdout] test test_jit_jsle_reg ... ok
Dec 02 16:12:33.442 INFO [stdout] test test_jit_jslt_imm ... ok
Dec 02 16:12:33.451 INFO [stdout] test test_jit_jslt_reg ... ok
Dec 02 16:12:33.451 INFO [stdout] test test_jit_lddw ... ok
Dec 02 16:12:33.451 INFO [stdout] test test_jit_lddw2 ... ok
Dec 02 16:12:33.451 INFO [stdout] test test_jit_ldxb_all ... ok
Dec 02 16:12:33.451 INFO [stdout] test test_jit_ldxb ... ok
Dec 02 16:12:33.452 INFO [stdout] test test_jit_ldxh_all2 ... ok
Dec 02 16:12:33.459 INFO [stdout] test test_jit_ldxh ... ok
Dec 02 16:12:33.467 INFO [stdout] test test_jit_ldxdw ... ok
Dec 02 16:12:33.475 INFO [stdout] test test_jit_ldxw_all ... ok
Dec 02 16:12:33.475 INFO [stdout] test test_jit_le16 ... ok
Dec 02 16:12:33.476 INFO [stdout] test test_jit_mod ... ok
Dec 02 16:12:33.476 INFO [stdout] test test_jit_ldxw ... ok
Dec 02 16:12:33.477 INFO [stdout] test test_jit_ldxh_same_reg ... ok
Dec 02 16:12:33.479 INFO [stdout] test test_jit_ldxh_all ... ok
Dec 02 16:12:33.480 INFO [stdout] test test_jit_le32 ... ok
Dec 02 16:12:33.483 INFO [stdout] test test_jit_mod64 ... ok
Dec 02 16:12:33.484 INFO [stdout] test test_jit_mod32 ... ok
Dec 02 16:12:33.485 INFO [stdout] test test_jit_mul32_imm ... ok
Dec 02 16:12:33.485 INFO [stdout] test test_jit_mov ... ok
Dec 02 16:12:33.486 INFO [stdout] test test_jit_mul32_reg ... ok
Dec 02 16:12:33.488 INFO [stdout] test test_jit_le64 ... ok
Dec 02 16:12:33.489 INFO [stdout] test test_jit_neg64 ... ok
Dec 02 16:12:33.489 INFO [stdout] test test_jit_neg ... ok
Dec 02 16:12:33.490 INFO [stdout] test test_jit_lsh_reg ... ok
Dec 02 16:12:33.492 INFO [stdout] test test_jit_mul64_imm ... ok
Dec 02 16:12:33.492 INFO [stdout] test test_jit_mul32_reg_overflow ... ok
Dec 02 16:12:33.492 INFO [stdout] test test_jit_mul_loop ... ok
Dec 02 16:12:33.493 INFO [stdout] test test_jit_mul64_reg ... ok
Dec 02 16:12:33.508 INFO [stdout] test test_jit_stack2 ... ok
Dec 02 16:12:33.508 INFO [stdout] test test_jit_rsh_reg ... ok
Dec 02 16:12:33.509 INFO [stdout] test test_jit_stack ... ok
Dec 02 16:12:33.510 INFO [stdout] test test_jit_stb ... ok
Dec 02 16:12:33.511 INFO [stdout] test test_jit_stdw ... ok
Dec 02 16:12:33.511 INFO [stdout] test test_jit_rhs32 ... ok
Dec 02 16:12:33.512 INFO [stdout] test test_jit_prime ... ok
Dec 02 16:12:33.513 INFO [stdout] test test_jit_sth ... ok
Dec 02 16:12:33.514 INFO [stdout] test test_jit_string_stack ... ok
Dec 02 16:12:33.515 INFO [stdout] test test_jit_stw ... ok
Dec 02 16:12:33.516 INFO [stdout] test test_jit_stxb ... ok
Dec 02 16:12:33.518 INFO [stdout] test test_jit_err_call_unreg ... ok
Dec 02 16:12:33.519 INFO [stdout] test test_jit_stxb_all ... ok
Dec 02 16:12:33.520 INFO [stdout] test test_jit_stxb_all2 ... ok
Dec 02 16:12:33.523 INFO [stdout] test test_jit_tcp_port80_match ... ok
Dec 02 16:12:33.523 INFO [stdout] test test_jit_tcp_port80_nomatch_proto ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_tcp_sack_match ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_stxb_chain ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_stxdw ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_stxh ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_stxw ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_subnet ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_tcp_port80_nomatch ... ok
Dec 02 16:12:33.547 INFO [stdout] test test_jit_tcp_port80_nomatch_ethertype ... ok
Dec 02 16:12:33.553 INFO [stdout] test test_jit_tcp_sack_nomatch ... ok
Dec 02 16:12:33.553 INFO [stdout] 
Dec 02 16:12:33.553 INFO [stdout] test result: ok. 97 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.553 INFO [stdout] 
Dec 02 16:12:33.555 INFO [stderr]      Running /target/debug/deps/ubpf_verifier-b4688b700bf75989
Dec 02 16:12:33.557 INFO [stdout] 
Dec 02 16:12:33.557 INFO [stdout] running 12 tests
Dec 02 16:12:33.617 INFO [stdout] test test_verifier_err_jmp_out ... ok
Dec 02 16:12:33.617 INFO [stdout] test test_verifier_err_jmp_lddw ... ok
Dec 02 16:12:33.617 INFO [stdout] test test_verifier_err_invalid_reg_src ... ok
Dec 02 16:12:33.618 INFO [stdout] test test_verifier_err_infinite_loop ... ok
Dec 02 16:12:33.618 INFO [stdout] test test_verifier_err_incomplete_lddw ... ok
Dec 02 16:12:33.618 INFO [stdout] test test_verifier_err_endian_size ... ok
Dec 02 16:12:33.618 INFO [stdout] test test_verifier_err_div_by_zero_imm ... ok
Dec 02 16:12:33.619 INFO [stdout] test test_verifier_err_unknown_opcode ... ok
Dec 02 16:12:33.619 INFO [stdout] test test_verifier_err_no_exit ... ok
Dec 02 16:12:33.631 INFO [stdout] test test_verifier_err_invalid_reg_dst ... ok
Dec 02 16:12:33.639 INFO [stdout] test test_verifier_err_write_r10 ... ok
Dec 02 16:12:33.639 INFO [stdout] test test_verifier_err_too_many_instructions ... ok
Dec 02 16:12:33.639 INFO [stdout] 
Dec 02 16:12:33.639 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.639 INFO [stdout] 
Dec 02 16:12:33.640 INFO [stderr]      Running /target/debug/deps/ubpf_vm-412b508411f26db5
Dec 02 16:12:33.642 INFO [stdout] 
Dec 02 16:12:33.642 INFO [stdout] running 102 tests
Dec 02 16:12:33.652 INFO [stdout] test test_vm_alu_arith ... ok
Dec 02 16:12:33.653 INFO [stdout] test test_vm_arsh_reg ... ok
Dec 02 16:12:33.653 INFO [stdout] test test_vm_be16 ... ok
Dec 02 16:12:33.654 INFO [stdout] test test_vm_be16_high ... ok
Dec 02 16:12:33.655 INFO [stdout] test test_vm_be32 ... ok
Dec 02 16:12:33.665 INFO [stdout] test test_vm_arsh ... ok
Dec 02 16:12:33.665 INFO [stdout] test test_vm_alu64_arith ... ok
Dec 02 16:12:33.665 INFO [stdout] test test_vm_alu_bit ... ok
Dec 02 16:12:33.666 INFO [stdout] test test_vm_arsh32_high_shift ... ok
Dec 02 16:12:33.666 INFO [stdout] test test_vm_alu64_bit ... ok
Dec 02 16:12:33.667 INFO [stdout] test test_vm_be64 ... ok
Dec 02 16:12:33.669 INFO [stdout] test test_vm_arsh64 ... ok
Dec 02 16:12:33.669 INFO [stdout] test test_vm_be32_high ... ok
Dec 02 16:12:33.669 INFO [stdout] test test_vm_add ... ok
Dec 02 16:12:33.669 INFO [stdout] test test_vm_div32_reg ... ok
Dec 02 16:12:33.675 INFO [stdout] test test_vm_div64_imm ... ok
Dec 02 16:12:33.675 INFO [stdout] test test_vm_call ... ok
Dec 02 16:12:33.679 INFO [stdout] test test_vm_early_exit ... ok
Dec 02 16:12:33.680 INFO [stdout] test test_vm_call_memfrob ... ok
Dec 02 16:12:33.681 INFO [stdout] test test_vm_div32_imm ... ok
Dec 02 16:12:33.681 INFO [stdout] test test_vm_div32_high_divisor ... ok
Dec 02 16:12:33.685 INFO [stdout] test test_vm_div64_reg ... ok
Dec 02 16:12:33.719 INFO [stdout] test test_vm_exit ... ok
Dec 02 16:12:33.719 INFO [stdout] test test_vm_ja ... ok
Dec 02 16:12:33.719 INFO [stdout] test test_vm_jeq_imm ... ok
Dec 02 16:12:33.719 INFO [stdout] test test_vm_jeq_reg ... ok
Dec 02 16:12:33.723 INFO [stdout] test test_vm_jge_imm ... ok
Dec 02 16:12:33.727 INFO [stdout] test test_vm_jgt_imm ... ok
Dec 02 16:12:33.727 INFO [stdout] test test_vm_jit_bounce ... ok
Dec 02 16:12:33.727 INFO [stdout] test test_vm_jgt_reg ... ok
Dec 02 16:12:33.731 INFO [stdout] test test_vm_jle_imm ... ok
Dec 02 16:12:33.739 INFO [stdout] test test_vm_jle_reg ... ok
Dec 02 16:12:33.739 INFO [stdout] test test_vm_jlt_imm ... ok
Dec 02 16:12:33.751 INFO [stdout] test test_vm_jlt_reg ... ok
Dec 02 16:12:33.751 INFO [stdout] test test_vm_jne_reg ... ok
Dec 02 16:12:33.752 INFO [stdout] test test_vm_jset_imm ... ok
Dec 02 16:12:33.755 INFO [stdout] test test_vm_jset_reg ... ok
Dec 02 16:12:33.757 INFO [stdout] test test_vm_jsge_imm ... ok
Dec 02 16:12:33.757 INFO [stdout] test test_vm_jsge_reg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_jsgt_reg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_err_call_unreg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_jsgt_imm ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_err_div64_by_zero_reg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_err_mod64_by_zero_reg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_err_div_by_zero_reg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_jslt_imm ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_lddw2 ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_jsle_reg ... ok
Dec 02 16:12:33.762 INFO [stdout] test test_vm_jslt_reg ... ok
Dec 02 16:12:33.763 INFO [stdout] test test_vm_err_mod_by_zero_reg ... ok
Dec 02 16:12:33.771 INFO [stdout] test test_vm_ldxb ... ok
Dec 02 16:12:33.775 INFO [stdout] test test_vm_err_stack_out_of_bound ... ok
Dec 02 16:12:33.775 INFO [stdout] test test_vm_lddw ... ok
Dec 02 16:12:33.775 INFO [stdout] test test_vm_jsle_imm ... ok
Dec 02 16:12:33.775 INFO [stdout] test test_vm_ldxh ... ok
Dec 02 16:12:33.775 INFO [stdout] test test_vm_ldxb_all ... ok
Dec 02 16:12:33.775 INFO [stdout] test test_vm_ldxdw ... ok
Dec 02 16:12:33.799 INFO [stdout] test test_vm_ldxh_all2 ... ok
Dec 02 16:12:33.799 INFO [stdout] test test_vm_ldxh_same_reg ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_ldxw ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_le32 ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_le64 ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_le16 ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_lsh_reg ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_ldxw_all ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_ldxh_all ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_mul32_imm ... ok
Dec 02 16:12:33.802 INFO [stdout] test test_vm_mul64_imm ... ok
Dec 02 16:12:33.803 INFO [stdout] test test_vm_mov ... ok
Dec 02 16:12:33.803 INFO [stdout] test test_vm_mul64_reg ... ok
Dec 02 16:12:33.803 INFO [stdout] test test_vm_mul32_reg ... ok
Dec 02 16:12:33.803 INFO [stdout] test test_vm_mod64 ... ok
Dec 02 16:12:33.811 INFO [stdout] test test_vm_mod32 ... ok
Dec 02 16:12:33.811 INFO [stdout] test test_vm_mod ... ok
Dec 02 16:12:33.823 INFO [stdout] test test_vm_rhs32 ... ok
Dec 02 16:12:33.823 INFO [stdout] test test_vm_neg ... ok
Dec 02 16:12:33.832 INFO [stdout] test test_vm_mul_loop ... ok
Dec 02 16:12:33.832 INFO [stdout] test test_vm_stack ... ok
Dec 02 16:12:33.832 INFO [stdout] test test_vm_rsh_reg ... ok
Dec 02 16:12:33.832 INFO [stdout] test test_vm_mul32_reg_overflow ... ok
Dec 02 16:12:33.832 INFO [stdout] test test_vm_stb ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_string_stack ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_sth ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_stdw ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_stw ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_neg64 ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_stxb_all ... ok
Dec 02 16:12:33.843 INFO [stdout] test test_vm_stxb_all2 ... ok
Dec 02 16:12:33.851 INFO [stdout] test test_vm_stack2 ... ok
Dec 02 16:12:33.859 INFO [stdout] test test_vm_stxw ... ok
Dec 02 16:12:33.859 INFO [stdout] test test_vm_stxdw ... ok
Dec 02 16:12:33.859 INFO [stdout] test test_vm_stxh ... ok
Dec 02 16:12:33.859 INFO [stdout] test test_vm_tcp_port80_match ... ok
Dec 02 16:12:33.863 INFO [stdout] test test_vm_subnet ... ok
Dec 02 16:12:33.867 INFO [stdout] test test_vm_stxb ... ok
Dec 02 16:12:33.867 INFO [stdout] test test_vm_prime ... ok
Dec 02 16:12:33.867 INFO [stdout] test test_vm_tcp_port80_nomatch ... ok
Dec 02 16:12:33.867 INFO [stdout] test test_vm_tcp_sack_match ... ok
Dec 02 16:12:33.867 INFO [stdout] test test_vm_tcp_port80_nomatch_ethertype ... ok
Dec 02 16:12:33.867 INFO [stdout] test test_vm_tcp_port80_nomatch_proto ... ok
Dec 02 16:12:33.875 INFO [stdout] test test_vm_stxb_chain ... ok
Dec 02 16:12:33.875 INFO [stdout] test test_vm_tcp_sack_nomatch ... ok
Dec 02 16:12:33.875 INFO [stdout] 
Dec 02 16:12:33.875 INFO [stdout] test result: ok. 102 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:12:33.875 INFO [stdout] 
Dec 02 16:12:33.887 INFO [stderr]    Doc-tests solana_rbpf
Dec 02 16:12:38.443 INFO [stdout] 
Dec 02 16:12:38.443 INFO [stdout] running 55 tests
Dec 02 16:13:08.831 INFO [stdout] test src/assembler.rs - assembler::assemble (line 210) ... ok
Dec 02 16:13:09.671 INFO [stdout] test src/disassembler.rs - disassembler::disassemble (line 323) ... ok
Dec 02 16:13:09.943 INFO [stdout] test src/ebpf.rs - ebpf::get_insn (line 507) ... ok
Dec 02 16:13:10.695 INFO [stdout] test src/ebpf.rs - ebpf::get_insn (line 520) ... ok
Dec 02 16:13:11.159 INFO [stdout] test src/ebpf.rs - ebpf::Insn::to_vec (line 468) ... ok
Dec 02 16:13:11.410 INFO [stdout] test src/ebpf.rs - ebpf::to_insn_vec (line 556) ... ok
Dec 02 16:13:11.532 INFO [stdout] test src/ebpf.rs - ebpf::Insn::to_array (line 436) ... ok
Dec 02 16:13:11.868 INFO [stdout] test src/disassembler.rs - disassembler::to_insn_vec (line 122) ... ok
Dec 02 16:13:26.743 INFO [stdout] test src/helpers.rs - helpers::bpf_time_getns (line 41) ... ok
Dec 02 16:13:28.103 INFO [stdout] test src/helpers.rs - helpers::bpf_trace_printf (line 72) ... ok
Dec 02 16:13:28.443 INFO [stdout] test src/helpers.rs - helpers::strcmp (line 206) ... ok
Dec 02 16:13:29.773 INFO [stdout] test src/helpers.rs - helpers::gather_bytes (line 122) ... ok
Dec 02 16:13:30.505 INFO [stdout] test src/helpers.rs - helpers::rand (line 251) ... ok
Dec 02 16:13:31.127 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff (line 881) ... ok
Dec 02 16:13:31.384 INFO [stdout] test src/helpers.rs - helpers::sqrti (line 190) ... ok
Dec 02 16:13:32.179 INFO [stdout] test src/helpers.rs - helpers::memfrob (line 142) ... ok
Dec 02 16:13:47.657 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::register_helper (line 1091) ... ok
Dec 02 16:13:47.767 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::execute_program_jit (line 1262) ... ok
Dec 02 16:13:47.890 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::execute_program (line 1168) ... ok
Dec 02 16:13:48.431 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::jit_compile (line 1208) ... ok
Dec 02 16:13:49.063 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::get_last_instruction_count (line 1058) ... ok
Dec 02 16:13:49.087 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::set_max_instruction_count (line 1036) ... ok
Dec 02 16:13:49.731 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::new (line 926) ... ok
Dec 02 16:13:51.475 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::set_program (line 962) ... ok
Dec 02 16:14:03.919 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::execute_program_jit (line 794) ... ok
Dec 02 16:14:06.008 INFO [stdout] test src/lib.rs - EbpfVmFixedMbuff<'a>::set_verifier (line 1003) ... ok
Dec 02 16:14:07.983 INFO [stdout] test src/lib.rs - EbpfVmMbuff (line 73) ... ok
Dec 02 16:14:08.095 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::execute_program (line 359) ... ok
Dec 02 16:14:08.541 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::get_last_instruction_count (line 244) ... ok
Dec 02 16:14:08.883 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::jit_compile (line 744) ... ok
Dec 02 16:14:09.038 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::register_helper (line 287) ... ok
Dec 02 16:14:10.155 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::new (line 117) ... ok
Dec 02 16:14:27.023 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::set_max_instruction_count (line 221) ... ok
Dec 02 16:14:28.991 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::set_program (line 147) ... ok
Dec 02 16:14:30.863 INFO [stdout] test src/lib.rs - EbpfVmMbuff<'a>::set_verifier (line 182) ... ok
Dec 02 16:14:32.114 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::execute_program (line 1940) ... ok
Dec 02 16:14:32.375 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::jit_compile (line 1920) ... ok
Dec 02 16:14:32.595 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::execute_program_jit (line 1971) ... ok
Dec 02 16:14:32.951 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::get_last_instruction_count (line 1828) ... ok
Dec 02 16:14:33.870 INFO [stdout] test src/lib.rs - EbpfVmNoData (line 1669) ... ok
Dec 02 16:14:45.732 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::new (line 1714) ... ok
Dec 02 16:14:46.871 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::register_helper (line 1857) ... ok
Dec 02 16:14:47.599 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::set_max_instruction_count (line 1806) ... ok
Dec 02 16:14:49.354 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::set_program (line 1735) ... ok
Dec 02 16:14:49.411 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::execute_program_jit (line 1635) ... ok
Dec 02 16:14:49.495 INFO [stdout] test src/lib.rs - EbpfVmNoData<'a>::set_verifier (line 1773) ... ok
Dec 02 16:14:49.569 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::execute_program (line 1562) ... ok
Dec 02 16:14:49.766 INFO [stdout] test src/lib.rs - EbpfVmRaw (line 1319) ... ok
Dec 02 16:15:00.211 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::get_last_instruction_count (line 1464) ... ok
Dec 02 16:15:02.503 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::jit_compile (line 1590) ... ok
Dec 02 16:15:02.975 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::new (line 1348) ... ok
Dec 02 16:15:04.298 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::set_program (line 1370) ... ok
Dec 02 16:15:04.301 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::set_verifier (line 1409) ... ok
Dec 02 16:15:04.383 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::set_max_instruction_count (line 1442) ... ok
Dec 02 16:15:04.748 INFO [stdout] test src/lib.rs - EbpfVmRaw<'a>::register_helper (line 1497) ... ok
Dec 02 16:15:04.748 INFO [stdout] 
Dec 02 16:15:04.749 INFO [stdout] test result: ok. 55 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 16:15:04.749 INFO [stdout] 
Dec 02 16:15:04.765 INFO [stderr] su: No module specific data is present
Dec 02 16:15:05.732 INFO running `"docker" "inspect" "3bbac694b4217ad9ea3dc4d10156245bee93e607da29f1b9e8224bb25c5c8e8d"`
Dec 02 16:15:05.949 INFO running `"docker" "rm" "-f" "3bbac694b4217ad9ea3dc4d10156245bee93e607da29f1b9e8224bb25c5c8e8d"`
Dec 02 16:15:06.173 INFO [stdout] 3bbac694b4217ad9ea3dc4d10156245bee93e607da29f1b9e8224bb25c5c8e8d
