Oct 12 03:25:55.035 INFO testing check_timed_logs_fast-0.1.0 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling Oct 12 03:25:55.036 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-7/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/rustdoc-test-static-cling/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 12 03:25:55.448 INFO blam! 2ad3beefabd29b1a713500b202fa6321664998663ed8719416c5b1dcd750416b Oct 12 03:25:55.450 INFO running `"docker" "start" "-a" "2ad3beefabd29b1a713500b202fa6321664998663ed8719416c5b1dcd750416b"` Oct 12 03:25:56.702 INFO kablam! usermod: no changes Oct 12 03:25:56.820 INFO kablam! Compiling fancy-regex v0.1.0 Oct 12 03:26:00.685 INFO kablam! Compiling check_timed_logs_fast v0.1.0 (/source) Oct 12 03:26:03.489 INFO kablam! warning: unused variable: `err` Oct 12 03:26:03.489 INFO kablam! --> src/lib.rs:146:16 Oct 12 03:26:03.489 INFO kablam! | Oct 12 03:26:03.489 INFO kablam! 146 | Err((err, matches_in_file)) => { Oct 12 03:26:03.489 INFO kablam! | ^^^ help: consider using `_err` instead Oct 12 03:26:03.489 INFO kablam! | Oct 12 03:26:03.489 INFO kablam! = note: #[warn(unused_variables)] on by default Oct 12 03:26:03.489 INFO kablam! Oct 12 03:26:09.076 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 12.34s Oct 12 03:26:09.081 INFO kablam! su: No module specific data is present Oct 12 03:26:09.805 INFO running `"docker" "rm" "-f" "2ad3beefabd29b1a713500b202fa6321664998663ed8719416c5b1dcd750416b"` Oct 12 03:26:10.162 INFO blam! 2ad3beefabd29b1a713500b202fa6321664998663ed8719416c5b1dcd750416b Oct 12 03:26:10.166 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-7/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/rustdoc-test-static-cling/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 12 03:26:10.604 INFO blam! 434735850e1ea54b6f7b3ffb3787158769c37bd427061f353f70273c5ba279bc Oct 12 03:26:10.607 INFO running `"docker" "start" "-a" "434735850e1ea54b6f7b3ffb3787158769c37bd427061f353f70273c5ba279bc"` Oct 12 03:26:11.718 INFO kablam! usermod: no changes Oct 12 03:26:11.840 INFO kablam! Compiling check_timed_logs_fast v0.1.0 (/source) Oct 12 03:26:15.983 INFO kablam! warning: unused variable: `err` Oct 12 03:26:15.984 INFO kablam! --> src/lib.rs:146:16 Oct 12 03:26:15.984 INFO kablam! | Oct 12 03:26:15.984 INFO kablam! 146 | Err((err, matches_in_file)) => { Oct 12 03:26:15.985 INFO kablam! | ^^^ help: consider using `_err` instead Oct 12 03:26:15.985 INFO kablam! | Oct 12 03:26:15.985 INFO kablam! = note: #[warn(unused_variables)] on by default Oct 12 03:26:15.986 INFO kablam! Oct 12 03:26:19.016 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 7.27s Oct 12 03:26:19.020 INFO kablam! su: No module specific data is present Oct 12 03:26:19.575 INFO running `"docker" "rm" "-f" "434735850e1ea54b6f7b3ffb3787158769c37bd427061f353f70273c5ba279bc"` Oct 12 03:26:19.872 INFO blam! 434735850e1ea54b6f7b3ffb3787158769c37bd427061f353f70273c5ba279bc Oct 12 03:26:19.877 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-7/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-7/rustdoc-test-static-cling/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 12 03:26:20.225 INFO blam! 73c09a1bc3a53d630d844e8ac3c4db405ee7ea7c742f0a01bab146f2a6fcad97 Oct 12 03:26:20.235 INFO running `"docker" "start" "-a" "73c09a1bc3a53d630d844e8ac3c4db405ee7ea7c742f0a01bab146f2a6fcad97"` Oct 12 03:26:21.632 INFO kablam! usermod: no changes Oct 12 03:26:21.833 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 0.17s Oct 12 03:26:21.847 INFO kablam! Running /target/debug/deps/check_timed_logs_fast-ffe0ebbe4acf2f65 Oct 12 03:26:21.849 INFO blam! Oct 12 03:26:21.850 INFO blam! running 17 tests Oct 12 03:26:21.864 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_utc ... ok Oct 12 03:26:21.878 INFO blam! test tests::should_handle_files_with_lines_without_dates ... ok Oct 12 03:26:21.884 INFO blam! test tests::should_handle_file_age_correctly ... ok Oct 12 03:26:21.888 INFO blam! test tests::should_handle_non_default_date_format ... ok Oct 12 03:26:21.892 INFO blam! test tests::should_handle_empty_file_correctly ... ok Oct 12 03:26:21.892 INFO blam! test tests::should_handle_non_default_date_format_and_trailing_comma_and_different_timeposition ... ok Oct 12 03:26:21.893 INFO blam! test tests::should_ignore_trailing_comma ... ok Oct 12 03:26:21.895 INFO blam! test tests::should_prepend_current_year ... ok Oct 12 03:26:21.895 INFO blam! test tests::should_not_prepend_year_if_already_present ... ok Oct 12 03:26:21.912 INFO blam! test tests::should_abort_when_stdin_used_as_logfile ... ok Oct 12 03:26:21.916 INFO blam! test tests::should_handle_utf8_file_content_correctly ... ok Oct 12 03:26:21.922 INFO blam! test tests::should_skip_old_files ... ok Oct 12 03:26:21.944 INFO blam! test tests::should_search_matching_files ... ok Oct 12 03:26:21.945 INFO blam! test tests::should_handle_timeposition ... ok Oct 12 03:26:21.946 INFO blam! test tests::should_search_in_file ... ok Oct 12 03:26:22.015 INFO blam! test tests::should_skip_binary_files ... FAILED Oct 12 03:26:22.015 INFO blam! test tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz ... FAILED Oct 12 03:26:22.015 INFO blam! Oct 12 03:26:22.015 INFO blam! failures: Oct 12 03:26:22.015 INFO blam! Oct 12 03:26:22.016 INFO blam! ---- tests::should_skip_binary_files stdout ---- Oct 12 03:26:22.016 INFO blam! looking for files matching ./fixtures/1x1.png* Oct 12 03:26:22.016 INFO blam! oldest allowed date in utc: 2018-10-12 03:25:21 and with tz offset: 2018-10-12 03:25:21 Oct 12 03:26:22.016 INFO blam! found file fixtures/1x1.png is 26 seconds old Oct 12 03:26:22.016 INFO blam! skipping file because not utf8 parseable! Oct 12 03:26:22.016 INFO blam! ERROR while searching the file fixtures/1x1.png: file not utf8 Oct 12 03:26:22.016 INFO blam! There were 0 matches until the error appeared. Oct 12 03:26:22.016 INFO blam! thread 'tests::should_skip_binary_files' panicked at 'assertion failed: `(left == right)` Oct 12 03:26:22.016 INFO blam! left: `Ok((0, 1))`, Oct 12 03:26:22.016 INFO blam! right: `Ok((0, 0))`', src/lib.rs:588:5 Oct 12 03:26:22.016 INFO blam! stack backtrace: Oct 12 03:26:22.016 INFO blam! 0: 0x562001d538be - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617 Oct 12 03:26:22.016 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 12 03:26:22.016 INFO blam! 1: 0x562001d5cb46 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9 Oct 12 03:26:22.016 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 12 03:26:22.016 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 12 03:26:22.016 INFO blam! 2: 0x562001d575bd - std::panicking::default_hook::{{closure}}::h47bedf778bf88480 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:211 Oct 12 03:26:22.016 INFO blam! 3: 0x562001d572cb - std::panicking::default_hook::h1ef46fa7cd894809 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:221 Oct 12 03:26:22.016 INFO blam! 4: 0x562001d57c6c - std::panicking::rust_panic_with_hook::h05510fde8841c579 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:476 Oct 12 03:26:22.016 INFO blam! 5: 0x562001d57829 - std::panicking::continue_panic_fmt::h6e11f50e47f94b00 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:390 Oct 12 03:26:22.016 INFO blam! 6: 0x562001d5778d - std::panicking::begin_panic_fmt::h0063f49bf5a35411 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:345 Oct 12 03:26:22.016 INFO blam! 7: 0x562001a8ad92 - check_timed_logs_fast::tests::should_skip_binary_files::h7341c36fd9d76d1b Oct 12 03:26:22.016 INFO blam! at src/lib.rs:588 Oct 12 03:26:22.016 INFO blam! 8: 0x562001a8aaa9 - check_timed_logs_fast::tests::should_skip_binary_files::{{closure}}::h21b7db9f498fed4d Oct 12 03:26:22.016 INFO blam! at src/lib.rs:577 Oct 12 03:26:22.016 INFO blam! 9: 0x562001a9fdcd - core::ops::function::FnOnce::call_once::h10f9cfe60a1f4835 Oct 12 03:26:22.016 INFO blam! at libcore/ops/function.rs:238 Oct 12 03:26:22.016 INFO blam! 10: 0x562001aa72ee - >::call_box::h95b7cb918af67b5d Oct 12 03:26:22.016 INFO blam! at libtest/lib.rs:1468 Oct 12 03:26:22.016 INFO blam! at libcore/ops/function.rs:238 Oct 12 03:26:22.016 INFO blam! at liballoc/boxed.rs:672 Oct 12 03:26:22.016 INFO blam! 11: 0x562001d67d19 - __rust_maybe_catch_panic Oct 12 03:26:22.016 INFO blam! at libpanic_unwind/lib.rs:102 Oct 12 03:26:22.016 INFO blam! 12: 0x562001ac7e3d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:289 Oct 12 03:26:22.016 INFO blam! at libstd/panic.rs:392 Oct 12 03:26:22.016 INFO blam! at libtest/lib.rs:1423 Oct 12 03:26:22.016 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 12 03:26:22.016 INFO blam! 13: 0x562001ac8a44 - std::panicking::try::do_call::ha990a345054c2d63 Oct 12 03:26:22.016 INFO blam! at libstd/thread/mod.rs:409 Oct 12 03:26:22.016 INFO blam! at libstd/panic.rs:313 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:310 Oct 12 03:26:22.016 INFO blam! 14: 0x562001d67d19 - __rust_maybe_catch_panic Oct 12 03:26:22.016 INFO blam! at libpanic_unwind/lib.rs:102 Oct 12 03:26:22.016 INFO blam! 15: 0x562001ab6006 - >::call_box::h96125cb29a9ba44b Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:289 Oct 12 03:26:22.016 INFO blam! at libstd/panic.rs:392 Oct 12 03:26:22.016 INFO blam! at libstd/thread/mod.rs:408 Oct 12 03:26:22.016 INFO blam! at liballoc/boxed.rs:672 Oct 12 03:26:22.016 INFO blam! 16: 0x562001d559aa - std::sys_common::thread::start_thread::h97c4c5d82028f6b1 Oct 12 03:26:22.016 INFO blam! at liballoc/boxed.rs:682 Oct 12 03:26:22.016 INFO blam! at libstd/sys_common/thread.rs:24 Oct 12 03:26:22.016 INFO blam! 17: 0x562001d4a135 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886 Oct 12 03:26:22.016 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 12 03:26:22.016 INFO blam! 18: 0x7fbb608916b9 - start_thread Oct 12 03:26:22.016 INFO blam! 19: 0x7fbb603b141c - clone Oct 12 03:26:22.016 INFO blam! 20: 0x0 - Oct 12 03:26:22.016 INFO blam! Oct 12 03:26:22.016 INFO blam! ---- tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz stdout ---- Oct 12 03:26:22.016 INFO blam! thread 'tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz' panicked at 'assertion failed: `(left == right)` Oct 12 03:26:22.016 INFO blam! left: `1539314001`, Oct 12 03:26:22.016 INFO blam! right: `1539288801`', src/lib.rs:498:5 Oct 12 03:26:22.016 INFO blam! stack backtrace: Oct 12 03:26:22.016 INFO blam! 0: 0x562001d538be - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617 Oct 12 03:26:22.016 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 12 03:26:22.016 INFO blam! 1: 0x562001d5cb46 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9 Oct 12 03:26:22.016 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 12 03:26:22.016 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 12 03:26:22.016 INFO blam! 2: 0x562001d575bd - std::panicking::default_hook::{{closure}}::h47bedf778bf88480 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:211 Oct 12 03:26:22.016 INFO blam! 3: 0x562001d572cb - std::panicking::default_hook::h1ef46fa7cd894809 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:221 Oct 12 03:26:22.016 INFO blam! 4: 0x562001d57c6c - std::panicking::rust_panic_with_hook::h05510fde8841c579 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:476 Oct 12 03:26:22.016 INFO blam! 5: 0x562001d57829 - std::panicking::continue_panic_fmt::h6e11f50e47f94b00 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:390 Oct 12 03:26:22.016 INFO blam! 6: 0x562001d5778d - std::panicking::begin_panic_fmt::h0063f49bf5a35411 Oct 12 03:26:22.016 INFO blam! at libstd/panicking.rs:345 Oct 12 03:26:22.016 INFO blam! 7: 0x562001a89276 - check_timed_logs_fast::tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz::hceac4a079a9b0b66 Oct 12 03:26:22.016 INFO blam! at src/lib.rs:498 Oct 12 03:26:22.017 INFO blam! 8: 0x562001a88e69 - check_timed_logs_fast::tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz::{{closure}}::h05dcdf1e7e0df6f4 Oct 12 03:26:22.017 INFO blam! at src/lib.rs:478 Oct 12 03:26:22.017 INFO blam! 9: 0x562001aa018d - core::ops::function::FnOnce::call_once::hbfa277d2f37ca4ff Oct 12 03:26:22.017 INFO blam! at libcore/ops/function.rs:238 Oct 12 03:26:22.017 INFO blam! 10: 0x562001aa72ee - >::call_box::h95b7cb918af67b5d Oct 12 03:26:22.017 INFO blam! at libtest/lib.rs:1468 Oct 12 03:26:22.017 INFO blam! at libcore/ops/function.rs:238 Oct 12 03:26:22.017 INFO blam! at liballoc/boxed.rs:672 Oct 12 03:26:22.017 INFO blam! 11: 0x562001d67d19 - __rust_maybe_catch_panic Oct 12 03:26:22.017 INFO blam! at libpanic_unwind/lib.rs:102 Oct 12 03:26:22.017 INFO blam! 12: 0x562001ac7e3d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f Oct 12 03:26:22.017 INFO blam! at libstd/panicking.rs:289 Oct 12 03:26:22.017 INFO blam! at libstd/panic.rs:392 Oct 12 03:26:22.017 INFO blam! at libtest/lib.rs:1423 Oct 12 03:26:22.017 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 12 03:26:22.017 INFO blam! 13: 0x562001ac8a44 - std::panicking::try::do_call::ha990a345054c2d63 Oct 12 03:26:22.017 INFO blam! at libstd/thread/mod.rs:409 Oct 12 03:26:22.017 INFO blam! at libstd/panic.rs:313 Oct 12 03:26:22.017 INFO blam! at libstd/panicking.rs:310 Oct 12 03:26:22.017 INFO blam! 14: 0x562001d67d19 - __rust_maybe_catch_panic Oct 12 03:26:22.017 INFO blam! at libpanic_unwind/lib.rs:102 Oct 12 03:26:22.017 INFO blam! 15: 0x562001ab6006 - >::call_box::h96125cb29a9ba44b Oct 12 03:26:22.017 INFO blam! at libstd/panicking.rs:289 Oct 12 03:26:22.017 INFO blam! at libstd/panic.rs:392 Oct 12 03:26:22.017 INFO blam! at libstd/thread/mod.rs:408 Oct 12 03:26:22.017 INFO blam! at liballoc/boxed.rs:672 Oct 12 03:26:22.017 INFO blam! 16: 0x562001d559aa - std::sys_common::thread::start_thread::h97c4c5d82028f6b1 Oct 12 03:26:22.017 INFO blam! at liballoc/boxed.rs:682 Oct 12 03:26:22.017 INFO blam! at libstd/sys_common/thread.rs:24 Oct 12 03:26:22.017 INFO blam! 17: 0x562001d4a135 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886 Oct 12 03:26:22.017 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 12 03:26:22.017 INFO blam! 18: 0x7fbb608916b9 - start_thread Oct 12 03:26:22.017 INFO blam! 19: 0x7fbb603b141c - clone Oct 12 03:26:22.017 INFO blam! 20: 0x0 - Oct 12 03:26:22.017 INFO blam! Oct 12 03:26:22.017 INFO blam! Oct 12 03:26:22.017 INFO blam! failures: Oct 12 03:26:22.017 INFO blam! tests::should_correctly_calculate_oldest_allowed_ts_adjusted_to_local_tz Oct 12 03:26:22.017 INFO blam! tests::should_skip_binary_files Oct 12 03:26:22.017 INFO blam! Oct 12 03:26:22.017 INFO blam! test result: FAILED. 15 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out Oct 12 03:26:22.017 INFO blam! Oct 12 03:26:22.031 INFO kablam! error: test failed, to rerun pass '--lib' Oct 12 03:26:22.032 INFO kablam! su: No module specific data is present Oct 12 03:26:23.340 INFO running `"docker" "rm" "-f" "73c09a1bc3a53d630d844e8ac3c4db405ee7ea7c742f0a01bab146f2a6fcad97"` Oct 12 03:26:23.660 INFO blam! 73c09a1bc3a53d630d844e8ac3c4db405ee7ea7c742f0a01bab146f2a6fcad97