Dec 05 05:20:44.141 INFO testing tailhook/lithos against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 05 05:20:44.141 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:44.627 INFO [stdout] 332ae9cbefca7d101ad5a71c75a8f3bbc54f2f8c517bda6675a76c74bda1bb8f
Dec 05 05:20:44.629 INFO running `"docker" "start" "-a" "332ae9cbefca7d101ad5a71c75a8f3bbc54f2f8c517bda6675a76c74bda1bb8f"`
Dec 05 05:20:46.708 INFO [stderr] usermod: no changes
Dec 05 05:20:47.026 INFO [stderr]    Compiling atomic v0.3.4
Dec 05 05:20:47.048 INFO [stderr]    Compiling termcolor v1.0.1
Dec 05 05:20:47.049 INFO [stderr]    Compiling argparse v0.2.1
Dec 05 05:20:47.075 INFO [stderr]    Compiling log v0.4.3
Dec 05 05:20:47.076 INFO [stderr]    Compiling unix_socket v0.5.0
Dec 05 05:20:47.080 INFO [stderr]    Compiling scan_dir v0.3.3
Dec 05 05:20:47.080 INFO [stderr]    Compiling base64 v0.9.2
Dec 05 05:20:47.081 INFO [stderr]    Compiling rand v0.3.22
Dec 05 05:20:48.583 INFO [stderr]    Compiling nix v0.11.0
Dec 05 05:20:49.248 INFO [stderr]    Compiling backtrace-sys v0.1.23
Dec 05 05:20:51.177 INFO [stderr]    Compiling serde_json v1.0.24
Dec 05 05:20:51.468 INFO [stderr]    Compiling serde_str v0.1.0
Dec 05 05:20:51.496 INFO [stderr]    Compiling rust-crypto v0.2.36
Dec 05 05:20:51.545 INFO [stderr]    Compiling syn v0.14.8
Dec 05 05:20:54.132 INFO [stderr]    Compiling thread_local v0.3.5
Dec 05 05:21:00.804 INFO [stderr]    Compiling digest v0.7.5
Dec 05 05:21:00.804 INFO [stderr]    Compiling backtrace v0.3.9
Dec 05 05:21:00.932 INFO [stderr]    Compiling humannum v0.1.0
Dec 05 05:21:01.085 INFO [stderr]    Compiling log v0.3.9
Dec 05 05:21:03.199 INFO [stderr]    Compiling ssh-keys v0.1.3
Dec 05 05:21:03.205 INFO [stderr]    Compiling regex v1.0.2
Dec 05 05:21:03.264 INFO [stderr]    Compiling sha2 v0.7.1
Dec 05 05:21:03.276 INFO [stderr]    Compiling blake2 v0.7.1
Dec 05 05:21:11.440 INFO [stderr]    Compiling quire v0.4.1
Dec 05 05:21:11.572 INFO [stderr]    Compiling fern v0.4.4
Dec 05 05:21:11.854 INFO [stderr]    Compiling syslog v3.3.0
Dec 05 05:21:12.192 INFO [stderr]    Compiling libcantal v0.3.2
Dec 05 05:21:44.563 INFO [stderr]    Compiling env_logger v0.5.12
Dec 05 05:21:45.198 INFO [stderr]    Compiling signal v0.6.0
Dec 05 05:21:45.200 INFO [stderr]    Compiling libmount v0.1.11
Dec 05 05:21:45.201 INFO [stderr]    Compiling unshare v0.5.0
Dec 05 05:21:48.481 INFO [stderr]    Compiling synstructure v0.9.0
Dec 05 05:21:48.481 INFO [stderr]    Compiling structopt-derive v0.2.10
Dec 05 05:21:48.481 INFO [stderr]    Compiling serde_derive v1.0.71
Dec 05 05:21:59.960 INFO [stderr]    Compiling failure_derive v0.1.2
Dec 05 05:22:01.568 INFO [stderr]    Compiling structopt v0.2.10
Dec 05 05:22:03.447 INFO [stderr]    Compiling failure v0.1.2
Dec 05 05:22:14.335 INFO [stderr]    Compiling ipnetwork v0.13.0
Dec 05 05:22:15.553 INFO [stderr]    Compiling lithos v0.18.4 (/source)
Dec 05 05:22:33.824 INFO [stderr] warning: unused `#[macro_use]` import
Dec 05 05:22:33.825 INFO [stderr]   --> src/bin/lithos_crypt.rs:10:1
Dec 05 05:22:33.825 INFO [stderr]    |
Dec 05 05:22:33.825 INFO [stderr] 10 | #[macro_use] extern crate structopt;
Dec 05 05:22:33.826 INFO [stderr]    | ^^^^^^^^^^^^
Dec 05 05:22:33.826 INFO [stderr]    |
Dec 05 05:22:33.826 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 05 05:22:33.827 INFO [stderr] 
Dec 05 05:23:24.641 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 37s
Dec 05 05:23:24.656 INFO [stderr] su: No module specific data is present
Dec 05 05:23:25.643 INFO running `"docker" "inspect" "332ae9cbefca7d101ad5a71c75a8f3bbc54f2f8c517bda6675a76c74bda1bb8f"`
Dec 05 05:23:25.990 INFO running `"docker" "rm" "-f" "332ae9cbefca7d101ad5a71c75a8f3bbc54f2f8c517bda6675a76c74bda1bb8f"`
Dec 05 05:23:26.256 INFO [stdout] 332ae9cbefca7d101ad5a71c75a8f3bbc54f2f8c517bda6675a76c74bda1bb8f
Dec 05 05:23:26.260 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:26.857 INFO [stdout] fddc30b461164115b9cc318d82ba68ce604717e9b7431955aad013c371168e97
Dec 05 05:23:26.864 INFO running `"docker" "start" "-a" "fddc30b461164115b9cc318d82ba68ce604717e9b7431955aad013c371168e97"`
Dec 05 05:23:28.221 INFO [stderr] usermod: no changes
Dec 05 05:23:28.493 INFO [stderr]    Compiling lithos v0.18.4 (/source)
Dec 05 05:23:34.792 INFO [stderr] warning: unused `#[macro_use]` import
Dec 05 05:23:34.792 INFO [stderr]   --> src/bin/lithos_crypt.rs:10:1
Dec 05 05:23:34.792 INFO [stderr]    |
Dec 05 05:23:34.792 INFO [stderr] 10 | #[macro_use] extern crate structopt;
Dec 05 05:23:34.792 INFO [stderr]    | ^^^^^^^^^^^^
Dec 05 05:23:34.792 INFO [stderr]    |
Dec 05 05:23:34.792 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 05 05:23:34.792 INFO [stderr] 
Dec 05 05:23:45.440 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.19s
Dec 05 05:23:45.456 INFO [stderr] su: No module specific data is present
Dec 05 05:23:46.462 INFO running `"docker" "inspect" "fddc30b461164115b9cc318d82ba68ce604717e9b7431955aad013c371168e97"`
Dec 05 05:23:46.752 INFO running `"docker" "rm" "-f" "fddc30b461164115b9cc318d82ba68ce604717e9b7431955aad013c371168e97"`
Dec 05 05:23:47.074 INFO [stdout] fddc30b461164115b9cc318d82ba68ce604717e9b7431955aad013c371168e97
Dec 05 05:23:47.078 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-0/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:47.512 INFO [stdout] a27f9597dd0a3e79d8adfeb751c61aefe73b8017ef86f9212627b3893ffecc11
Dec 05 05:23:47.520 INFO running `"docker" "start" "-a" "a27f9597dd0a3e79d8adfeb751c61aefe73b8017ef86f9212627b3893ffecc11"`
Dec 05 05:23:48.648 INFO [stderr] usermod: no changes
Dec 05 05:23:48.872 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Dec 05 05:23:48.892 INFO [stderr]      Running /target/debug/deps/lithos-d29afdc2f57cb7f5
Dec 05 05:23:48.900 INFO [stdout] 
Dec 05 05:23:48.900 INFO [stdout] running 16 tests
Dec 05 05:23:48.900 INFO [stdout] test child_config::test::dedeserialize_vars ... ok
Dec 05 05:23:48.900 INFO [stdout] test child_config::test::serialize_vars ... ok
Dec 05 05:23:48.900 INFO [stdout] test container_config::test::prefix ... ok
Dec 05 05:23:48.900 INFO [stdout] test container_config::test::middle ... ok
Dec 05 05:23:48.900 INFO [stdout] test child_config::test::serialize_compat ... ok
Dec 05 05:23:48.900 INFO [stdout] test container_config::test::just_var ... ok
Dec 05 05:23:48.900 INFO [stdout] test container_config::test::correct_name ... ok
Dec 05 05:23:48.900 INFO [stdout] test child_config::test::deserialize_compat ... ok
Dec 05 05:23:48.900 INFO [stdout] test container_config::test::suffix ... ok
Dec 05 05:23:48.900 INFO [stdout] test container_config::test::two_vars ... ok
Dec 05 05:23:48.900 INFO [stdout] test nacl::test::test_nacl_secretbox_vectors ... ok
Dec 05 05:23:48.920 INFO [stdout] test nacl::test::test_box_edwards_seal ... ok
Dec 05 05:23:48.928 INFO [stdout] test nacl::test::test_box_seal ... ok
Dec 05 05:23:48.928 INFO [stdout] test nacl::test::test_nacl_box_vectors ... ok
Dec 05 05:23:48.928 INFO [stdout] test nacl::test::curve_roundtrip ... ok
Dec 05 05:23:48.928 INFO [stdout] test nacl::test::curve_roundtrip2 ... ok
Dec 05 05:23:48.928 INFO [stdout] 
Dec 05 05:23:48.928 INFO [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.928 INFO [stdout] 
Dec 05 05:23:48.928 INFO [stderr]      Running /target/debug/deps/lithos_check-10f2ccdaf334862b
Dec 05 05:23:48.936 INFO [stdout] 
Dec 05 05:23:48.936 INFO [stdout] running 0 tests
Dec 05 05:23:48.936 INFO [stdout] 
Dec 05 05:23:48.936 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.936 INFO [stdout] 
Dec 05 05:23:48.936 INFO [stderr]      Running /target/debug/deps/lithos_clean-01b7f035473beb61
Dec 05 05:23:48.944 INFO [stdout] 
Dec 05 05:23:48.944 INFO [stdout] running 0 tests
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stderr]      Running /target/debug/deps/lithos_cmd-ace9ec6f314c6730
Dec 05 05:23:48.948 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.948 INFO [stderr]      Running /target/debug/deps/lithos_crypt-b485aa536e35ad9d
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stdout] running 0 tests
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stdout] running 0 tests
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.948 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.948 INFO [stdout] 
Dec 05 05:23:48.949 INFO [stderr]      Running /target/debug/deps/lithos_knot-b68688617c258485
Dec 05 05:23:48.951 INFO [stdout] 
Dec 05 05:23:48.951 INFO [stdout] running 0 tests
Dec 05 05:23:48.951 INFO [stdout] 
Dec 05 05:23:48.951 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.951 INFO [stdout] 
Dec 05 05:23:48.968 INFO [stderr]      Running /target/debug/deps/lithos_ps-9fb430c97c155f64
Dec 05 05:23:48.972 INFO [stdout] 
Dec 05 05:23:48.972 INFO [stdout] running 2 tests
Dec 05 05:23:48.976 INFO [stdout] test ascii::test::test_many_nodes ... ok
Dec 05 05:23:48.976 INFO [stderr]      Running /target/debug/deps/lithos_switch-c9acb47fba50c4d6
Dec 05 05:23:48.976 INFO [stdout] test ascii::test::test_one_node ... ok
Dec 05 05:23:48.976 INFO [stdout] 
Dec 05 05:23:48.976 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.976 INFO [stdout] 
Dec 05 05:23:48.984 INFO [stdout] 
Dec 05 05:23:48.984 INFO [stdout] running 0 tests
Dec 05 05:23:48.984 INFO [stdout] 
Dec 05 05:23:48.984 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.984 INFO [stdout] 
Dec 05 05:23:48.988 INFO [stderr]      Running /target/debug/deps/lithos_tree-5eb0664ee043bd28
Dec 05 05:23:48.992 INFO [stdout] 
Dec 05 05:23:48.992 INFO [stdout] running 0 tests
Dec 05 05:23:48.992 INFO [stdout] 
Dec 05 05:23:48.992 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:48.992 INFO [stdout] 
Dec 05 05:23:48.996 INFO [stderr]    Doc-tests lithos
Dec 05 05:23:51.675 INFO [stdout] 
Dec 05 05:23:51.675 INFO [stdout] running 0 tests
Dec 05 05:23:51.675 INFO [stdout] 
Dec 05 05:23:51.675 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 05 05:23:51.675 INFO [stdout] 
Dec 05 05:23:51.688 INFO [stderr] su: No module specific data is present
Dec 05 05:23:52.944 INFO running `"docker" "inspect" "a27f9597dd0a3e79d8adfeb751c61aefe73b8017ef86f9212627b3893ffecc11"`
Dec 05 05:23:53.278 INFO running `"docker" "rm" "-f" "a27f9597dd0a3e79d8adfeb751c61aefe73b8017ef86f9212627b3893ffecc11"`
Dec 05 05:23:53.596 INFO [stdout] a27f9597dd0a3e79d8adfeb751c61aefe73b8017ef86f9212627b3893ffecc11