Nov 03 00:31:24.145 INFO testing check_timed_logs_fast-0.1.0 against 1.30.0 for beta-1.31-1
Nov 03 00:31:24.146 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/1.30.0:/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.0 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"`
Nov 03 00:31:24.628 INFO blam! 9409b4c84c810f30ad4647a1c5f9c7e0c582239eed7c38bc06a6b43d945bb807
Nov 03 00:31:24.631 INFO running `"docker" "start" "-a" "9409b4c84c810f30ad4647a1c5f9c7e0c582239eed7c38bc06a6b43d945bb807"`
Nov 03 00:31:25.891 INFO kablam! usermod: no changes
Nov 03 00:31:26.059 INFO kablam!    Compiling check_timed_logs_fast v0.1.0 (/source)
Nov 03 00:31:28.631 INFO kablam! warning: unused variable: `err`
Nov 03 00:31:28.631 INFO kablam!    --> src/lib.rs:146:16
Nov 03 00:31:28.631 INFO kablam!     |
Nov 03 00:31:28.631 INFO kablam! 146 |           Err((err, matches_in_file)) => {
Nov 03 00:31:28.631 INFO kablam!     |                ^^^ help: consider using `_err` instead
Nov 03 00:31:28.631 INFO kablam!     |
Nov 03 00:31:28.631 INFO kablam!     = note: #[warn(unused_variables)] on by default
Nov 03 00:31:28.631 INFO kablam! 
Nov 03 00:31:33.267 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 7.35s
Nov 03 00:31:33.267 INFO kablam! su: No module specific data is present
Nov 03 00:31:34.160 INFO running `"docker" "rm" "-f" "9409b4c84c810f30ad4647a1c5f9c7e0c582239eed7c38bc06a6b43d945bb807"`
Nov 03 00:31:34.452 INFO blam! 9409b4c84c810f30ad4647a1c5f9c7e0c582239eed7c38bc06a6b43d945bb807
Nov 03 00:31:34.463 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/1.30.0:/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.0 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"`
Nov 03 00:31:34.888 INFO blam! c6dacacfea3eb13eabcf98fe69c487323b6e7e9475c2aae658f5d08b254c9438
Nov 03 00:31:34.892 INFO running `"docker" "start" "-a" "c6dacacfea3eb13eabcf98fe69c487323b6e7e9475c2aae658f5d08b254c9438"`
Nov 03 00:31:35.786 INFO kablam! usermod: no changes
Nov 03 00:31:35.927 INFO kablam!    Compiling check_timed_logs_fast v0.1.0 (/source)
Nov 03 00:31:39.339 INFO kablam! warning: unused variable: `err`
Nov 03 00:31:39.340 INFO kablam!    --> src/lib.rs:146:16
Nov 03 00:31:39.341 INFO kablam!     |
Nov 03 00:31:39.341 INFO kablam! 146 |           Err((err, matches_in_file)) => {
Nov 03 00:31:39.342 INFO kablam!     |                ^^^ help: consider using `_err` instead
Nov 03 00:31:39.343 INFO kablam!     |
Nov 03 00:31:39.343 INFO kablam!     = note: #[warn(unused_variables)] on by default
Nov 03 00:31:39.344 INFO kablam! 
Nov 03 00:31:41.301 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 5.48s
Nov 03 00:31:41.316 INFO kablam! su: No module specific data is present
Nov 03 00:31:42.887 INFO running `"docker" "rm" "-f" "c6dacacfea3eb13eabcf98fe69c487323b6e7e9475c2aae658f5d08b254c9438"`
Nov 03 00:31:43.187 INFO blam! c6dacacfea3eb13eabcf98fe69c487323b6e7e9475c2aae658f5d08b254c9438
Nov 03 00:31:43.191 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/1.30.0:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/1.30.0:/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.0 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"`
Nov 03 00:31:43.739 INFO blam! 6a0807009d7400e36a88c3a769ac61decb5aa1fbc6dc40a3f44007a10112d175
Nov 03 00:31:43.739 INFO running `"docker" "start" "-a" "6a0807009d7400e36a88c3a769ac61decb5aa1fbc6dc40a3f44007a10112d175"`
Nov 03 00:31:44.907 INFO kablam! usermod: no changes
Nov 03 00:31:45.039 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Nov 03 00:31:45.043 INFO kablam!      Running /target/debug/deps/check_timed_logs_fast-e27ffc2a89ee9eac
Nov 03 00:31:45.055 INFO blam! 
Nov 03 00:31:45.055 INFO blam! running 17 tests
Nov 03 00:31:45.067 INFO blam! test tests::should_abort_when_stdin_used_as_logfile ... ok
Nov 03 00:31:45.067 INFO blam! test tests::should_handle_files_with_lines_without_dates ... ok
Nov 03 00:31:45.073 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_utc ... ok
Nov 03 00:31:45.076 INFO blam! test tests::should_handle_utf8_file_content_correctly ... ok
Nov 03 00:31:45.095 INFO blam! test tests::should_not_prepend_year_if_already_present ... ok
Nov 03 00:31:45.095 INFO blam! test tests::should_handle_empty_file_correctly ... ok
Nov 03 00:31:45.095 INFO blam! test tests::should_ignore_trailing_comma ... ok
Nov 03 00:31:45.095 INFO blam! test tests::should_handle_non_default_date_format ... ok
Nov 03 00:31:45.095 INFO blam! test tests::should_handle_file_age_correctly ... ok
Nov 03 00:31:45.100 INFO blam! test tests::should_prepend_current_year ... ok
Nov 03 00:31:45.118 INFO blam! test tests::should_handle_non_default_date_format_and_trailing_comma_and_different_timeposition ... ok
Nov 03 00:31:45.125 INFO blam! test tests::should_handle_timeposition ... ok
Nov 03 00:31:45.131 INFO blam! test tests::should_search_matching_files ... ok
Nov 03 00:31:45.131 INFO blam! test tests::should_skip_old_files ... ok
Nov 03 00:31:45.131 INFO blam! test tests::should_search_in_file ... ok
Nov 03 00:31:45.196 INFO blam! test tests::should_skip_binary_files ... FAILED
Nov 03 00:31:45.196 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 03 00:31:45.196 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz ... FAILED
Nov 03 00:31:45.196 INFO blam! 
Nov 03 00:31:45.196 INFO blam! failures:
Nov 03 00:31:45.196 INFO blam! 
Nov 03 00:31:45.196 INFO blam! ---- tests::should_skip_binary_files stdout ----
Nov 03 00:31:45.196 INFO blam! looking for files matching ./fixtures/1x1.png*
Nov 03 00:31:45.196 INFO blam! oldest allowed date in utc: 2018-11-03 00:30:45 and with tz offset: 2018-11-03 00:30:45
Nov 03 00:31:45.196 INFO blam! found file fixtures/1x1.png is 20 seconds old
Nov 03 00:31:45.196 INFO blam! skipping file because not utf8 parseable!
Nov 03 00:31:45.196 INFO blam! ERROR while searching the file fixtures/1x1.png: file not utf8
Nov 03 00:31:45.196 INFO blam!                       There were 0 matches until the error appeared.
Nov 03 00:31:45.196 INFO blam! thread 'tests::should_skip_binary_files' panicked at 'assertion failed: `(left == right)`
Nov 03 00:31:45.196 INFO blam!   left: `Ok((0, 1))`,
Nov 03 00:31:45.196 INFO blam!  right: `Ok((0, 0))`', src/lib.rs:588:5
Nov 03 00:31:45.196 INFO blam! stack backtrace:
Nov 03 00:31:45.196 INFO blam!    0:     0x5596c2cfe59e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 03 00:31:45.196 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 03 00:31:45.196 INFO blam!    1:     0x5596c2cf09c6 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 03 00:31:45.196 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 03 00:31:45.196 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 03 00:31:45.196 INFO blam!    2:     0x5596c2ceed9d - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 03 00:31:45.196 INFO blam!                                at libstd/panicking.rs:211
Nov 03 00:31:45.196 INFO blam!    3:     0x5596c2ceeaab - std::panicking::default_hook::h3a27b204a313406b
Nov 03 00:31:45.196 INFO blam!                                at libstd/panicking.rs:221
Nov 03 00:31:45.196 INFO blam!    4:     0x5596c2cef44c - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 03 00:31:45.196 INFO blam!                                at libstd/panicking.rs:477
Nov 03 00:31:45.196 INFO blam!    5:     0x5596c2cef009 - std::panicking::continue_panic_fmt::h141671b29fe0e27d
Nov 03 00:31:45.196 INFO blam!                                at libstd/panicking.rs:391
Nov 03 00:31:45.196 INFO blam!    6:     0x5596c2ceef6d - std::panicking::begin_panic_fmt::h598547fc766c278a
Nov 03 00:31:45.196 INFO blam!                                at libstd/panicking.rs:346
Nov 03 00:31:45.196 INFO blam!    7:     0x5596c2a2ad82 - check_timed_logs_fast::tests::should_skip_binary_files::h78dcbc553b4ab429
Nov 03 00:31:45.196 INFO blam!                                at src/lib.rs:588
Nov 03 00:31:45.196 INFO blam!    8:     0x5596c2a2aa99 - check_timed_logs_fast::tests::should_skip_binary_files::{{closure}}::h5d722dde14f27225
Nov 03 00:31:45.196 INFO blam!                                at src/lib.rs:577
Nov 03 00:31:45.196 INFO blam!    9:     0x5596c2a4025d - core::ops::function::FnOnce::call_once::ha68d56b9b598b2d3
Nov 03 00:31:45.196 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:45.196 INFO blam!   10:     0x5596c2a47aae - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 03 00:31:45.196 INFO blam!                                at libtest/lib.rs:1461
Nov 03 00:31:45.196 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:45.196 INFO blam!                                at liballoc/boxed.rs:646
Nov 03 00:31:45.197 INFO blam!   11:     0x5596c2d0dcf9 - __rust_maybe_catch_panic
Nov 03 00:31:45.197 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 03 00:31:45.197 INFO blam!   12:     0x5596c2a533bd - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:45.197 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:45.197 INFO blam!                                at libtest/lib.rs:1416
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 03 00:31:45.197 INFO blam!   13:     0x5596c2a6b9f4 - std::panicking::try::do_call::h1f0578498122cc00
Nov 03 00:31:45.197 INFO blam!                                at libstd/thread/mod.rs:409
Nov 03 00:31:45.197 INFO blam!                                at libstd/panic.rs:313
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:310
Nov 03 00:31:45.197 INFO blam!   14:     0x5596c2d0dcf9 - __rust_maybe_catch_panic
Nov 03 00:31:45.197 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 03 00:31:45.197 INFO blam!   15:     0x5596c2a5c2c6 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:45.197 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:45.197 INFO blam!                                at libstd/thread/mod.rs:408
Nov 03 00:31:45.197 INFO blam!                                at liballoc/boxed.rs:646
Nov 03 00:31:45.197 INFO blam!   16:     0x5596c2cfff6a - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 03 00:31:45.197 INFO blam!                                at liballoc/boxed.rs:656
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 03 00:31:45.197 INFO blam!   17:     0x5596c2cefc05 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 03 00:31:45.197 INFO blam!   18:     0x7f6a705fc6b9 - start_thread
Nov 03 00:31:45.197 INFO blam!   19:     0x7f6a7011c41c - clone
Nov 03 00:31:45.197 INFO blam!   20:                0x0 - <unknown>
Nov 03 00:31:45.197 INFO blam! 
Nov 03 00:31:45.197 INFO blam! ---- tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz stdout ----
Nov 03 00:31:45.197 INFO blam! thread 'tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz' panicked at 'assertion failed: `(left == right)`
Nov 03 00:31:45.197 INFO blam!   left: `1541204325`,
Nov 03 00:31:45.197 INFO blam!  right: `1541179125`', src/lib.rs:498:5
Nov 03 00:31:45.197 INFO blam! stack backtrace:
Nov 03 00:31:45.197 INFO blam!    0:     0x5596c2cfe59e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::hf5316c67202e23c8
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 03 00:31:45.197 INFO blam!    1:     0x5596c2cf09c6 - std::sys_common::backtrace::print::h3068ed2a6dc7ac86
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 03 00:31:45.197 INFO blam!    2:     0x5596c2ceed9d - std::panicking::default_hook::{{closure}}::h1b9d29cc09d41897
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:211
Nov 03 00:31:45.197 INFO blam!    3:     0x5596c2ceeaab - std::panicking::default_hook::h3a27b204a313406b
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:221
Nov 03 00:31:45.197 INFO blam!    4:     0x5596c2cef44c - std::panicking::rust_panic_with_hook::h0e12cb2fc86d00fa
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:477
Nov 03 00:31:45.197 INFO blam!    5:     0x5596c2cef009 - std::panicking::continue_panic_fmt::h141671b29fe0e27d
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:391
Nov 03 00:31:45.197 INFO blam!    6:     0x5596c2ceef6d - std::panicking::begin_panic_fmt::h598547fc766c278a
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:346
Nov 03 00:31:45.197 INFO blam!    7:     0x5596c2a29266 - check_timed_logs_fast::tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz::h2672c3f2ca0b6782
Nov 03 00:31:45.197 INFO blam!                                at src/lib.rs:498
Nov 03 00:31:45.197 INFO blam!    8:     0x5596c2a28e59 - check_timed_logs_fast::tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz::{{closure}}::h984fe8e036ffca2b
Nov 03 00:31:45.197 INFO blam!                                at src/lib.rs:478
Nov 03 00:31:45.197 INFO blam!    9:     0x5596c2a4019d - core::ops::function::FnOnce::call_once::ha1e096c78b5859ca
Nov 03 00:31:45.197 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:45.197 INFO blam!   10:     0x5596c2a47aae - <F as alloc::boxed::FnBox<A>>::call_box::ha9910eac2d17ff23
Nov 03 00:31:45.197 INFO blam!                                at libtest/lib.rs:1461
Nov 03 00:31:45.197 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:45.197 INFO blam!                                at liballoc/boxed.rs:646
Nov 03 00:31:45.197 INFO blam!   11:     0x5596c2d0dcf9 - __rust_maybe_catch_panic
Nov 03 00:31:45.197 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 03 00:31:45.197 INFO blam!   12:     0x5596c2a533bd - std::sys_common::backtrace::__rust_begin_short_backtrace::h470b05235fe5e5ad
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:45.197 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:45.197 INFO blam!                                at libtest/lib.rs:1416
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 03 00:31:45.197 INFO blam!   13:     0x5596c2a6b9f4 - std::panicking::try::do_call::h1f0578498122cc00
Nov 03 00:31:45.197 INFO blam!                                at libstd/thread/mod.rs:409
Nov 03 00:31:45.197 INFO blam!                                at libstd/panic.rs:313
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:310
Nov 03 00:31:45.197 INFO blam!   14:     0x5596c2d0dcf9 - __rust_maybe_catch_panic
Nov 03 00:31:45.197 INFO blam!                                at libpanic_unwind/lib.rs:103
Nov 03 00:31:45.197 INFO blam!   15:     0x5596c2a5c2c6 - <F as alloc::boxed::FnBox<A>>::call_box::h7b129f9f235ab460
Nov 03 00:31:45.197 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:45.197 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:45.197 INFO blam!                                at libstd/thread/mod.rs:408
Nov 03 00:31:45.197 INFO blam!                                at liballoc/boxed.rs:646
Nov 03 00:31:45.197 INFO blam!   16:     0x5596c2cfff6a - std::sys_common::thread::start_thread::hb77935aee02382da
Nov 03 00:31:45.197 INFO blam!                                at liballoc/boxed.rs:656
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 03 00:31:45.197 INFO blam!   17:     0x5596c2cefc05 - std::sys::unix::thread::Thread::new::thread_start::hd61f7429b3de1d75
Nov 03 00:31:45.197 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 03 00:31:45.197 INFO blam!   18:     0x7f6a705fc6b9 - start_thread
Nov 03 00:31:45.197 INFO blam!   19:     0x7f6a7011c41c - clone
Nov 03 00:31:45.197 INFO blam!   20:                0x0 - <unknown>
Nov 03 00:31:45.197 INFO blam! 
Nov 03 00:31:45.197 INFO blam! 
Nov 03 00:31:45.197 INFO blam! failures:
Nov 03 00:31:45.197 INFO blam!     tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz
Nov 03 00:31:45.197 INFO blam!     tests::should_skip_binary_files
Nov 03 00:31:45.197 INFO blam! 
Nov 03 00:31:45.198 INFO blam! test result: FAILED. 15 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:31:45.198 INFO blam! 
Nov 03 00:31:45.211 INFO kablam! su: No module specific data is present
Nov 03 00:31:45.699 INFO running `"docker" "rm" "-f" "6a0807009d7400e36a88c3a769ac61decb5aa1fbc6dc40a3f44007a10112d175"`
Nov 03 00:31:45.979 INFO blam! 6a0807009d7400e36a88c3a769ac61decb5aa1fbc6dc40a3f44007a10112d175
