Nov 18 16:29:00.236 INFO testing datafusion-arrow-0.1.0-nightly-20180521 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 16:29:00.236 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 16:29:00.573 INFO [stdout] 94e1f3259fcae98a3f667543e26bc9bd4486e0dc2dec1c6c732e28a9435aaf72
Nov 18 16:29:00.579 INFO running `"docker" "start" "-a" "94e1f3259fcae98a3f667543e26bc9bd4486e0dc2dec1c6c732e28a9435aaf72"`
Nov 18 16:29:01.177 INFO [stderr] usermod: no changes
Nov 18 16:29:01.251 INFO [stderr]    Compiling datafusion-arrow v0.1.0-nightly-20180521 (/source)
Nov 18 16:29:03.355 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.16s
Nov 18 16:29:03.360 INFO [stderr] su: No module specific data is present
Nov 18 16:29:04.047 INFO running `"docker" "inspect" "94e1f3259fcae98a3f667543e26bc9bd4486e0dc2dec1c6c732e28a9435aaf72"`
Nov 18 16:29:04.347 INFO running `"docker" "rm" "-f" "94e1f3259fcae98a3f667543e26bc9bd4486e0dc2dec1c6c732e28a9435aaf72"`
Nov 18 16:29:04.647 INFO [stdout] 94e1f3259fcae98a3f667543e26bc9bd4486e0dc2dec1c6c732e28a9435aaf72
Nov 18 16:29:04.655 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 16:29:05.097 INFO [stdout] 2fe5f20d735f1a582f08d3884bca0363ce972aa65acbad67170a38b23617b741
Nov 18 16:29:05.098 INFO running `"docker" "start" "-a" "2fe5f20d735f1a582f08d3884bca0363ce972aa65acbad67170a38b23617b741"`
Nov 18 16:29:06.127 INFO [stderr] usermod: no changes
Nov 18 16:29:06.173 INFO [stderr]    Compiling datafusion-arrow v0.1.0-nightly-20180521 (/source)
Nov 18 16:29:09.963 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 3.81s
Nov 18 16:29:09.967 INFO [stderr] su: No module specific data is present
Nov 18 16:29:11.243 INFO running `"docker" "inspect" "2fe5f20d735f1a582f08d3884bca0363ce972aa65acbad67170a38b23617b741"`
Nov 18 16:29:11.517 INFO running `"docker" "rm" "-f" "2fe5f20d735f1a582f08d3884bca0363ce972aa65acbad67170a38b23617b741"`
Nov 18 16:29:11.987 INFO [stdout] 2fe5f20d735f1a582f08d3884bca0363ce972aa65acbad67170a38b23617b741
Nov 18 16:29:11.994 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 18 16:29:12.517 INFO [stdout] 4bb4a8b55b385b2e4347f6ee046cde3b8428de5b80bc4c2cfce6f62439dce396
Nov 18 16:29:12.519 INFO running `"docker" "start" "-a" "4bb4a8b55b385b2e4347f6ee046cde3b8428de5b80bc4c2cfce6f62439dce396"`
Nov 18 16:29:13.809 INFO [stderr] usermod: no changes
Nov 18 16:29:13.942 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Nov 18 16:29:13.944 INFO [stderr]      Running /target/debug/deps/arrow-22a2e7e60cfd8e0e
Nov 18 16:29:13.949 INFO [stdout] 
Nov 18 16:29:13.949 INFO [stdout] running 51 tests
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_from_f32 ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_from_empty_vec ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_from_bool ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_struct ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::array_from_list_u8 ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_buffer_array_min_max ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::array_data_from_list_u8 ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_from_optional_i32 ... ok
Nov 18 16:29:13.949 INFO [stdout] test array::tests::test_from_i32 ... ok
Nov 18 16:29:13.959 INFO [stdout] test bitmap::tests::test_bitmap_length ... ok
Nov 18 16:29:13.959 INFO [stdout] test bitmap::tests::test_set_clear_bit ... ok
Nov 18 16:29:13.959 INFO [stdout] test buffer::tests::slice_empty_at_end ... ok
Nov 18 16:29:13.959 INFO [stdout] test array::tests::test_buffer_array_min_max_with_nulls ... ok
Nov 18 16:29:13.959 INFO [stdout] test buffer::tests::test_buffer_add ... ok
Nov 18 16:29:13.964 INFO [stdout] test buffer::tests::test_buffer_multiply ... ok
Nov 18 16:29:13.964 INFO [stdout] test buffer::tests::test_buffer_eq ... ok
Nov 18 16:29:13.964 INFO [stdout] test buffer::tests::test_buffer_i32 ... ok
Nov 18 16:29:13.964 INFO [stdout] test buffer::tests::test_buffer_gt ... ok
Nov 18 16:29:13.964 INFO [stdout] test buffer::tests::test_buffer_lt ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_builder_i32_alloc_zero_bytes ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_builder_i32 ... ok
Nov 18 16:29:13.979 INFO [stdout] test buffer::tests::test_iterator_i32 ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_builder_i32_empty ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_slice_empty_at_end ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_push_slice ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_reserve ... ok
Nov 18 16:29:13.979 INFO [stdout] test builder::tests::test_builder_i32_grow_buffer ... ok
Nov 18 16:29:13.979 INFO [stdout] test datatypes::tests::create_schema_string ... ok
Nov 18 16:29:13.979 INFO [stdout] test datatypes::tests::create_struct_type ... ok
Nov 18 16:29:13.991 INFO [stdout] test datatypes::tests::parse_int32_from_json ... ok
Nov 18 16:29:13.995 INFO [stdout] test datatypes::tests::parse_struct_from_json ... ok
Nov 18 16:29:14.011 INFO [stdout] test datatypes::tests::parse_utf8_from_json ... ok
Nov 18 16:29:14.011 INFO [stdout] test datatypes::tests::primitive_field_to_json ... ok
Nov 18 16:29:14.011 INFO [stdout] test datatypes::tests::schema_field_accessors ... ok
Nov 18 16:29:14.015 INFO [stdout] test datatypes::tests::struct_field_to_json ... ok
Nov 18 16:29:14.015 INFO [stdout] test list::tests::test_utf8_empty_strings ... ok
Nov 18 16:29:14.015 INFO [stdout] test list::tests::test_utf8_slices ... ok
Nov 18 16:29:14.015 INFO [stdout] test list_builder::tests::test_empty_lists ... ok
Nov 18 16:29:14.015 INFO [stdout] test list_builder::tests::test_list_u8_default_capacity ... ok
Nov 18 16:29:14.015 INFO [stdout] test list_builder::tests::test_list_u8_zero_capacity ... ok
Nov 18 16:29:14.015 INFO [stdout] test memory::tests::test_allocate ... ok
Nov 18 16:29:14.015 INFO [stdout] test memory_pool::tests::test_allocate ... ok
Nov 18 16:29:14.015 INFO [stdout] test memory_pool::tests::test_reallocate ... ok
Nov 18 16:29:14.015 INFO [stdout] test record_batch::tests::create_record_batch ... ok
Nov 18 16:29:14.026 INFO [stdout] test buffer::tests::slice_start_out_of_bounds ... ok
Nov 18 16:29:14.026 INFO [stdout] test buffer::tests::test_get_out_of_bounds ... ok
Nov 18 16:29:14.026 INFO [stdout] test buffer::tests::slice_end_out_of_bounds ... ok
Nov 18 16:29:14.030 INFO [stdout] test buffer::tests::slice_end_before_start ... ok
Nov 18 16:29:14.030 INFO [stderr]    Doc-tests arrow
Nov 18 16:29:14.030 INFO [stdout] test builder::tests::test_slice_start_out_of_bounds ... ok
Nov 18 16:29:14.030 INFO [stdout] test builder::tests::test_slice_end_before_start ... ok
Nov 18 16:29:14.030 INFO [stdout] test builder::tests::test_slice_end_out_of_bounds ... ok
Nov 18 16:29:14.030 INFO [stdout] 
Nov 18 16:29:14.030 INFO [stdout] test result: ok. 51 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:29:14.030 INFO [stdout] 
Nov 18 16:29:15.703 INFO [stdout] 
Nov 18 16:29:15.703 INFO [stdout] running 0 tests
Nov 18 16:29:15.703 INFO [stdout] 
Nov 18 16:29:15.703 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 16:29:15.703 INFO [stdout] 
Nov 18 16:29:15.709 INFO [stderr] su: No module specific data is present
Nov 18 16:29:16.673 INFO running `"docker" "inspect" "4bb4a8b55b385b2e4347f6ee046cde3b8428de5b80bc4c2cfce6f62439dce396"`
Nov 18 16:29:17.079 INFO running `"docker" "rm" "-f" "4bb4a8b55b385b2e4347f6ee046cde3b8428de5b80bc4c2cfce6f62439dce396"`
Nov 18 16:29:17.299 INFO [stdout] 4bb4a8b55b385b2e4347f6ee046cde3b8428de5b80bc4c2cfce6f62439dce396