Nov 16 11:37:02.350 INFO testing tailhook/lithos against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 11:37:02.350 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 16 11:37:02.886 INFO [stdout] 2a0f98c74674fdf0a7c89390ae8ee268e573ee9b91a80a4a41c454225d14943b
Nov 16 11:37:02.889 INFO running `"docker" "start" "-a" "2a0f98c74674fdf0a7c89390ae8ee268e573ee9b91a80a4a41c454225d14943b"`
Nov 16 11:37:04.295 INFO [stderr] usermod: no changes
Nov 16 11:37:04.559 INFO [stderr]    Compiling proc-macro2 v0.4.12
Nov 16 11:37:04.559 INFO [stderr]    Compiling atomic v0.3.4
Nov 16 11:37:04.562 INFO [stderr]    Compiling log v0.4.3
Nov 16 11:37:04.562 INFO [stderr]    Compiling unix_socket v0.5.0
Nov 16 11:37:04.562 INFO [stderr]    Compiling scan_dir v0.3.3
Nov 16 11:37:04.562 INFO [stderr]    Compiling rand v0.3.22
Nov 16 11:37:04.562 INFO [stderr]    Compiling nix v0.11.0
Nov 16 11:37:04.562 INFO [stderr]    Compiling ssh-keys v0.1.3
Nov 16 11:37:05.212 INFO [stderr]    Compiling backtrace-sys v0.1.23
Nov 16 11:37:05.866 INFO [stderr]    Compiling serde_str v0.1.0
Nov 16 11:37:06.003 INFO [stderr]    Compiling rust-crypto v0.2.36
Nov 16 11:37:07.671 INFO [stderr]    Compiling humannum v0.1.0
Nov 16 11:37:07.678 INFO [stderr]    Compiling backtrace v0.3.9
Nov 16 11:37:07.695 INFO [stderr]    Compiling log v0.3.9
Nov 16 11:37:07.707 INFO [stderr]    Compiling blake2 v0.7.1
Nov 16 11:37:07.759 INFO [stderr]    Compiling env_logger v0.5.12
Nov 16 11:37:11.503 INFO [stderr]    Compiling quire v0.4.1
Nov 16 11:37:11.535 INFO [stderr]    Compiling fern v0.4.4
Nov 16 11:37:11.700 INFO [stderr]    Compiling syslog v3.3.0
Nov 16 11:37:14.347 INFO [stderr]    Compiling libcantal v0.3.2
Nov 16 11:37:24.067 INFO [stderr]    Compiling quote v0.6.6
Nov 16 11:37:26.584 INFO [stderr]    Compiling syn v0.14.8
Nov 16 11:37:39.678 INFO [stderr]    Compiling signal v0.6.0
Nov 16 11:37:39.785 INFO [stderr]    Compiling libmount v0.1.11
Nov 16 11:37:39.903 INFO [stderr]    Compiling unshare v0.5.0
Nov 16 11:37:56.276 INFO [stderr]    Compiling synstructure v0.9.0
Nov 16 11:37:56.277 INFO [stderr]    Compiling structopt-derive v0.2.10
Nov 16 11:37:56.277 INFO [stderr]    Compiling serde_derive v1.0.71
Nov 16 11:38:05.407 INFO [stderr]    Compiling failure_derive v0.1.2
Nov 16 11:38:06.849 INFO [stderr]    Compiling structopt v0.2.10
Nov 16 11:38:10.045 INFO [stderr]    Compiling failure v0.1.2
Nov 16 11:38:22.396 INFO [stderr]    Compiling ipnetwork v0.13.0
Nov 16 11:38:23.458 INFO [stderr]    Compiling lithos v0.18.4 (/source)
Nov 16 11:38:39.947 INFO [stderr] warning: unused `#[macro_use]` import
Nov 16 11:38:39.948 INFO [stderr]   --> src/bin/lithos_crypt.rs:10:1
Nov 16 11:38:39.948 INFO [stderr]    |
Nov 16 11:38:39.948 INFO [stderr] 10 | #[macro_use] extern crate structopt;
Nov 16 11:38:39.949 INFO [stderr]    | ^^^^^^^^^^^^
Nov 16 11:38:39.949 INFO [stderr]    |
Nov 16 11:38:39.949 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Nov 16 11:38:39.950 INFO [stderr] 
Nov 16 11:39:28.893 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 24s
Nov 16 11:39:28.927 INFO [stderr] su: No module specific data is present
Nov 16 11:39:29.798 INFO running `"docker" "inspect" "2a0f98c74674fdf0a7c89390ae8ee268e573ee9b91a80a4a41c454225d14943b"`
Nov 16 11:39:30.083 INFO running `"docker" "rm" "-f" "2a0f98c74674fdf0a7c89390ae8ee268e573ee9b91a80a4a41c454225d14943b"`
Nov 16 11:39:30.368 INFO [stdout] 2a0f98c74674fdf0a7c89390ae8ee268e573ee9b91a80a4a41c454225d14943b
Nov 16 11:39:30.377 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 16 11:39:30.868 INFO [stdout] b0f170515e8fb06f15c54055f61f1cc67697c2563e2c7aebafa6d2a4cbf2310a
Nov 16 11:39:30.870 INFO running `"docker" "start" "-a" "b0f170515e8fb06f15c54055f61f1cc67697c2563e2c7aebafa6d2a4cbf2310a"`
Nov 16 11:39:32.654 INFO [stderr] usermod: no changes
Nov 16 11:39:32.919 INFO [stderr]    Compiling lithos v0.18.4 (/source)
Nov 16 11:39:38.187 INFO [stderr] warning: unused `#[macro_use]` import
Nov 16 11:39:38.187 INFO [stderr]   --> src/bin/lithos_crypt.rs:10:1
Nov 16 11:39:38.187 INFO [stderr]    |
Nov 16 11:39:38.187 INFO [stderr] 10 | #[macro_use] extern crate structopt;
Nov 16 11:39:38.187 INFO [stderr]    | ^^^^^^^^^^^^
Nov 16 11:39:38.187 INFO [stderr]    |
Nov 16 11:39:38.187 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Nov 16 11:39:38.187 INFO [stderr] 
Nov 16 11:39:51.174 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.50s
Nov 16 11:39:51.199 INFO [stderr] su: No module specific data is present
Nov 16 11:39:52.655 INFO running `"docker" "inspect" "b0f170515e8fb06f15c54055f61f1cc67697c2563e2c7aebafa6d2a4cbf2310a"`
Nov 16 11:39:52.997 INFO running `"docker" "rm" "-f" "b0f170515e8fb06f15c54055f61f1cc67697c2563e2c7aebafa6d2a4cbf2310a"`
Nov 16 11:39:53.399 INFO [stdout] b0f170515e8fb06f15c54055f61f1cc67697c2563e2c7aebafa6d2a4cbf2310a
Nov 16 11:39:53.401 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 16 11:39:54.089 INFO [stdout] 3a67ebae230dea72dad87711f54ce4fa989f88ac2ca8c8852a93aa429ef3dc09
Nov 16 11:39:54.091 INFO running `"docker" "start" "-a" "3a67ebae230dea72dad87711f54ce4fa989f88ac2ca8c8852a93aa429ef3dc09"`
Nov 16 11:39:56.763 INFO [stderr] usermod: no changes
Nov 16 11:39:57.062 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.28s
Nov 16 11:39:57.077 INFO [stderr]      Running /target/debug/deps/lithos-e931e3feb4da4822
Nov 16 11:39:57.080 INFO [stdout] 
Nov 16 11:39:57.080 INFO [stdout] running 16 tests
Nov 16 11:39:57.081 INFO [stdout] test container_config::test::prefix ... ok
Nov 16 11:39:57.081 INFO [stdout] test container_config::test::middle ... ok
Nov 16 11:39:57.082 INFO [stdout] test container_config::test::just_var ... ok
Nov 16 11:39:57.119 INFO [stdout] test container_config::test::two_vars ... ok
Nov 16 11:39:57.119 INFO [stdout] test container_config::test::suffix ... ok
Nov 16 11:39:57.120 INFO [stdout] test container_config::test::correct_name ... ok
Nov 16 11:39:57.120 INFO [stdout] test child_config::test::serialize_vars ... ok
Nov 16 11:39:57.120 INFO [stdout] test child_config::test::serialize_compat ... ok
Nov 16 11:39:57.120 INFO [stdout] test child_config::test::deserialize_compat ... ok
Nov 16 11:39:57.120 INFO [stdout] test nacl::test::test_nacl_secretbox_vectors ... ok
Nov 16 11:39:57.120 INFO [stdout] test child_config::test::dedeserialize_vars ... ok
Nov 16 11:39:57.122 INFO [stdout] test nacl::test::test_nacl_box_vectors ... ok
Nov 16 11:39:57.126 INFO [stdout] test nacl::test::test_box_seal ... ok
Nov 16 11:39:57.127 INFO [stdout] test nacl::test::test_box_edwards_seal ... ok
Nov 16 11:39:57.139 INFO [stdout] test nacl::test::curve_roundtrip2 ... ok
Nov 16 11:39:57.151 INFO [stdout] test nacl::test::curve_roundtrip ... ok
Nov 16 11:39:57.151 INFO [stdout] 
Nov 16 11:39:57.151 INFO [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.151 INFO [stdout] 
Nov 16 11:39:57.155 INFO [stderr]      Running /target/debug/deps/lithos_check-d8af07a9d10d05a1
Nov 16 11:39:57.161 INFO [stdout] 
Nov 16 11:39:57.161 INFO [stdout] running 0 tests
Nov 16 11:39:57.161 INFO [stdout] 
Nov 16 11:39:57.161 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.161 INFO [stdout] 
Nov 16 11:39:57.163 INFO [stderr]      Running /target/debug/deps/lithos_clean-34fc6d8d234604a7
Nov 16 11:39:57.165 INFO [stdout] 
Nov 16 11:39:57.165 INFO [stdout] running 0 tests
Nov 16 11:39:57.165 INFO [stdout] 
Nov 16 11:39:57.165 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.165 INFO [stdout] 
Nov 16 11:39:57.166 INFO [stderr]      Running /target/debug/deps/lithos_cmd-1ee9dc307456d0bb
Nov 16 11:39:57.172 INFO [stdout] 
Nov 16 11:39:57.172 INFO [stdout] running 0 tests
Nov 16 11:39:57.172 INFO [stdout] 
Nov 16 11:39:57.172 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.172 INFO [stdout] 
Nov 16 11:39:57.179 INFO [stderr]      Running /target/debug/deps/lithos_crypt-b60bb31bfcbc532d
Nov 16 11:39:57.184 INFO [stdout] 
Nov 16 11:39:57.184 INFO [stdout] running 0 tests
Nov 16 11:39:57.184 INFO [stdout] 
Nov 16 11:39:57.184 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.184 INFO [stdout] 
Nov 16 11:39:57.185 INFO [stderr]      Running /target/debug/deps/lithos_knot-580486bb101e3573
Nov 16 11:39:57.188 INFO [stdout] 
Nov 16 11:39:57.188 INFO [stdout] running 0 tests
Nov 16 11:39:57.188 INFO [stdout] 
Nov 16 11:39:57.188 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.188 INFO [stdout] 
Nov 16 11:39:57.189 INFO [stderr]      Running /target/debug/deps/lithos_ps-ee198337aa201d55
Nov 16 11:39:57.191 INFO [stdout] 
Nov 16 11:39:57.191 INFO [stdout] running 2 tests
Nov 16 11:39:57.191 INFO [stdout] test ascii::test::test_many_nodes ... ok
Nov 16 11:39:57.191 INFO [stdout] test ascii::test::test_one_node ... ok
Nov 16 11:39:57.191 INFO [stdout] 
Nov 16 11:39:57.191 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.191 INFO [stdout] 
Nov 16 11:39:57.192 INFO [stderr]      Running /target/debug/deps/lithos_switch-0bf41e98b0c0829b
Nov 16 11:39:57.200 INFO [stdout] 
Nov 16 11:39:57.200 INFO [stdout] running 0 tests
Nov 16 11:39:57.200 INFO [stdout] 
Nov 16 11:39:57.200 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.200 INFO [stdout] 
Nov 16 11:39:57.201 INFO [stderr]      Running /target/debug/deps/lithos_tree-96f8132190ea2f7a
Nov 16 11:39:57.208 INFO [stdout] 
Nov 16 11:39:57.208 INFO [stdout] running 0 tests
Nov 16 11:39:57.208 INFO [stdout] 
Nov 16 11:39:57.208 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:57.208 INFO [stdout] 
Nov 16 11:39:57.209 INFO [stderr]    Doc-tests lithos
Nov 16 11:39:59.359 INFO [stdout] 
Nov 16 11:39:59.359 INFO [stdout] running 0 tests
Nov 16 11:39:59.360 INFO [stdout] 
Nov 16 11:39:59.360 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 11:39:59.360 INFO [stdout] 
Nov 16 11:39:59.376 INFO [stderr] su: No module specific data is present
Nov 16 11:40:00.609 INFO running `"docker" "inspect" "3a67ebae230dea72dad87711f54ce4fa989f88ac2ca8c8852a93aa429ef3dc09"`
Nov 16 11:40:00.799 INFO running `"docker" "rm" "-f" "3a67ebae230dea72dad87711f54ce4fa989f88ac2ca8c8852a93aa429ef3dc09"`
Nov 16 11:40:01.075 INFO [stdout] 3a67ebae230dea72dad87711f54ce4fa989f88ac2ca8c8852a93aa429ef3dc09