Oct 11 03:38:23.810 INFO testing achntrl/logmonitoring against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling Oct 11 03:38:23.810 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen Oct 11 03:38:23.810 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt build --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"` Oct 11 03:38:24.262 INFO blam! 1eac7f345ff52aaef6669746b2b23f9f97cdd9227b8b2d025e1c6c8e8c21e35f Oct 11 03:38:24.265 INFO running `"docker" "start" "-a" "1eac7f345ff52aaef6669746b2b23f9f97cdd9227b8b2d025e1c6c8e8c21e35f"` Oct 11 03:38:25.848 INFO kablam! usermod: no changes Oct 11 03:38:25.977 INFO kablam! Compiling chrono v0.4.3 Oct 11 03:38:29.006 INFO kablam! Compiling logmonitoring v0.1.0 (/source) Oct 11 03:38:29.773 INFO kablam! warning: unused import: `TimeZone` Oct 11 03:38:29.773 INFO kablam! --> src/consumer/alerter.rs:3:31 Oct 11 03:38:29.774 INFO kablam! | Oct 11 03:38:29.774 INFO kablam! 3 | use chrono::{DateTime, Local, TimeZone}; Oct 11 03:38:29.774 INFO kablam! | ^^^^^^^^ Oct 11 03:38:29.774 INFO kablam! | Oct 11 03:38:29.774 INFO kablam! = note: #[warn(unused_imports)] on by default Oct 11 03:38:29.775 INFO kablam! Oct 11 03:38:29.926 INFO kablam! warning: unused variable: `threshold` Oct 11 03:38:29.926 INFO kablam! --> src/consumer/alerter.rs:60:12 Oct 11 03:38:29.926 INFO kablam! | Oct 11 03:38:29.927 INFO kablam! 60 | fn new(threshold: u32) -> Alerter { Oct 11 03:38:29.927 INFO kablam! | ^^^^^^^^^ help: consider using `_threshold` instead Oct 11 03:38:29.927 INFO kablam! | Oct 11 03:38:29.928 INFO kablam! = note: #[warn(unused_variables)] on by default Oct 11 03:38:29.928 INFO kablam! Oct 11 03:38:29.960 INFO kablam! warning: field is never used: `current_alert` Oct 11 03:38:29.961 INFO kablam! --> src/consumer/alerter.rs:18:5 Oct 11 03:38:29.961 INFO kablam! | Oct 11 03:38:29.961 INFO kablam! 18 | current_alert: Option, Oct 11 03:38:29.962 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:29.962 INFO kablam! | Oct 11 03:38:29.962 INFO kablam! = note: #[warn(dead_code)] on by default Oct 11 03:38:29.962 INFO kablam! Oct 11 03:38:29.963 INFO kablam! warning: field is never used: `threshold` Oct 11 03:38:29.963 INFO kablam! --> src/consumer/alerter.rs:21:5 Oct 11 03:38:29.963 INFO kablam! | Oct 11 03:38:29.963 INFO kablam! 21 | threshold: u32, Oct 11 03:38:29.964 INFO kablam! | ^^^^^^^^^^^^^^ Oct 11 03:38:29.964 INFO kablam! Oct 11 03:38:29.964 INFO kablam! warning: method is never used: `new` Oct 11 03:38:29.965 INFO kablam! --> src/consumer/alerter.rs:25:5 Oct 11 03:38:29.965 INFO kablam! | Oct 11 03:38:29.965 INFO kablam! 25 | fn new(time: DateTime) -> Alert { Oct 11 03:38:29.965 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:29.966 INFO kablam! Oct 11 03:38:29.966 INFO kablam! warning: method is never used: `recover` Oct 11 03:38:29.966 INFO kablam! --> src/consumer/alerter.rs:34:5 Oct 11 03:38:29.967 INFO kablam! | Oct 11 03:38:29.967 INFO kablam! 34 | fn recover(&mut self) { Oct 11 03:38:29.967 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:29.967 INFO kablam! Oct 11 03:38:29.968 INFO kablam! warning: method is never used: `new` Oct 11 03:38:29.968 INFO kablam! --> src/consumer/alerter.rs:60:5 Oct 11 03:38:29.968 INFO kablam! | Oct 11 03:38:29.969 INFO kablam! 60 | fn new(threshold: u32) -> Alerter { Oct 11 03:38:29.969 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:29.969 INFO kablam! Oct 11 03:38:33.163 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 7.28s Oct 11 03:38:33.163 INFO kablam! su: No module specific data is present Oct 11 03:38:34.302 INFO running `"docker" "rm" "-f" "1eac7f345ff52aaef6669746b2b23f9f97cdd9227b8b2d025e1c6c8e8c21e35f"` Oct 11 03:38:34.588 INFO blam! 1eac7f345ff52aaef6669746b2b23f9f97cdd9227b8b2d025e1c6c8e8c21e35f Oct 11 03:38:34.595 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run Oct 11 03:38:34.595 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen --no-run" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"` Oct 11 03:38:34.960 INFO blam! ad80eab959bd89f83528db58c4b9475c1f653a4decbf3296c778565336abc398 Oct 11 03:38:34.961 INFO running `"docker" "start" "-a" "ad80eab959bd89f83528db58c4b9475c1f653a4decbf3296c778565336abc398"` Oct 11 03:38:36.483 INFO kablam! usermod: no changes Oct 11 03:38:36.578 INFO kablam! Compiling logmonitoring v0.1.0 (/source) Oct 11 03:38:37.583 INFO kablam! warning: unused import: `TimeZone` Oct 11 03:38:37.583 INFO kablam! --> src/consumer/alerter.rs:3:31 Oct 11 03:38:37.583 INFO kablam! | Oct 11 03:38:37.583 INFO kablam! 3 | use chrono::{DateTime, Local, TimeZone}; Oct 11 03:38:37.583 INFO kablam! | ^^^^^^^^ Oct 11 03:38:37.583 INFO kablam! | Oct 11 03:38:37.583 INFO kablam! = note: #[warn(unused_imports)] on by default Oct 11 03:38:37.583 INFO kablam! Oct 11 03:38:37.824 INFO kablam! warning: unused variable: `threshold` Oct 11 03:38:37.824 INFO kablam! --> src/consumer/alerter.rs:60:12 Oct 11 03:38:37.824 INFO kablam! | Oct 11 03:38:37.824 INFO kablam! 60 | fn new(threshold: u32) -> Alerter { Oct 11 03:38:37.824 INFO kablam! | ^^^^^^^^^ help: consider using `_threshold` instead Oct 11 03:38:37.824 INFO kablam! | Oct 11 03:38:37.824 INFO kablam! = note: #[warn(unused_variables)] on by default Oct 11 03:38:37.824 INFO kablam! Oct 11 03:38:37.896 INFO kablam! warning: field is never used: `current_alert` Oct 11 03:38:37.896 INFO kablam! --> src/consumer/alerter.rs:18:5 Oct 11 03:38:37.896 INFO kablam! | Oct 11 03:38:37.896 INFO kablam! 18 | current_alert: Option, Oct 11 03:38:37.896 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:37.896 INFO kablam! | Oct 11 03:38:37.896 INFO kablam! = note: #[warn(dead_code)] on by default Oct 11 03:38:37.896 INFO kablam! Oct 11 03:38:37.896 INFO kablam! warning: field is never used: `threshold` Oct 11 03:38:37.896 INFO kablam! --> src/consumer/alerter.rs:21:5 Oct 11 03:38:37.896 INFO kablam! | Oct 11 03:38:37.896 INFO kablam! 21 | threshold: u32, Oct 11 03:38:37.896 INFO kablam! | ^^^^^^^^^^^^^^ Oct 11 03:38:37.896 INFO kablam! Oct 11 03:38:37.896 INFO kablam! warning: method is never used: `new` Oct 11 03:38:37.896 INFO kablam! --> src/consumer/alerter.rs:25:5 Oct 11 03:38:37.896 INFO kablam! | Oct 11 03:38:37.897 INFO kablam! 25 | fn new(time: DateTime) -> Alert { Oct 11 03:38:37.897 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:37.897 INFO kablam! Oct 11 03:38:37.897 INFO kablam! warning: method is never used: `recover` Oct 11 03:38:37.897 INFO kablam! --> src/consumer/alerter.rs:34:5 Oct 11 03:38:37.897 INFO kablam! | Oct 11 03:38:37.897 INFO kablam! 34 | fn recover(&mut self) { Oct 11 03:38:37.897 INFO kablam! | ^^^^^^^^^^^^^^^^^^^^^ Oct 11 03:38:37.897 INFO kablam! Oct 11 03:38:39.476 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 2.95s Oct 11 03:38:39.476 INFO kablam! su: No module specific data is present Oct 11 03:38:39.792 INFO running `"docker" "rm" "-f" "ad80eab959bd89f83528db58c4b9475c1f653a4decbf3296c778565336abc398"` Oct 11 03:38:39.988 INFO blam! ad80eab959bd89f83528db58c4b9475c1f653a4decbf3296c778565336abc398 Oct 11 03:38:39.990 INFO running: cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen Oct 11 03:38:39.992 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/source:ro,Z" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,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" "CMD=cargo +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-alt test --frozen" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-m" "1536M" "--network" "none" "crater"` Oct 11 03:38:40.440 INFO blam! 3c1c8722e998804e2dd7bbd89e4a9379ad270096fa7b4f1c016a0163dbb44f9b Oct 11 03:38:40.443 INFO running `"docker" "start" "-a" "3c1c8722e998804e2dd7bbd89e4a9379ad270096fa7b4f1c016a0163dbb44f9b"` Oct 11 03:38:41.560 INFO kablam! usermod: no changes Oct 11 03:38:41.641 INFO kablam! Finished dev [unoptimized + debuginfo] target(s) in 0.05s Oct 11 03:38:41.641 INFO kablam! Running /target/debug/deps/logmonitoring-0ecbcf0736361d8b Oct 11 03:38:41.642 INFO blam! Oct 11 03:38:41.642 INFO blam! running 10 tests Oct 11 03:38:41.648 INFO blam! test consumer::errorwatcher::test::should_ingest_errors ... ok Oct 11 03:38:41.649 INFO blam! test consumer::ranker::test::should_ingest_sections_from_root_url ... ok Oct 11 03:38:41.649 INFO blam! test consumer::ranker::test::should_ingest_sections_from_long_url ... ok Oct 11 03:38:41.649 INFO blam! test consumer::ranker::test::should_ingest_host ... ok Oct 11 03:38:41.654 INFO blam! test consumer::ranker::test::should_rank ... ok Oct 11 03:38:41.669 INFO blam! test consumer::ranker::test::should_ingest_sections_from_simple_url ... ok Oct 11 03:38:41.672 INFO blam! test consumer::ranker::test::should_not_ingest_empty_host ... ok Oct 11 03:38:41.672 INFO blam! test parser::test::should_not_parse_an_invalid_log_line ... ok Oct 11 03:38:41.679 INFO blam! test parser::test::should_parse_a_valid_log_line ... ok Oct 11 03:38:41.711 INFO blam! test consumer::alerter::test::should_ingest_dates ... FAILED Oct 11 03:38:41.711 INFO blam! Oct 11 03:38:41.711 INFO blam! failures: Oct 11 03:38:41.711 INFO blam! Oct 11 03:38:41.711 INFO blam! ---- consumer::alerter::test::should_ingest_dates stdout ---- Oct 11 03:38:41.711 INFO blam! thread 'consumer::alerter::test::should_ingest_dates' panicked at 'assertion failed: `(left == right)` Oct 11 03:38:41.711 INFO blam! left: `[2018-06-05T18:55:45+00:00]`, Oct 11 03:38:41.711 INFO blam! right: `[2018-06-05T20:55:45+00:00]`', src/consumer/alerter.rs:98:9 Oct 11 03:38:41.711 INFO blam! stack backtrace: Oct 11 03:38:41.711 INFO blam! 0: 0x562b23d9cb5e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h52ace5b3d4e95617 Oct 11 03:38:41.711 INFO blam! at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Oct 11 03:38:41.711 INFO blam! 1: 0x562b23da5a06 - std::sys_common::backtrace::print::h6cfc8a66009c1cc9 Oct 11 03:38:41.711 INFO blam! at libstd/sys_common/backtrace.rs:71 Oct 11 03:38:41.711 INFO blam! at libstd/sys_common/backtrace.rs:59 Oct 11 03:38:41.711 INFO blam! 2: 0x562b23da075d - std::panicking::default_hook::{{closure}}::h47bedf778bf88480 Oct 11 03:38:41.711 INFO blam! at libstd/panicking.rs:211 Oct 11 03:38:41.711 INFO blam! 3: 0x562b23da046b - std::panicking::default_hook::h1ef46fa7cd894809 Oct 11 03:38:41.711 INFO blam! at libstd/panicking.rs:221 Oct 11 03:38:41.711 INFO blam! 4: 0x562b23da0e0c - std::panicking::rust_panic_with_hook::h05510fde8841c579 Oct 11 03:38:41.711 INFO blam! at libstd/panicking.rs:476 Oct 11 03:38:41.711 INFO blam! 5: 0x562b23da09c9 - std::panicking::continue_panic_fmt::h6e11f50e47f94b00 Oct 11 03:38:41.711 INFO blam! at libstd/panicking.rs:390 Oct 11 03:38:41.711 INFO blam! 6: 0x562b23da092d - std::panicking::begin_panic_fmt::h0063f49bf5a35411 Oct 11 03:38:41.711 INFO blam! at libstd/panicking.rs:345 Oct 11 03:38:41.711 INFO blam! 7: 0x562b23b908f0 - logmonitoring::consumer::alerter::test::should_ingest_dates::h74cb34d244f56ba6 Oct 11 03:38:41.711 INFO blam! at src/consumer/alerter.rs:98 Oct 11 03:38:41.711 INFO blam! 8: 0x562b23b904e9 - logmonitoring::consumer::alerter::test::should_ingest_dates::{{closure}}::h186a5b4e33e0d3e8 Oct 11 03:38:41.711 INFO blam! at src/consumer/alerter.rs:88 Oct 11 03:38:41.711 INFO blam! 9: 0x562b23b8c0cd - core::ops::function::FnOnce::call_once::h5d01bfa14b67f628 Oct 11 03:38:41.711 INFO blam! at libcore/ops/function.rs:238 Oct 11 03:38:41.711 INFO blam! 10: 0x562b23ba222e - >::call_box::h95b7cb918af67b5d Oct 11 03:38:41.711 INFO blam! at libtest/lib.rs:1468 Oct 11 03:38:41.711 INFO blam! at libcore/ops/function.rs:238 Oct 11 03:38:41.711 INFO blam! at liballoc/boxed.rs:672 Oct 11 03:38:41.712 INFO blam! 11: 0x562b23db0bd9 - __rust_maybe_catch_panic Oct 11 03:38:41.712 INFO blam! at libpanic_unwind/lib.rs:102 Oct 11 03:38:41.712 INFO blam! 12: 0x562b23bc2d7d - std::sys_common::backtrace::__rust_begin_short_backtrace::he1120aab928e481f Oct 11 03:38:41.712 INFO blam! at libstd/panicking.rs:289 Oct 11 03:38:41.712 INFO blam! at libstd/panic.rs:392 Oct 11 03:38:41.712 INFO blam! at libtest/lib.rs:1423 Oct 11 03:38:41.712 INFO blam! at libstd/sys_common/backtrace.rs:136 Oct 11 03:38:41.712 INFO blam! 13: 0x562b23bc3984 - std::panicking::try::do_call::ha990a345054c2d63 Oct 11 03:38:41.712 INFO blam! at libstd/thread/mod.rs:409 Oct 11 03:38:41.712 INFO blam! at libstd/panic.rs:313 Oct 11 03:38:41.712 INFO blam! at libstd/panicking.rs:310 Oct 11 03:38:41.712 INFO blam! 14: 0x562b23db0bd9 - __rust_maybe_catch_panic Oct 11 03:38:41.712 INFO blam! at libpanic_unwind/lib.rs:102 Oct 11 03:38:41.712 INFO blam! 15: 0x562b23bb0f46 - >::call_box::h96125cb29a9ba44b Oct 11 03:38:41.712 INFO blam! at libstd/panicking.rs:289 Oct 11 03:38:41.712 INFO blam! at libstd/panic.rs:392 Oct 11 03:38:41.712 INFO blam! at libstd/thread/mod.rs:408 Oct 11 03:38:41.712 INFO blam! at liballoc/boxed.rs:672 Oct 11 03:38:41.712 INFO blam! 16: 0x562b23d9eb9a - std::sys_common::thread::start_thread::h97c4c5d82028f6b1 Oct 11 03:38:41.712 INFO blam! at liballoc/boxed.rs:682 Oct 11 03:38:41.712 INFO blam! at libstd/sys_common/thread.rs:24 Oct 11 03:38:41.712 INFO blam! 17: 0x562b23d942e5 - std::sys::unix::thread::Thread::new::thread_start::he7d65efc4dd16886 Oct 11 03:38:41.712 INFO blam! at libstd/sys/unix/thread.rs:90 Oct 11 03:38:41.712 INFO blam! 18: 0x7f77989b16b9 - start_thread Oct 11 03:38:41.712 INFO blam! 19: 0x7f77984d141c - clone Oct 11 03:38:41.712 INFO blam! 20: 0x0 - Oct 11 03:38:41.712 INFO blam! Oct 11 03:38:41.712 INFO blam! Oct 11 03:38:41.712 INFO blam! failures: Oct 11 03:38:41.712 INFO blam! consumer::alerter::test::should_ingest_dates Oct 11 03:38:41.712 INFO blam! Oct 11 03:38:41.712 INFO blam! test result: FAILED. 9 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out Oct 11 03:38:41.712 INFO blam! Oct 11 03:38:41.715 INFO kablam! error: test failed, to rerun pass '--lib' Oct 11 03:38:41.716 INFO kablam! su: No module specific data is present Oct 11 03:38:42.311 INFO running `"docker" "rm" "-f" "3c1c8722e998804e2dd7bbd89e4a9379ad270096fa7b4f1c016a0163dbb44f9b"` Oct 11 03:38:42.455 INFO blam! 3c1c8722e998804e2dd7bbd89e4a9379ad270096fa7b4f1c016a0163dbb44f9b