Oct 16 15:17:58.527 INFO testing check_timed_logs_fast-0.1.0 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1 Oct 16 15:17:58.529 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"` Oct 16 15:17:58.927 INFO blam! 639fea7fdb0f2b0afcd69936d77fe3aa5cc7367bd5d2b7461f876b51e3b87fa3 Oct 16 15:17:58.951 INFO running `"docker" "start" "-a" "639fea7fdb0f2b0afcd69936d77fe3aa5cc7367bd5d2b7461f876b51e3b87fa3"` Oct 16 15:18:00.091 INFO kablam! usermod: no changes Oct 16 15:18:00.251 INFO kablam! Compiling fancy-regex v0.1.0 Oct 16 15:18:03.271 INFO kablam! Compiling check_timed_logs_fast v0.1.0 (/source) Oct 16 15:18:05.165 INFO kablam! warning: unused variable: `err` Oct 16 15:18:05.165 INFO kablam! --> src/lib.rs:146:16 Oct 16 15:18:05.165 INFO kablam! | Oct 16 15:18:05.165 INFO kablam! 146 | Err((err, matches_in_file)) => { Oct 16 15:18:05.165 INFO kablam! | ^^^ help: consider using `_err` instead Oct 16 15:18:05.165 INFO kablam! | Oct 16 15:18:05.165 INFO kablam! = note: #[warn(unused_variables)] on by default Oct 16 15:18:05.165 INFO kablam! Oct 16 15:18:09.695 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 9.57s Oct 16 15:18:09.707 INFO kablam! su: No module specific data is present Oct 16 15:18:10.264 INFO running `"docker" "rm" "-f" "639fea7fdb0f2b0afcd69936d77fe3aa5cc7367bd5d2b7461f876b51e3b87fa3"` Oct 16 15:18:10.519 INFO blam! 639fea7fdb0f2b0afcd69936d77fe3aa5cc7367bd5d2b7461f876b51e3b87fa3 Oct 16 15:18:10.523 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"` Oct 16 15:18:11.069 INFO blam! 7c3f0fcb46a9e18bd9a820068666a0cc14c5793bfb8b0c25e6091dc1cc729649 Oct 16 15:18:11.072 INFO running `"docker" "start" "-a" "7c3f0fcb46a9e18bd9a820068666a0cc14c5793bfb8b0c25e6091dc1cc729649"` Oct 16 15:18:12.246 INFO kablam! usermod: no changes Oct 16 15:18:12.352 INFO kablam! Compiling check_timed_logs_fast v0.1.0 (/source) Oct 16 15:18:16.075 INFO kablam! warning: unused variable: `err` Oct 16 15:18:16.075 INFO kablam! --> src/lib.rs:146:16 Oct 16 15:18:16.075 INFO kablam! | Oct 16 15:18:16.075 INFO kablam! 146 | Err((err, matches_in_file)) => { Oct 16 15:18:16.075 INFO kablam! | ^^^ help: consider using `_err` instead Oct 16 15:18:16.075 INFO kablam! | Oct 16 15:18:16.075 INFO kablam! = note: #[warn(unused_variables)] on by default Oct 16 15:18:16.075 INFO kablam! Oct 16 15:18:18.623 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 6.34s Oct 16 15:18:18.623 INFO kablam! su: No module specific data is present Oct 16 15:18:19.215 INFO running `"docker" "rm" "-f" "7c3f0fcb46a9e18bd9a820068666a0cc14c5793bfb8b0c25e6091dc1cc729649"` Oct 16 15:18:19.665 INFO blam! 7c3f0fcb46a9e18bd9a820068666a0cc14c5793bfb8b0c25e6091dc1cc729649 Oct 16 15:18:19.667 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"` Oct 16 15:18:20.040 INFO blam! d5cf3c9ed7ebcf1506fa33237a70dd29b14c4f8cab045071737995bfd3d070d3 Oct 16 15:18:20.047 INFO running `"docker" "start" "-a" "d5cf3c9ed7ebcf1506fa33237a70dd29b14c4f8cab045071737995bfd3d070d3"` Oct 16 15:18:21.308 INFO kablam! usermod: no changes Oct 16 15:18:21.439 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 0.10s Oct 16 15:18:21.445 INFO kablam! Running /target/debug/deps/check_timed_logs_fast-ffe0ebbe4acf2f65 Oct 16 15:18:21.450 INFO blam! Oct 16 15:18:21.458 INFO blam! running 17 tests Oct 16 15:18:21.460 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_utc ... ok Oct 16 15:18:21.460 INFO blam! test tests::should_abort_when_stdin_used_as_logfile ... ok Oct 16 15:18:21.479 INFO blam! test tests::should_handle_empty_file_correctly ... ok Oct 16 15:18:21.487 INFO blam! test tests::should_handle_file_age_correctly ... ok Oct 16 15:18:21.487 INFO blam! test tests::should_handle_non_default_date_format_and_trailing_comma_and_different_timeposition ... ok Oct 16 15:18:21.487 INFO blam! test tests::should_prepend_current_year ... ok Oct 16 15:18:21.487 INFO blam! test tests::should_not_prepend_year_if_already_present ... ok Oct 16 15:18:21.495 INFO blam! test tests::should_search_matching_files ... ok Oct 16 15:18:21.495 INFO blam! test tests::should_search_in_file ... ok Oct 16 15:18:21.523 INFO blam! test tests::should_handle_files_with_lines_without_dates ... ok Oct 16 15:18:21.523 INFO blam! test tests::should_handle_timeposition ... ok Oct 16 15:18:21.527 INFO blam! test tests::should_handle_non_default_date_format ... ok Oct 16 15:18:21.535 INFO blam! test tests::should_ignore_trailing_comma ... ok Oct 16 15:18:21.535 INFO blam! test tests::should_handle_utf8_file_content_correctly ... ok Oct 16 15:18:21.535 INFO blam! test tests::should_skip_old_files ... ok Oct 16 15:18:21.579 INFO blam! test tests::should_skip_binary_files ... FAILED Oct 16 15:18:21.579 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz ... FAILED Oct 16 15:18:21.579 INFO blam! Oct 16 15:18:21.580 INFO blam! failures: Oct 16 15:18:21.580 INFO blam! Oct 16 15:18:21.580 INFO blam! ---- tests::should_skip_binary_files stdout ---- Oct 16 15:18:21.580 INFO blam! looking for files matching ./fixtures/1x1.png* Oct 16 15:18:21.581 INFO blam! oldest allowed date in utc: 2018-10-16 15:17:21 and with tz offset: 2018-10-16 15:17:21 Oct 16 15:18:21.581 INFO kablam! error: test failed, to rerun pass '--lib' Oct 16 15:18:21.581 INFO blam! found file fixtures/1x1.png is 23 seconds old Oct 16 15:18:21.582 INFO blam! skipping file because not utf8 parseable! Oct 16 15:18:21.582 INFO blam! ERROR while searching the file fixtures/1x1.png: file not utf8 Oct 16 15:18:21.582 INFO blam! There were 0 matches until the error appeared. Oct 16 15:18:21.583 INFO blam! thread 'tests::should_skip_binary_files' panicked at 'assertion failed: `(left == right)` Oct 16 15:18:21.583 INFO blam! left: `Ok((0, 1))`, Oct 16 15:18:21.583 INFO blam! right: `Ok((0, 0))`', src/lib.rs:588:5 Oct 16 15:18:21.583 INFO blam! stack backtrace: Oct 16 15:18:21.584 INFO blam! 0: 0x562adf7f38be - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617 Oct 16 15:18:21.584 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 16 15:18:21.584 INFO blam! 1: 0x562adf7fcb46 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9 Oct 16 15:18:21.585 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 16 15:18:21.585 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 16 15:18:21.585 INFO blam! 2: 0x562adf7f75bd - std::panicking::default_hook::{{closure}}::h47bedf778bf88480 Oct 16 15:18:21.586 INFO blam! at libstd/panicking.rs:211 Oct 16 15:18:21.586 INFO blam! 3: 0x562adf7f72cb - std::panicking::default_hook::h1ef46fa7cd894809 Oct 16 15:18:21.586 INFO blam! at libstd/panicking.rs:221 Oct 16 15:18:21.587 INFO blam! 4: 0x562adf7f7c6c - std::panicking::rust_panic_with_hook::h05510fde8841c579 Oct 16 15:18:21.587 INFO kablam! su: No module specific data is present Oct 16 15:18:21.588 INFO blam! at libstd/panicking.rs:476 Oct 16 15:18:21.588 INFO blam! 5: 0x562adf7f7829 - std::panicking::continue_panic_fmt::h6e11f50e47f94b00 Oct 16 15:18:21.588 INFO blam! at libstd/panicking.rs:390 Oct 16 15:18:21.588 INFO blam! 6: 0x562adf7f778d - std::panicking::begin_panic_fmt::h0063f49bf5a35411 Oct 16 15:18:21.589 INFO blam! at libstd/panicking.rs:345 Oct 16 15:18:21.589 INFO blam! 7: 0x562adf52ad92 - check_timed_logs_fast::tests::should_skip_binary_files::h7341c36fd9d76d1b Oct 16 15:18:21.589 INFO blam! at src/lib.rs:588 Oct 16 15:18:21.590 INFO blam! 8: 0x562adf52aaa9 - check_timed_logs_fast::tests::should_skip_binary_files::{{closure}}::h21b7db9f498fed4d Oct 16 15:18:21.590 INFO blam! at src/lib.rs:577 Oct 16 15:18:21.590 INFO blam! 9: 0x562adf53fdcd - core::ops::function::FnOnce::call_once::h10f9cfe60a1f4835 Oct 16 15:18:21.591 INFO blam! at libcore/ops/function.rs:238 Oct 16 15:18:21.591 INFO blam! 10: 0x562adf5472ee - >::call_box::h95b7cb918af67b5d Oct 16 15:18:21.591 INFO blam! at libtest/lib.rs:1468 Oct 16 15:18:21.591 INFO blam! at libcore/ops/function.rs:238 Oct 16 15:18:21.592 INFO blam! at liballoc/boxed.rs:672 Oct 16 15:18:21.592 INFO blam! 11: 0x562adf807d19 - __rust_maybe_catch_panic Oct 16 15:18:21.592 INFO blam! at libpanic_unwind/lib.rs:102 Oct 16 15:18:21.592 INFO blam! 12: 0x562adf567e3d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f Oct 16 15:18:21.592 INFO blam! at libstd/panicking.rs:289 Oct 16 15:18:21.593 INFO blam! at libstd/panic.rs:392 Oct 16 15:18:21.593 INFO blam! at libtest/lib.rs:1423 Oct 16 15:18:21.593 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 16 15:18:21.593 INFO blam! 13: 0x562adf568a44 - std::panicking::try::do_call::ha990a345054c2d63 Oct 16 15:18:21.594 INFO blam! at libstd/thread/mod.rs:409 Oct 16 15:18:21.594 INFO blam! at libstd/panic.rs:313 Oct 16 15:18:21.594 INFO blam! at libstd/panicking.rs:310 Oct 16 15:18:21.594 INFO blam! 14: 0x562adf807d19 - __rust_maybe_catch_panic Oct 16 15:18:21.594 INFO blam! at libpanic_unwind/lib.rs:102 Oct 16 15:18:21.595 INFO blam! 15: 0x562adf556006 - >::call_box::h96125cb29a9ba44b Oct 16 15:18:21.595 INFO blam! at libstd/panicking.rs:289 Oct 16 15:18:21.595 INFO blam! at libstd/panic.rs:392 Oct 16 15:18:21.595 INFO blam! at libstd/thread/mod.rs:408 Oct 16 15:18:21.595 INFO blam! at liballoc/boxed.rs:672 Oct 16 15:18:21.596 INFO blam! 16: 0x562adf7f59aa - std::sys_common::thread::start_thread::h97c4c5d82028f6b1 Oct 16 15:18:21.596 INFO blam! at liballoc/boxed.rs:682 Oct 16 15:18:21.596 INFO blam! at libstd/sys_common/thread.rs:24 Oct 16 15:18:21.596 INFO blam! 17: 0x562adf7ea135 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886 Oct 16 15:18:21.596 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 16 15:18:21.597 INFO blam! 18: 0x7fcb27d656b9 - start_thread Oct 16 15:18:21.597 INFO blam! 19: 0x7fcb2788541c - clone Oct 16 15:18:21.597 INFO blam! 20: 0x0 - Oct 16 15:18:21.597 INFO blam! Oct 16 15:18:21.597 INFO blam! ---- tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz stdout ---- Oct 16 15:18:21.598 INFO blam! thread 'tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz' panicked at 'assertion failed: `(left == right)` Oct 16 15:18:21.598 INFO blam! left: `1539702321`, Oct 16 15:18:21.598 INFO blam! right: `1539677121`', src/lib.rs:498:5 Oct 16 15:18:21.598 INFO blam! stack backtrace: Oct 16 15:18:21.599 INFO blam! 0: 0x562adf7f38be - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617 Oct 16 15:18:21.599 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 16 15:18:21.599 INFO blam! 1: 0x562adf7fcb46 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9 Oct 16 15:18:21.599 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 16 15:18:21.600 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 16 15:18:21.600 INFO blam! 2: 0x562adf7f75bd - std::panicking::default_hook::{{closure}}::h47bedf778bf88480 Oct 16 15:18:21.600 INFO blam! at libstd/panicking.rs:211 Oct 16 15:18:21.600 INFO blam! 3: 0x562adf7f72cb - std::panicking::default_hook::h1ef46fa7cd894809 Oct 16 15:18:21.601 INFO blam! at libstd/panicking.rs:221 Oct 16 15:18:21.601 INFO blam! 4: 0x562adf7f7c6c - std::panicking::rust_panic_with_hook::h05510fde8841c579 Oct 16 15:18:21.601 INFO blam! at libstd/panicking.rs:476 Oct 16 15:18:21.601 INFO blam! 5: 0x562adf7f7829 - std::panicking::continue_panic_fmt::h6e11f50e47f94b00 Oct 16 15:18:21.602 INFO blam! at libstd/panicking.rs:390 Oct 16 15:18:21.602 INFO blam! 6: 0x562adf7f778d - std::panicking::begin_panic_fmt::h0063f49bf5a35411 Oct 16 15:18:21.602 INFO blam! at libstd/panicking.rs:345 Oct 16 15:18:21.603 INFO blam! 7: 0x562adf529276 - check_timed_logs_fast::tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz::hceac4a079a9b0b66 Oct 16 15:18:21.603 INFO blam! at src/lib.rs:498 Oct 16 15:18:21.603 INFO blam! 8: 0x562adf528e69 - check_timed_logs_fast::tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz::{{closure}}::h05dcdf1e7e0df6f4 Oct 16 15:18:21.603 INFO blam! at src/lib.rs:478 Oct 16 15:18:21.603 INFO blam! 9: 0x562adf54018d - core::ops::function::FnOnce::call_once::hbfa277d2f37ca4ff Oct 16 15:18:21.604 INFO blam! at libcore/ops/function.rs:238 Oct 16 15:18:21.604 INFO blam! 10: 0x562adf5472ee - >::call_box::h95b7cb918af67b5d Oct 16 15:18:21.604 INFO blam! at libtest/lib.rs:1468 Oct 16 15:18:21.604 INFO blam! at libcore/ops/function.rs:238 Oct 16 15:18:21.605 INFO blam! at liballoc/boxed.rs:672 Oct 16 15:18:21.605 INFO blam! 11: 0x562adf807d19 - __rust_maybe_catch_panic Oct 16 15:18:21.605 INFO blam! at libpanic_unwind/lib.rs:102 Oct 16 15:18:21.605 INFO blam! 12: 0x562adf567e3d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f Oct 16 15:18:21.606 INFO blam! at libstd/panicking.rs:289 Oct 16 15:18:21.606 INFO blam! at libstd/panic.rs:392 Oct 16 15:18:21.606 INFO blam! at libtest/lib.rs:1423 Oct 16 15:18:21.606 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 16 15:18:21.607 INFO blam! 13: 0x562adf568a44 - std::panicking::try::do_call::ha990a345054c2d63 Oct 16 15:18:21.607 INFO blam! at libstd/thread/mod.rs:409 Oct 16 15:18:21.607 INFO blam! at libstd/panic.rs:313 Oct 16 15:18:21.608 INFO blam! at libstd/panicking.rs:310 Oct 16 15:18:21.608 INFO blam! 14: 0x562adf807d19 - __rust_maybe_catch_panic Oct 16 15:18:21.608 INFO blam! at libpanic_unwind/lib.rs:102 Oct 16 15:18:21.608 INFO blam! 15: 0x562adf556006 - >::call_box::h96125cb29a9ba44b Oct 16 15:18:21.609 INFO blam! at libstd/panicking.rs:289 Oct 16 15:18:21.609 INFO blam! at libstd/panic.rs:392 Oct 16 15:18:21.609 INFO blam! at libstd/thread/mod.rs:408 Oct 16 15:18:21.610 INFO blam! at liballoc/boxed.rs:672 Oct 16 15:18:21.610 INFO blam! 16: 0x562adf7f59aa - std::sys_common::thread::start_thread::h97c4c5d82028f6b1 Oct 16 15:18:21.610 INFO blam! at liballoc/boxed.rs:682 Oct 16 15:18:21.610 INFO blam! at libstd/sys_common/thread.rs:24 Oct 16 15:18:21.611 INFO blam! 17: 0x562adf7ea135 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886 Oct 16 15:18:21.611 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 16 15:18:21.611 INFO blam! 18: 0x7fcb27d656b9 - start_thread Oct 16 15:18:21.612 INFO blam! 19: 0x7fcb2788541c - clone Oct 16 15:18:21.612 INFO blam! 20: 0x0 - Oct 16 15:18:21.612 INFO blam! Oct 16 15:18:21.612 INFO blam! Oct 16 15:18:21.613 INFO blam! failures: Oct 16 15:18:21.613 INFO blam! tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz Oct 16 15:18:21.613 INFO blam! tests::should_skip_binary_files Oct 16 15:18:21.614 INFO blam! Oct 16 15:18:21.614 INFO blam! test result: FAILED. 15 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out Oct 16 15:18:21.614 INFO blam! Oct 16 15:18:22.173 INFO running `"docker" "rm" "-f" "d5cf3c9ed7ebcf1506fa33237a70dd29b14c4f8cab045071737995bfd3d070d3"` Oct 16 15:18:22.521 INFO blam! d5cf3c9ed7ebcf1506fa33237a70dd29b14c4f8cab045071737995bfd3d070d3