Nov 03 00:31:23.280 INFO testing check_timed_logs_fast-0.1.0 against beta-2018-10-30 for beta-1.31-1
Nov 03 00:31:23.280 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-7/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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:23.695 INFO blam! f40337258ced1d481156e30086c12d9e92f14b285ff6ada0e12a010dbd5525dc
Nov 03 00:31:23.704 INFO running `"docker" "start" "-a" "f40337258ced1d481156e30086c12d9e92f14b285ff6ada0e12a010dbd5525dc"`
Nov 03 00:31:25.068 INFO kablam! usermod: no changes
Nov 03 00:31:25.179 INFO kablam!    Compiling memmap v0.5.2
Nov 03 00:31:25.179 INFO kablam!    Compiling fancy-regex v0.1.0
Nov 03 00:31:28.683 INFO kablam!    Compiling check_timed_logs_fast v0.1.0 (/source)
Nov 03 00:31:30.419 INFO kablam! warning: unused variable: `err`
Nov 03 00:31:30.419 INFO kablam!    --> src/lib.rs:146:16
Nov 03 00:31:30.420 INFO kablam!     |
Nov 03 00:31:30.420 INFO kablam! 146 |           Err((err, matches_in_file)) => {
Nov 03 00:31:30.420 INFO kablam!     |                ^^^ help: consider using `_err` instead
Nov 03 00:31:30.421 INFO kablam!     |
Nov 03 00:31:30.421 INFO kablam!     = note: #[warn(unused_variables)] on by default
Nov 03 00:31:30.421 INFO kablam! 
Nov 03 00:31:34.505 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 9.40s
Nov 03 00:31:34.517 INFO kablam! su: No module specific data is present
Nov 03 00:31:35.199 INFO running `"docker" "rm" "-f" "f40337258ced1d481156e30086c12d9e92f14b285ff6ada0e12a010dbd5525dc"`
Nov 03 00:31:35.431 INFO blam! f40337258ced1d481156e30086c12d9e92f14b285ff6ada0e12a010dbd5525dc
Nov 03 00:31:35.433 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-7/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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:35.836 INFO blam! 2e778893f719de8f8b4c2eda0e6b2f70a18d518f7c09d775212002646eb1d8c8
Nov 03 00:31:35.839 INFO running `"docker" "start" "-a" "2e778893f719de8f8b4c2eda0e6b2f70a18d518f7c09d775212002646eb1d8c8"`
Nov 03 00:31:37.211 INFO kablam! usermod: no changes
Nov 03 00:31:37.355 INFO kablam!    Compiling check_timed_logs_fast v0.1.0 (/source)
Nov 03 00:31:40.011 INFO kablam! warning: unused variable: `err`
Nov 03 00:31:40.011 INFO kablam!    --> src/lib.rs:146:16
Nov 03 00:31:40.011 INFO kablam!     |
Nov 03 00:31:40.011 INFO kablam! 146 |           Err((err, matches_in_file)) => {
Nov 03 00:31:40.011 INFO kablam!     |                ^^^ help: consider using `_err` instead
Nov 03 00:31:40.011 INFO kablam!     |
Nov 03 00:31:40.011 INFO kablam!     = note: #[warn(unused_variables)] on by default
Nov 03 00:31:40.011 INFO kablam! 
Nov 03 00:31:42.988 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 5.75s
Nov 03 00:31:42.991 INFO kablam! su: No module specific data is present
Nov 03 00:31:44.288 INFO running `"docker" "rm" "-f" "2e778893f719de8f8b4c2eda0e6b2f70a18d518f7c09d775212002646eb1d8c8"`
Nov 03 00:31:44.609 INFO blam! 2e778893f719de8f8b4c2eda0e6b2f70a18d518f7c09d775212002646eb1d8c8
Nov 03 00:31:44.615 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-7/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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:45.021 INFO blam! 093b06fea18b7bab8b3b7395946c7a1b97e8b62eecf55c85ab4afef8c66a7341
Nov 03 00:31:45.025 INFO running `"docker" "start" "-a" "093b06fea18b7bab8b3b7395946c7a1b97e8b62eecf55c85ab4afef8c66a7341"`
Nov 03 00:31:46.027 INFO kablam! usermod: no changes
Nov 03 00:31:46.143 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Nov 03 00:31:46.159 INFO kablam!      Running /target/debug/deps/check_timed_logs_fast-deb94b68eb5a435d
Nov 03 00:31:46.160 INFO blam! 
Nov 03 00:31:46.160 INFO blam! running 17 tests
Nov 03 00:31:46.161 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_utc ... ok
Nov 03 00:31:46.161 INFO blam! test tests::should_abort_when_stdin_used_as_logfile ... ok
Nov 03 00:31:46.168 INFO blam! test tests::should_handle_utf8_file_content_correctly ... ok
Nov 03 00:31:46.174 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz ... ok
Nov 03 00:31:46.191 INFO blam! test tests::should_handle_file_age_correctly ... ok
Nov 03 00:31:46.191 INFO blam! test tests::should_handle_non_default_date_format_and_trailing_comma_and_different_timeposition ... ok
Nov 03 00:31:46.192 INFO blam! test tests::should_ignore_trailing_comma ... ok
Nov 03 00:31:46.192 INFO blam! test tests::should_not_prepend_year_if_already_present ... ok
Nov 03 00:31:46.192 INFO blam! test tests::should_handle_files_with_lines_without_dates ... ok
Nov 03 00:31:46.192 INFO blam! test tests::should_handle_empty_file_correctly ... ok
Nov 03 00:31:46.211 INFO blam! test tests::should_search_matching_files ... ok
Nov 03 00:31:46.212 INFO blam! test tests::should_prepend_current_year ... ok
Nov 03 00:31:46.212 INFO blam! test tests::should_skip_old_files ... ok
Nov 03 00:31:46.212 INFO blam! test tests::should_search_in_file ... ok
Nov 03 00:31:46.304 INFO blam! test tests::should_handle_non_default_date_format ... FAILED
Nov 03 00:31:46.304 INFO blam! test tests::should_skip_binary_files ... FAILED
Nov 03 00:31:46.305 INFO blam! test tests::should_handle_timeposition ... FAILED
Nov 03 00:31:46.305 INFO blam! 
Nov 03 00:31:46.305 INFO blam! failures:
Nov 03 00:31:46.305 INFO blam! 
Nov 03 00:31:46.306 INFO blam! ---- tests::should_handle_non_default_date_format stdout ----
Nov 03 00:31:46.306 INFO blam! looking for files matching /tmp/.tmpsy2S1O*
Nov 03 00:31:46.306 INFO blam! oldest allowed date in utc: 2018-11-03 00:29:46 and with tz offset: 2018-11-02 17:29:46
Nov 03 00:31:46.307 INFO blam! found file /tmp/.tmpsy2S1O is 0 seconds old
Nov 03 00:31:46.307 INFO blam! processing line: Nov 03 00:31:46 foobar
Nov 03 00:31:46.307 INFO blam! parsed Nov 03 00:31:46 to date 2018-11-03 00:31:46 UTC
Nov 03 00:31:46.307 INFO blam! Nov 03 00:31:46 foobarprocessing line: Nov 03 00:31:46 bar
Nov 03 00:31:46.308 INFO blam! parsed Nov 03 00:31:46 to date 2018-11-03 00:31:46 UTC
Nov 03 00:31:46.308 INFO blam! processing line: Nov 03 00:26:46 foo
Nov 03 00:31:46.308 INFO blam! parsed Nov 03 00:26:46 to date 2018-11-03 00:26:46 UTC
Nov 03 00:31:46.309 INFO blam! Nov 03 00:26:46 foothread 'tests::should_handle_non_default_date_format' panicked at 'assertion failed: `(left == right)`
Nov 03 00:31:46.309 INFO blam!   left: `Ok((2, 1))`,
Nov 03 00:31:46.309 INFO blam!  right: `Ok((1, 1))`', src/lib.rs:665:5
Nov 03 00:31:46.309 INFO blam! stack backtrace:
Nov 03 00:31:46.310 INFO blam!    0:     0x559057ebb96f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c55afa80a996a32
Nov 03 00:31:46.315 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 03 00:31:46.315 INFO blam!    1:     0x559057ec4d87 - std::sys_common::backtrace::print::hcef907ea7a07cd40
Nov 03 00:31:46.315 INFO kablam! error: test failed, to rerun pass '--lib'
Nov 03 00:31:46.316 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 03 00:31:46.316 INFO kablam! su: No module specific data is present
Nov 03 00:31:46.316 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 03 00:31:46.316 INFO blam!    2:     0x559057ebf77f - std::panicking::default_hook::{{closure}}::hf541876c65f36ea7
Nov 03 00:31:46.317 INFO blam!                                at libstd/panicking.rs:211
Nov 03 00:31:46.317 INFO blam!    3:     0x559057ebf47e - std::panicking::default_hook::h4ecc06b30ae4459d
Nov 03 00:31:46.317 INFO blam!                                at libstd/panicking.rs:221
Nov 03 00:31:46.317 INFO blam!    4:     0x559057ebfe5e - std::panicking::rust_panic_with_hook::h0cc6d9cc9f34a947
Nov 03 00:31:46.318 INFO blam!                                at libstd/panicking.rs:476
Nov 03 00:31:46.323 INFO blam!    5:     0x559057ebfa01 - std::panicking::continue_panic_fmt::hc71ad5f01496e320
Nov 03 00:31:46.323 INFO blam!                                at libstd/panicking.rs:390
Nov 03 00:31:46.323 INFO blam!    6:     0x559057ebf94e - std::panicking::begin_panic_fmt::hdb958223ae20ca60
Nov 03 00:31:46.323 INFO blam!                                at libstd/panicking.rs:345
Nov 03 00:31:46.324 INFO blam!    7:     0x559057bdfb89 - check_timed_logs_fast::tests::should_handle_non_default_date_format::h052d21b007c4e120
Nov 03 00:31:46.324 INFO blam!                                at src/lib.rs:665
Nov 03 00:31:46.324 INFO blam!    8:     0x559057bdf409 - check_timed_logs_fast::tests::should_handle_non_default_date_format::{{closure}}::he9cda6fd69a0ce1a
Nov 03 00:31:46.324 INFO blam!                                at src/lib.rs:638
Nov 03 00:31:46.325 INFO blam!    9:     0x559057bf3b6d - core::ops::function::FnOnce::call_once::h47f32f9879b0abdf
Nov 03 00:31:46.325 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:46.325 INFO blam!   10:     0x559057bfb59e - <F as alloc::boxed::FnBox<A>>::call_box::hf32558bd83684b60
Nov 03 00:31:46.326 INFO blam!                                at libtest/lib.rs:1468
Nov 03 00:31:46.326 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:46.331 INFO blam!                                at liballoc/boxed.rs:672
Nov 03 00:31:46.331 INFO blam!   11:     0x559057ecffb9 - __rust_maybe_catch_panic
Nov 03 00:31:46.331 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 03 00:31:46.331 INFO blam!   12:     0x559057c1d202 - std::sys_common::backtrace::__rust_begin_short_backtrace::h1bb71a7386fd3269
Nov 03 00:31:46.332 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:46.332 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:46.332 INFO blam!                                at libtest/lib.rs:1423
Nov 03 00:31:46.333 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 03 00:31:46.333 INFO blam!   13:     0x559057c1dcb4 - std::panicking::try::do_call::h9b94719c3074bb5c
Nov 03 00:31:46.333 INFO blam!                                at libstd/thread/mod.rs:409
Nov 03 00:31:46.333 INFO blam!                                at libstd/panic.rs:313
Nov 03 00:31:46.339 INFO blam!                                at libstd/panicking.rs:310
Nov 03 00:31:46.339 INFO blam!   14:     0x559057ecffb9 - __rust_maybe_catch_panic
Nov 03 00:31:46.339 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 03 00:31:46.340 INFO blam!   15:     0x559057c0aedc - <F as alloc::boxed::FnBox<A>>::call_box::hb3bcd66d16fd3e84
Nov 03 00:31:46.340 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:46.340 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:46.341 INFO blam!                                at libstd/thread/mod.rs:408
Nov 03 00:31:46.341 INFO blam!                                at liballoc/boxed.rs:672
Nov 03 00:31:46.341 INFO blam!   16:     0x559057ebd62d - std::sys_common::thread::start_thread::had0c80d121669d29
Nov 03 00:31:46.341 INFO blam!                                at liballoc/boxed.rs:682
Nov 03 00:31:46.342 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 03 00:31:46.355 INFO blam!   17:     0x559057eb1de5 - std::sys::unix::thread::Thread::new::thread_start::h4a5b66a70d76f47f
Nov 03 00:31:46.355 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 03 00:31:46.355 INFO blam!   18:     0x7f38ef1fb6b9 - start_thread
Nov 03 00:31:46.355 INFO blam!   19:     0x7f38eed1b41c - clone
Nov 03 00:31:46.356 INFO blam!   20:                0x0 - <unknown>
Nov 03 00:31:46.356 INFO blam! 
Nov 03 00:31:46.356 INFO blam! ---- tests::should_skip_binary_files stdout ----
Nov 03 00:31:46.356 INFO blam! looking for files matching ./fixtures/1x1.png*
Nov 03 00:31:46.356 INFO blam! oldest allowed date in utc: 2018-11-03 00:30:46 and with tz offset: 2018-11-03 00:30:46
Nov 03 00:31:46.356 INFO blam! found file fixtures/1x1.png is 22 seconds old
Nov 03 00:31:46.357 INFO blam! skipping file because not utf8 parseable!
Nov 03 00:31:46.357 INFO blam! ERROR while searching the file fixtures/1x1.png: file not utf8
Nov 03 00:31:46.357 INFO blam!                       There were 0 matches until the error appeared.
Nov 03 00:31:46.357 INFO blam! thread 'tests::should_skip_binary_files' panicked at 'assertion failed: `(left == right)`
Nov 03 00:31:46.357 INFO blam!   left: `Ok((0, 1))`,
Nov 03 00:31:46.358 INFO blam!  right: `Ok((0, 0))`', src/lib.rs:588:5
Nov 03 00:31:46.358 INFO blam! stack backtrace:
Nov 03 00:31:46.358 INFO blam!    0:     0x559057ebb96f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c55afa80a996a32
Nov 03 00:31:46.358 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 03 00:31:46.358 INFO blam!    1:     0x559057ec4d87 - std::sys_common::backtrace::print::hcef907ea7a07cd40
Nov 03 00:31:46.359 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 03 00:31:46.359 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 03 00:31:46.359 INFO blam!    2:     0x559057ebf77f - std::panicking::default_hook::{{closure}}::hf541876c65f36ea7
Nov 03 00:31:46.359 INFO blam!                                at libstd/panicking.rs:211
Nov 03 00:31:46.359 INFO blam!    3:     0x559057ebf47e - std::panicking::default_hook::h4ecc06b30ae4459d
Nov 03 00:31:46.359 INFO blam!                                at libstd/panicking.rs:221
Nov 03 00:31:46.359 INFO blam!    4:     0x559057ebfe5e - std::panicking::rust_panic_with_hook::h0cc6d9cc9f34a947
Nov 03 00:31:46.360 INFO blam!                                at libstd/panicking.rs:476
Nov 03 00:31:46.360 INFO blam!    5:     0x559057ebfa01 - std::panicking::continue_panic_fmt::hc71ad5f01496e320
Nov 03 00:31:46.360 INFO blam!                                at libstd/panicking.rs:390
Nov 03 00:31:46.360 INFO blam!    6:     0x559057ebf94e - std::panicking::begin_panic_fmt::hdb958223ae20ca60
Nov 03 00:31:46.360 INFO blam!                                at libstd/panicking.rs:345
Nov 03 00:31:46.360 INFO blam!    7:     0x559057bde666 - check_timed_logs_fast::tests::should_skip_binary_files::h624dd7d82b4b6d75
Nov 03 00:31:46.361 INFO blam!                                at src/lib.rs:588
Nov 03 00:31:46.361 INFO blam!    8:     0x559057bde379 - check_timed_logs_fast::tests::should_skip_binary_files::{{closure}}::h3c6b88fff91a50d3
Nov 03 00:31:46.361 INFO blam!                                at src/lib.rs:577
Nov 03 00:31:46.361 INFO blam!    9:     0x559057bf3a2d - core::ops::function::FnOnce::call_once::h19ab203cdcb02c2f
Nov 03 00:31:46.361 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:46.361 INFO blam!   10:     0x559057bfb59e - <F as alloc::boxed::FnBox<A>>::call_box::hf32558bd83684b60
Nov 03 00:31:46.361 INFO blam!                                at libtest/lib.rs:1468
Nov 03 00:31:46.362 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:46.362 INFO blam!                                at liballoc/boxed.rs:672
Nov 03 00:31:46.362 INFO blam!   11:     0x559057ecffb9 - __rust_maybe_catch_panic
Nov 03 00:31:46.362 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 03 00:31:46.362 INFO blam!   12:     0x559057c1d202 - std::sys_common::backtrace::__rust_begin_short_backtrace::h1bb71a7386fd3269
Nov 03 00:31:46.362 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:46.367 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:46.367 INFO blam!                                at libtest/lib.rs:1423
Nov 03 00:31:46.367 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 03 00:31:46.367 INFO blam!   13:     0x559057c1dcb4 - std::panicking::try::do_call::h9b94719c3074bb5c
Nov 03 00:31:46.367 INFO blam!                                at libstd/thread/mod.rs:409
Nov 03 00:31:46.367 INFO blam!                                at libstd/panic.rs:313
Nov 03 00:31:46.368 INFO blam!                                at libstd/panicking.rs:310
Nov 03 00:31:46.368 INFO blam!   14:     0x559057ecffb9 - __rust_maybe_catch_panic
Nov 03 00:31:46.368 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 03 00:31:46.368 INFO blam!   15:     0x559057c0aedc - <F as alloc::boxed::FnBox<A>>::call_box::hb3bcd66d16fd3e84
Nov 03 00:31:46.368 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:46.368 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:46.369 INFO blam!                                at libstd/thread/mod.rs:408
Nov 03 00:31:46.369 INFO blam!                                at liballoc/boxed.rs:672
Nov 03 00:31:46.369 INFO blam!   16:     0x559057ebd62d - std::sys_common::thread::start_thread::had0c80d121669d29
Nov 03 00:31:46.369 INFO blam!                                at liballoc/boxed.rs:682
Nov 03 00:31:46.369 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 03 00:31:46.369 INFO blam!   17:     0x559057eb1de5 - std::sys::unix::thread::Thread::new::thread_start::h4a5b66a70d76f47f
Nov 03 00:31:46.370 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 03 00:31:46.370 INFO blam!   18:     0x7f38ef1fb6b9 - start_thread
Nov 03 00:31:46.370 INFO blam!   19:     0x7f38eed1b41c - clone
Nov 03 00:31:46.370 INFO blam!   20:                0x0 - <unknown>
Nov 03 00:31:46.370 INFO blam! 
Nov 03 00:31:46.371 INFO blam! ---- tests::should_handle_timeposition stdout ----
Nov 03 00:31:46.371 INFO blam! looking for files matching /tmp/.tmpj5YSOQ*
Nov 03 00:31:46.371 INFO blam! oldest allowed date in utc: 2018-11-03 00:29:46 and with tz offset: 2018-11-02 17:29:46
Nov 03 00:31:46.371 INFO blam! found file /tmp/.tmpj5YSOQ is 0 seconds old
Nov 03 00:31:46.371 INFO blam! processing line: foo_bar Nov 03 00:31:46
Nov 03 00:31:46.371 INFO blam! parsed Nov 03 00:31:46 to date 2018-11-03 00:31:46 UTC
Nov 03 00:31:46.372 INFO blam! foo_bar Nov 03 00:31:46processing line: foo-bar Nov 03 00:31:46
Nov 03 00:31:46.372 INFO blam! parsed Nov 03 00:31:46 to date 2018-11-03 00:31:46 UTC
Nov 03 00:31:46.372 INFO blam! foo-bar Nov 03 00:31:46processing line: bar Nov 03 00:31:46
Nov 03 00:31:46.372 INFO blam! parsed Nov 03 00:31:46 to date 2018-11-03 00:31:46 UTC
Nov 03 00:31:46.372 INFO blam! processing line: foo_bar Nov 03 00:26:46
Nov 03 00:31:46.372 INFO blam! parsed Nov 03 00:26:46 to date 2018-11-03 00:26:46 UTC
Nov 03 00:31:46.373 INFO blam! foo_bar Nov 03 00:26:46thread 'tests::should_handle_timeposition' panicked at 'assertion failed: `(left == right)`
Nov 03 00:31:46.373 INFO blam!   left: `Ok((3, 1))`,
Nov 03 00:31:46.373 INFO blam!  right: `Ok((2, 1))`', src/lib.rs:558:5
Nov 03 00:31:46.373 INFO blam! stack backtrace:
Nov 03 00:31:46.373 INFO blam!    0:     0x559057ebb96f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c55afa80a996a32
Nov 03 00:31:46.373 INFO blam!                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 03 00:31:46.374 INFO blam!    1:     0x559057ec4d87 - std::sys_common::backtrace::print::hcef907ea7a07cd40
Nov 03 00:31:46.374 INFO blam!                                at libstd/sys_common/backtrace.rs:71
Nov 03 00:31:46.374 INFO blam!                                at libstd/sys_common/backtrace.rs:59
Nov 03 00:31:46.374 INFO blam!    2:     0x559057ebf77f - std::panicking::default_hook::{{closure}}::hf541876c65f36ea7
Nov 03 00:31:46.378 INFO blam!                                at libstd/panicking.rs:211
Nov 03 00:31:46.379 INFO blam!    3:     0x559057ebf47e - std::panicking::default_hook::h4ecc06b30ae4459d
Nov 03 00:31:46.379 INFO blam!                                at libstd/panicking.rs:221
Nov 03 00:31:46.379 INFO blam!    4:     0x559057ebfe5e - std::panicking::rust_panic_with_hook::h0cc6d9cc9f34a947
Nov 03 00:31:46.379 INFO blam!                                at libstd/panicking.rs:476
Nov 03 00:31:46.379 INFO blam!    5:     0x559057ebfa01 - std::panicking::continue_panic_fmt::hc71ad5f01496e320
Nov 03 00:31:46.380 INFO blam!                                at libstd/panicking.rs:390
Nov 03 00:31:46.380 INFO blam!    6:     0x559057ebf94e - std::panicking::begin_panic_fmt::hdb958223ae20ca60
Nov 03 00:31:46.380 INFO blam!                                at libstd/panicking.rs:345
Nov 03 00:31:46.380 INFO blam!    7:     0x559057bddd9d - check_timed_logs_fast::tests::should_handle_timeposition::h12c655ae9f1803ee
Nov 03 00:31:46.380 INFO blam!                                at src/lib.rs:558
Nov 03 00:31:46.381 INFO blam!    8:     0x559057bdd599 - check_timed_logs_fast::tests::should_handle_timeposition::{{closure}}::hfe9e21c80806caaa
Nov 03 00:31:46.381 INFO blam!                                at src/lib.rs:532
Nov 03 00:31:46.381 INFO blam!    9:     0x559057bf3bed - core::ops::function::FnOnce::call_once::h5840a7cd9c8d4e6e
Nov 03 00:31:46.381 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:46.381 INFO blam!   10:     0x559057bfb59e - <F as alloc::boxed::FnBox<A>>::call_box::hf32558bd83684b60
Nov 03 00:31:46.381 INFO blam!                                at libtest/lib.rs:1468
Nov 03 00:31:46.382 INFO blam!                                at libcore/ops/function.rs:238
Nov 03 00:31:46.382 INFO blam!                                at liballoc/boxed.rs:672
Nov 03 00:31:46.382 INFO blam!   11:     0x559057ecffb9 - __rust_maybe_catch_panic
Nov 03 00:31:46.382 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 03 00:31:46.382 INFO blam!   12:     0x559057c1d202 - std::sys_common::backtrace::__rust_begin_short_backtrace::h1bb71a7386fd3269
Nov 03 00:31:46.383 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:46.383 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:46.383 INFO blam!                                at libtest/lib.rs:1423
Nov 03 00:31:46.383 INFO blam!                                at libstd/sys_common/backtrace.rs:136
Nov 03 00:31:46.383 INFO blam!   13:     0x559057c1dcb4 - std::panicking::try::do_call::h9b94719c3074bb5c
Nov 03 00:31:46.383 INFO blam!                                at libstd/thread/mod.rs:409
Nov 03 00:31:46.384 INFO blam!                                at libstd/panic.rs:313
Nov 03 00:31:46.384 INFO blam!                                at libstd/panicking.rs:310
Nov 03 00:31:46.384 INFO blam!   14:     0x559057ecffb9 - __rust_maybe_catch_panic
Nov 03 00:31:46.384 INFO blam!                                at libpanic_unwind/lib.rs:102
Nov 03 00:31:46.384 INFO blam!   15:     0x559057c0aedc - <F as alloc::boxed::FnBox<A>>::call_box::hb3bcd66d16fd3e84
Nov 03 00:31:46.384 INFO blam!                                at libstd/panicking.rs:289
Nov 03 00:31:46.385 INFO blam!                                at libstd/panic.rs:392
Nov 03 00:31:46.385 INFO blam!                                at libstd/thread/mod.rs:408
Nov 03 00:31:46.385 INFO blam!                                at liballoc/boxed.rs:672
Nov 03 00:31:46.385 INFO blam!   16:     0x559057ebd62d - std::sys_common::thread::start_thread::had0c80d121669d29
Nov 03 00:31:46.385 INFO blam!                                at liballoc/boxed.rs:682
Nov 03 00:31:46.385 INFO blam!                                at libstd/sys_common/thread.rs:24
Nov 03 00:31:46.386 INFO blam!   17:     0x559057eb1de5 - std::sys::unix::thread::Thread::new::thread_start::h4a5b66a70d76f47f
Nov 03 00:31:46.386 INFO blam!                                at libstd/sys/unix/thread.rs:90
Nov 03 00:31:46.386 INFO blam!   18:     0x7f38ef1fb6b9 - start_thread
Nov 03 00:31:46.386 INFO blam!   19:     0x7f38eed1b41c - clone
Nov 03 00:31:46.386 INFO blam!   20:                0x0 - <unknown>
Nov 03 00:31:46.386 INFO blam! 
Nov 03 00:31:46.391 INFO blam! 
Nov 03 00:31:46.391 INFO blam! failures:
Nov 03 00:31:46.391 INFO blam!     tests::should_handle_non_default_date_format
Nov 03 00:31:46.391 INFO blam!     tests::should_handle_timeposition
Nov 03 00:31:46.391 INFO blam!     tests::should_skip_binary_files
Nov 03 00:31:46.391 INFO blam! 
Nov 03 00:31:46.392 INFO blam! test result: FAILED. 14 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out
Nov 03 00:31:46.392 INFO blam! 
Nov 03 00:31:46.912 INFO running `"docker" "rm" "-f" "093b06fea18b7bab8b3b7395946c7a1b97e8b62eecf55c85ab4afef8c66a7341"`
Nov 03 00:31:47.287 INFO blam! 093b06fea18b7bab8b3b7395946c7a1b97e8b62eecf55c85ab4afef8c66a7341
