Dec 14 06:38:17.363 INFO testing RCasatta/ots-aggregator against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 06:38:17.363 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 14 06:38:17.626 INFO [stdout] 1925eaf14cab0e1c0cc590d417eac589676d01d0446fa04eb404e8dab080e6a2
Dec 14 06:38:17.628 INFO running `"docker" "start" "-a" "1925eaf14cab0e1c0cc590d417eac589676d01d0446fa04eb404e8dab080e6a2"`
Dec 14 06:38:18.070 INFO [stderr] usermod: no changes
Dec 14 06:38:18.281 INFO [stderr]    Compiling atty v0.2.8
Dec 14 06:38:18.284 INFO [stderr]    Compiling openssl-sys v0.9.31
Dec 14 06:38:18.285 INFO [stderr]    Compiling regex v0.2.10
Dec 14 06:38:18.285 INFO [stderr]    Compiling rust-crypto v0.2.36
Dec 14 06:38:18.285 INFO [stderr]    Compiling tokio v0.1.1
Dec 14 06:38:18.287 INFO [stderr]    Compiling hyper v0.11.22
Dec 14 06:38:19.296 INFO [stderr]    Compiling clap v2.31.1
Dec 14 06:38:21.353 INFO [stderr]    Compiling openssl v0.9.24
Dec 14 06:38:33.590 INFO [stderr]    Compiling env_logger v0.4.3
Dec 14 06:38:33.590 INFO [stderr]    Compiling env_logger v0.5.5
Dec 14 06:38:43.843 INFO [stderr]    Compiling native-tls v0.1.5
Dec 14 06:38:47.215 INFO [stderr]    Compiling opentimestamps v0.1.2
Dec 14 06:38:49.423 INFO [stderr]    Compiling tokio-tls v0.1.4
Dec 14 06:38:52.059 INFO [stderr]    Compiling hyper-tls v0.1.3
Dec 14 06:38:54.595 INFO [stderr]    Compiling ots-aggregator v0.1.0 (/source)
Dec 14 06:38:57.498 INFO [stderr] warning: a method with this name may be added to the standard library in the future
Dec 14 06:38:57.498 INFO [stderr]   --> src/merkle.rs:29:50
Dec 14 06:38:57.498 INFO [stderr]    |
Dec 14 06:38:57.498 INFO [stderr] 29 |              digests_sha256.len(), now.elapsed().as_millis(), root);
Dec 14 06:38:57.498 INFO [stderr]    |                                                  ^^^^^^^^^
Dec 14 06:38:57.498 INFO [stderr]    |
Dec 14 06:38:57.498 INFO [stderr]    = note: #[warn(unstable_name_collisions)] on by default
Dec 14 06:38:57.498 INFO [stderr]    = warning: once this method is added to the standard library, the ambiguity may cause an error or change in behavior!
Dec 14 06:38:57.498 INFO [stderr]    = note: for more information, see issue #48919 <https://github.com/rust-lang/rust/issues/48919>
Dec 14 06:38:57.498 INFO [stderr]    = help: call with fully qualified syntax `Millis::as_millis(...)` to keep using the current method
Dec 14 06:38:57.498 INFO [stderr]    = help: add #![feature(duration_as_u128)] to the crate attributes to enable `std::time::Duration::as_millis`
Dec 14 06:38:57.498 INFO [stderr] 
Dec 14 06:38:57.519 INFO [stderr] warning: a method with this name may be added to the standard library in the future
Dec 14 06:38:57.519 INFO [stderr]   --> src/timer.rs:55:60
Dec 14 06:38:57.519 INFO [stderr]    |
Dec 14 06:38:57.519 INFO [stderr] 55 |                              res.status(), start.elapsed().as_millis());
Dec 14 06:38:57.519 INFO [stderr]    |                                                            ^^^^^^^^^
Dec 14 06:38:57.519 INFO [stderr]    |
Dec 14 06:38:57.519 INFO [stderr]    = warning: once this method is added to the standard library, the ambiguity may cause an error or change in behavior!
Dec 14 06:38:57.519 INFO [stderr]    = note: for more information, see issue #48919 <https://github.com/rust-lang/rust/issues/48919>
Dec 14 06:38:57.519 INFO [stderr]    = help: call with fully qualified syntax `Millis::as_millis(...)` to keep using the current method
Dec 14 06:38:57.519 INFO [stderr]    = help: add #![feature(duration_as_u128)] to the crate attributes to enable `std::time::Duration::as_millis`
Dec 14 06:38:57.519 INFO [stderr] 
Dec 14 06:39:08.275 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 50.18s
Dec 14 06:39:08.290 INFO [stderr] su: No module specific data is present
Dec 14 06:39:09.189 INFO running `"docker" "inspect" "1925eaf14cab0e1c0cc590d417eac589676d01d0446fa04eb404e8dab080e6a2"`
Dec 14 06:39:09.383 INFO running `"docker" "rm" "-f" "1925eaf14cab0e1c0cc590d417eac589676d01d0446fa04eb404e8dab080e6a2"`
Dec 14 06:39:09.521 INFO [stdout] 1925eaf14cab0e1c0cc590d417eac589676d01d0446fa04eb404e8dab080e6a2
Dec 14 06:39:09.525 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 14 06:39:09.747 INFO [stdout] 888ffb031421f0413caf6692a2460f09147a01c0fb24cd93336ccb08e7ed39e9
Dec 14 06:39:09.750 INFO running `"docker" "start" "-a" "888ffb031421f0413caf6692a2460f09147a01c0fb24cd93336ccb08e7ed39e9"`
Dec 14 06:39:10.167 INFO [stderr] usermod: no changes
Dec 14 06:39:10.340 INFO [stderr]    Compiling ots-aggregator v0.1.0 (/source)
Dec 14 06:39:12.504 INFO [stderr] warning: unused imports: `Write`, `self`
Dec 14 06:39:12.504 INFO [stderr]  --> examples/load_testing.rs:6:15
Dec 14 06:39:12.504 INFO [stderr]   |
Dec 14 06:39:12.504 INFO [stderr] 6 | use std::io::{self, Write};
Dec 14 06:39:12.504 INFO [stderr]   |               ^^^^  ^^^^^
Dec 14 06:39:12.504 INFO [stderr]   |
Dec 14 06:39:12.504 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 14 06:39:12.504 INFO [stderr] 
Dec 14 06:39:12.563 INFO [stderr] warning: unused variable: `i`
Dec 14 06:39:12.563 INFO [stderr]   --> examples/load_testing.rs:38:9
Dec 14 06:39:12.563 INFO [stderr]    |
Dec 14 06:39:12.563 INFO [stderr] 38 |     for i in 0..100 {
Dec 14 06:39:12.563 INFO [stderr]    |         ^ help: consider using `_i` instead
Dec 14 06:39:12.563 INFO [stderr]    |
Dec 14 06:39:12.563 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Dec 14 06:39:12.563 INFO [stderr] 
Dec 14 06:39:12.583 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 14 06:39:12.583 INFO [stderr]   --> examples/load_testing.rs:22:9
Dec 14 06:39:12.583 INFO [stderr]    |
Dec 14 06:39:12.583 INFO [stderr] 22 |         start(arg1);
Dec 14 06:39:12.583 INFO [stderr]    |         ^^^^^^^^^^^^
Dec 14 06:39:12.583 INFO [stderr]    |
Dec 14 06:39:12.583 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Dec 14 06:39:12.583 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 14 06:39:12.583 INFO [stderr] 
Dec 14 06:39:13.759 INFO [stderr] warning: unused import: `merkle::Sha256Hash`
Dec 14 06:39:13.759 INFO [stderr]    --> src/merkle.rs:121:9
Dec 14 06:39:13.759 INFO [stderr]     |
Dec 14 06:39:13.759 INFO [stderr] 121 |     use merkle::Sha256Hash;
Dec 14 06:39:13.759 INFO [stderr]     |         ^^^^^^^^^^^^^^^^^^
Dec 14 06:39:13.759 INFO [stderr]     |
Dec 14 06:39:13.759 INFO [stderr]     = note: #[warn(unused_imports)] on by default
Dec 14 06:39:13.759 INFO [stderr] 
Dec 14 06:39:14.339 INFO [stderr] warning: a method with this name may be added to the standard library in the future
Dec 14 06:39:14.339 INFO [stderr]   --> src/merkle.rs:29:50
Dec 14 06:39:14.339 INFO [stderr]    |
Dec 14 06:39:14.339 INFO [stderr] 29 |              digests_sha256.len(), now.elapsed().as_millis(), root);
Dec 14 06:39:14.339 INFO [stderr]    |                                                  ^^^^^^^^^
Dec 14 06:39:14.339 INFO [stderr]    |
Dec 14 06:39:14.339 INFO [stderr]    = note: #[warn(unstable_name_collisions)] on by default
Dec 14 06:39:14.339 INFO [stderr]    = warning: once this method is added to the standard library, the ambiguity may cause an error or change in behavior!
Dec 14 06:39:14.339 INFO [stderr]    = note: for more information, see issue #48919 <https://github.com/rust-lang/rust/issues/48919>
Dec 14 06:39:14.339 INFO [stderr]    = help: call with fully qualified syntax `Millis::as_millis(...)` to keep using the current method
Dec 14 06:39:14.339 INFO [stderr]    = help: add #![feature(duration_as_u128)] to the crate attributes to enable `std::time::Duration::as_millis`
Dec 14 06:39:14.339 INFO [stderr] 
Dec 14 06:39:14.403 INFO [stderr] warning: a method with this name may be added to the standard library in the future
Dec 14 06:39:14.403 INFO [stderr]   --> src/timer.rs:55:60
Dec 14 06:39:14.403 INFO [stderr]    |
Dec 14 06:39:14.403 INFO [stderr] 55 |                              res.status(), start.elapsed().as_millis());
Dec 14 06:39:14.403 INFO [stderr]    |                                                            ^^^^^^^^^
Dec 14 06:39:14.403 INFO [stderr]    |
Dec 14 06:39:14.403 INFO [stderr]    = warning: once this method is added to the standard library, the ambiguity may cause an error or change in behavior!
Dec 14 06:39:14.403 INFO [stderr]    = note: for more information, see issue #48919 <https://github.com/rust-lang/rust/issues/48919>
Dec 14 06:39:14.403 INFO [stderr]    = help: call with fully qualified syntax `Millis::as_millis(...)` to keep using the current method
Dec 14 06:39:14.403 INFO [stderr]    = help: add #![feature(duration_as_u128)] to the crate attributes to enable `std::time::Duration::as_millis`
Dec 14 06:39:14.403 INFO [stderr] 
Dec 14 06:39:17.819 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.63s
Dec 14 06:39:17.830 INFO [stderr] su: No module specific data is present
Dec 14 06:39:18.555 INFO running `"docker" "inspect" "888ffb031421f0413caf6692a2460f09147a01c0fb24cd93336ccb08e7ed39e9"`
Dec 14 06:39:18.950 INFO running `"docker" "rm" "-f" "888ffb031421f0413caf6692a2460f09147a01c0fb24cd93336ccb08e7ed39e9"`
Dec 14 06:39:19.242 INFO [stdout] 888ffb031421f0413caf6692a2460f09147a01c0fb24cd93336ccb08e7ed39e9
Dec 14 06:39:19.323 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 14 06:39:19.811 INFO [stdout] 932d50c869c2e8d393895aec85551f6ddb43f3c7e6adbb3c863cdd99a6602034
Dec 14 06:39:19.813 INFO running `"docker" "start" "-a" "932d50c869c2e8d393895aec85551f6ddb43f3c7e6adbb3c863cdd99a6602034"`
Dec 14 06:39:21.144 INFO [stderr] usermod: no changes
Dec 14 06:39:21.302 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 14 06:39:21.319 INFO [stderr]      Running /target/debug/deps/ots_aggregator-1481dfc342ae4001
Dec 14 06:39:21.323 INFO [stdout] 
Dec 14 06:39:21.323 INFO [stdout] running 2 tests
Dec 14 06:39:21.324 INFO [stdout] test merkle::tests::test_sha256 ... ok
Dec 14 06:39:21.324 INFO [stdout] test merkle::tests::test_make ... ok
Dec 14 06:39:21.324 INFO [stdout] 
Dec 14 06:39:21.324 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 06:39:21.324 INFO [stdout] 
Dec 14 06:39:21.325 INFO [stderr]      Running /target/debug/deps/aggregator-1086b27ab7c84b4a
Dec 14 06:39:21.327 INFO [stdout] 
Dec 14 06:39:21.327 INFO [stdout] running 0 tests
Dec 14 06:39:21.327 INFO [stdout] 
Dec 14 06:39:21.327 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 06:39:21.327 INFO [stdout] 
Dec 14 06:39:21.328 INFO [stderr]    Doc-tests ots-aggregator
Dec 14 06:39:26.534 INFO [stdout] 
Dec 14 06:39:26.534 INFO [stdout] running 0 tests
Dec 14 06:39:26.534 INFO [stdout] 
Dec 14 06:39:26.534 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 06:39:26.534 INFO [stdout] 
Dec 14 06:39:26.543 INFO [stderr] su: No module specific data is present
Dec 14 06:39:27.623 INFO running `"docker" "inspect" "932d50c869c2e8d393895aec85551f6ddb43f3c7e6adbb3c863cdd99a6602034"`
Dec 14 06:39:27.940 INFO running `"docker" "rm" "-f" "932d50c869c2e8d393895aec85551f6ddb43f3c7e6adbb3c863cdd99a6602034"`
Dec 14 06:39:28.275 INFO [stdout] 932d50c869c2e8d393895aec85551f6ddb43f3c7e6adbb3c863cdd99a6602034