Dec 05 05:20:41.578 INFO testing tailhook/lithos against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 05 05:20:41.579 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 05 05:20:42.050 INFO [stdout] c37eb64724cba08edba8c1f9b721f6ed37e5df2a3874c6d5628da260e04c3fbd
Dec 05 05:20:42.053 INFO running `"docker" "start" "-a" "c37eb64724cba08edba8c1f9b721f6ed37e5df2a3874c6d5628da260e04c3fbd"`
Dec 05 05:20:43.451 INFO [stderr] usermod: no changes
Dec 05 05:20:43.827 INFO [stderr]    Compiling proc-macro2 v0.4.12
Dec 05 05:20:43.829 INFO [stderr]    Compiling atomic v0.3.4
Dec 05 05:20:43.830 INFO [stderr]    Compiling argparse v0.2.1
Dec 05 05:20:43.830 INFO [stderr]    Compiling rand v0.4.2
Dec 05 05:20:43.830 INFO [stderr]    Compiling log v0.4.3
Dec 05 05:20:43.830 INFO [stderr]    Compiling unix_socket v0.5.0
Dec 05 05:20:43.831 INFO [stderr]    Compiling scan_dir v0.3.3
Dec 05 05:20:43.850 INFO [stderr]    Compiling nix v0.11.0
Dec 05 05:20:45.560 INFO [stderr]    Compiling ssh-keys v0.1.3
Dec 05 05:20:47.622 INFO [stderr]    Compiling backtrace-sys v0.1.23
Dec 05 05:20:47.624 INFO [stderr]    Compiling serde_str v0.1.0
Dec 05 05:20:47.674 INFO [stderr]    Compiling log v0.3.9
Dec 05 05:20:47.778 INFO [stderr]    Compiling thread_local v0.3.5
Dec 05 05:20:49.018 INFO [stderr]    Compiling humannum v0.1.0
Dec 05 05:20:49.163 INFO [stderr]    Compiling digest v0.7.5
Dec 05 05:20:54.600 INFO [stderr]    Compiling backtrace v0.3.9
Dec 05 05:20:54.624 INFO [stderr]    Compiling blake2 v0.7.1
Dec 05 05:20:54.688 INFO [stderr]    Compiling sha2 v0.7.1
Dec 05 05:20:54.688 INFO [stderr]    Compiling libcantal v0.3.2
Dec 05 05:20:54.692 INFO [stderr]    Compiling fern v0.4.4
Dec 05 05:20:55.064 INFO [stderr]    Compiling syslog v3.3.0
Dec 05 05:21:00.648 INFO [stderr]    Compiling quire v0.4.1
Dec 05 05:21:02.492 INFO [stderr]    Compiling regex v1.0.2
Dec 05 05:21:02.624 INFO [stderr]    Compiling rand v0.3.22
Dec 05 05:21:02.934 INFO [stderr]    Compiling rust-crypto v0.2.36
Dec 05 05:21:07.260 INFO [stderr]    Compiling quote v0.6.6
Dec 05 05:21:08.979 INFO [stderr]    Compiling syn v0.14.8
Dec 05 05:21:28.325 INFO [stderr]    Compiling env_logger v0.5.12
Dec 05 05:21:32.774 INFO [stderr]    Compiling libmount v0.1.11
Dec 05 05:21:32.914 INFO [stderr]    Compiling unshare v0.5.0
Dec 05 05:21:33.042 INFO [stderr]    Compiling signal v0.6.0
Dec 05 05:21:48.999 INFO [stderr]    Compiling synstructure v0.9.0
Dec 05 05:21:49.001 INFO [stderr]    Compiling serde_derive v1.0.71
Dec 05 05:21:49.008 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 05 05:21:56.697 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 05 05:21:57.760 INFO [stderr]    Compiling structopt v0.2.10
Dec 05 05:22:00.136 INFO [stderr]    Compiling failure v0.1.2
Dec 05 05:22:09.811 INFO [stderr]    Compiling ipnetwork v0.13.0
Dec 05 05:22:10.966 INFO [stderr]    Compiling lithos v0.18.4 (/source)
Dec 05 05:22:30.136 INFO [stderr] warning: unused `#[macro_use]` import
Dec 05 05:22:30.136 INFO [stderr]   --> src/bin/lithos_crypt.rs:10:1
Dec 05 05:22:30.136 INFO [stderr]    |
Dec 05 05:22:30.137 INFO [stderr] 10 | #[macro_use] extern crate structopt;
Dec 05 05:22:30.137 INFO [stderr]    | ^^^^^^^^^^^^
Dec 05 05:22:30.137 INFO [stderr]    |
Dec 05 05:22:30.137 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 05 05:22:30.138 INFO [stderr] 
Dec 05 05:23:20.757 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 37s
Dec 05 05:23:20.782 INFO [stderr] su: No module specific data is present
Dec 05 05:23:21.694 INFO running `"docker" "inspect" "c37eb64724cba08edba8c1f9b721f6ed37e5df2a3874c6d5628da260e04c3fbd"`
Dec 05 05:23:22.102 INFO running `"docker" "rm" "-f" "c37eb64724cba08edba8c1f9b721f6ed37e5df2a3874c6d5628da260e04c3fbd"`
Dec 05 05:23:22.468 INFO [stdout] c37eb64724cba08edba8c1f9b721f6ed37e5df2a3874c6d5628da260e04c3fbd
Dec 05 05:23:22.476 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 05 05:23:23.001 INFO [stdout] 00469520bd754dfa09a9ca3deaf6566bb8b1448a577cd4d4feadc8f4788d96fb
Dec 05 05:23:23.003 INFO running `"docker" "start" "-a" "00469520bd754dfa09a9ca3deaf6566bb8b1448a577cd4d4feadc8f4788d96fb"`
Dec 05 05:23:24.403 INFO [stderr] usermod: no changes
Dec 05 05:23:24.701 INFO [stderr]    Compiling lithos v0.18.4 (/source)
Dec 05 05:23:27.120 INFO [stderr] warning: unused `#[macro_use]` import
Dec 05 05:23:27.120 INFO [stderr]   --> src/bin/lithos_crypt.rs:10:1
Dec 05 05:23:27.120 INFO [stderr]    |
Dec 05 05:23:27.120 INFO [stderr] 10 | #[macro_use] extern crate structopt;
Dec 05 05:23:27.120 INFO [stderr]    | ^^^^^^^^^^^^
Dec 05 05:23:27.120 INFO [stderr]    |
Dec 05 05:23:27.120 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 05 05:23:27.120 INFO [stderr] 
Dec 05 05:23:38.126 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.69s
Dec 05 05:23:38.140 INFO [stderr] su: No module specific data is present
Dec 05 05:23:39.142 INFO running `"docker" "inspect" "00469520bd754dfa09a9ca3deaf6566bb8b1448a577cd4d4feadc8f4788d96fb"`
Dec 05 05:23:39.454 INFO running `"docker" "rm" "-f" "00469520bd754dfa09a9ca3deaf6566bb8b1448a577cd4d4feadc8f4788d96fb"`
Dec 05 05:23:39.764 INFO [stdout] 00469520bd754dfa09a9ca3deaf6566bb8b1448a577cd4d4feadc8f4788d96fb
Dec 05 05:23:39.767 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 05 05:23:40.377 INFO [stdout] e90491ea7b9c9f2e7efc51e39575005ac6f993a1f1ee159c0521f307a87b3112
Dec 05 05:23:40.379 INFO running `"docker" "start" "-a" "e90491ea7b9c9f2e7efc51e39575005ac6f993a1f1ee159c0521f307a87b3112"`
Dec 05 05:23:42.065 INFO [stderr] usermod: no changes
Dec 05 05:23:42.244 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 05 05:23:42.264 INFO [stderr]      Running /target/debug/deps/lithos-6beb780f8bc1f141
Dec 05 05:23:42.264 INFO [stdout] 
Dec 05 05:23:42.264 INFO [stdout] running 16 tests
Dec 05 05:23:42.264 INFO [stdout] test child_config::test::serialize_compat ... ok
Dec 05 05:23:42.264 INFO [stdout] test child_config::test::deserialize_compat ... ok
Dec 05 05:23:42.264 INFO [stdout] test container_config::test::prefix ... ok
Dec 05 05:23:42.264 INFO [stdout] test container_config::test::two_vars ... ok
Dec 05 05:23:42.264 INFO [stdout] test container_config::test::middle ... ok
Dec 05 05:23:42.264 INFO [stdout] test container_config::test::suffix ... ok
Dec 05 05:23:42.264 INFO [stdout] test container_config::test::just_var ... ok
Dec 05 05:23:42.268 INFO [stdout] test child_config::test::serialize_vars ... ok
Dec 05 05:23:42.268 INFO [stdout] test nacl::test::test_nacl_secretbox_vectors ... ok
Dec 05 05:23:42.268 INFO [stdout] test container_config::test::correct_name ... ok
Dec 05 05:23:42.272 INFO [stdout] test nacl::test::test_nacl_box_vectors ... ok
Dec 05 05:23:42.272 INFO [stdout] test nacl::test::curve_roundtrip ... ok
Dec 05 05:23:42.276 INFO [stdout] test child_config::test::dedeserialize_vars ... ok
Dec 05 05:23:42.296 INFO [stdout] test nacl::test::test_box_seal ... ok
Dec 05 05:23:42.296 INFO [stderr]      Running /target/debug/deps/lithos_check-43038fae364c1d64
Dec 05 05:23:42.296 INFO [stdout] test nacl::test::curve_roundtrip2 ... ok
Dec 05 05:23:42.296 INFO [stdout] test nacl::test::test_box_edwards_seal ... ok
Dec 05 05:23:42.296 INFO [stdout] 
Dec 05 05:23:42.296 INFO [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.296 INFO [stdout] 
Dec 05 05:23:42.296 INFO [stdout] 
Dec 05 05:23:42.296 INFO [stderr]      Running /target/debug/deps/lithos_clean-85357fc729064c93
Dec 05 05:23:42.296 INFO [stdout] running 0 tests
Dec 05 05:23:42.296 INFO [stdout] 
Dec 05 05:23:42.296 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.296 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stderr]      Running /target/debug/deps/lithos_cmd-4654936c1a579345
Dec 05 05:23:42.312 INFO [stdout] running 0 tests
Dec 05 05:23:42.312 INFO [stderr]      Running /target/debug/deps/lithos_crypt-e11770d213e9b9c2
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stderr]      Running /target/debug/deps/lithos_knot-abede65e041e62d8
Dec 05 05:23:42.312 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] running 0 tests
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] running 0 tests
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] running 0 tests
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.312 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.312 INFO [stdout] 
Dec 05 05:23:42.315 INFO [stdout] 
Dec 05 05:23:42.315 INFO [stderr]      Running /target/debug/deps/lithos_ps-3b8caeec1f4715b1
Dec 05 05:23:42.315 INFO [stdout] running 2 tests
Dec 05 05:23:42.315 INFO [stdout] test ascii::test::test_one_node ... ok
Dec 05 05:23:42.315 INFO [stdout] test ascii::test::test_many_nodes ... ok
Dec 05 05:23:42.315 INFO [stdout] 
Dec 05 05:23:42.315 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.315 INFO [stdout] 
Dec 05 05:23:42.320 INFO [stdout] 
Dec 05 05:23:42.320 INFO [stderr]      Running /target/debug/deps/lithos_switch-e51309a3b86ee366
Dec 05 05:23:42.320 INFO [stdout] running 0 tests
Dec 05 05:23:42.321 INFO [stderr]      Running /target/debug/deps/lithos_tree-9e0581b8e7a0caa5
Dec 05 05:23:42.321 INFO [stdout] 
Dec 05 05:23:42.321 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.321 INFO [stdout] 
Dec 05 05:23:42.333 INFO [stdout] 
Dec 05 05:23:42.334 INFO [stderr]    Doc-tests lithos
Dec 05 05:23:42.334 INFO [stdout] running 0 tests
Dec 05 05:23:42.334 INFO [stdout] 
Dec 05 05:23:42.334 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:42.334 INFO [stdout] 
Dec 05 05:23:44.948 INFO [stdout] 
Dec 05 05:23:44.948 INFO [stdout] running 0 tests
Dec 05 05:23:44.948 INFO [stdout] 
Dec 05 05:23:44.948 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:44.948 INFO [stdout] 
Dec 05 05:23:44.964 INFO [stderr] su: No module specific data is present
Dec 05 05:23:45.900 INFO running `"docker" "inspect" "e90491ea7b9c9f2e7efc51e39575005ac6f993a1f1ee159c0521f307a87b3112"`
Dec 05 05:23:46.166 INFO running `"docker" "rm" "-f" "e90491ea7b9c9f2e7efc51e39575005ac6f993a1f1ee159c0521f307a87b3112"`
Dec 05 05:23:46.483 INFO [stdout] e90491ea7b9c9f2e7efc51e39575005ac6f993a1f1ee159c0521f307a87b3112