Dec 02 01:35:12.501 INFO testing advancedtelematic/ota-plus-tools against 1.30.1 for beta-1.31-5
Dec 02 01:35:12.501 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-0/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 01:35:12.936 INFO [stdout] f73a0f89e76ec00db34811b1cc4c1321d5ea053f94ae84e7833841e5d01ff019
Dec 02 01:35:12.939 INFO running `"docker" "start" "-a" "f73a0f89e76ec00db34811b1cc4c1321d5ea053f94ae84e7833841e5d01ff019"`
Dec 02 01:35:14.527 INFO [stderr] usermod: no changes
Dec 02 01:35:14.846 INFO [stderr]    Compiling futures v0.1.15
Dec 02 01:35:14.858 INFO [stderr]    Compiling iovec v0.1.0
Dec 02 01:35:14.858 INFO [stderr]    Compiling num_cpus v1.6.2
Dec 02 01:35:14.863 INFO [stderr]    Compiling memchr v1.0.1
Dec 02 01:35:14.863 INFO [stderr]    Compiling time v0.1.38
Dec 02 01:35:14.863 INFO [stderr]    Compiling term_size v0.3.0
Dec 02 01:35:14.863 INFO [stderr]    Compiling atty v0.2.2
Dec 02 01:35:14.863 INFO [stderr]    Compiling net2 v0.2.31
Dec 02 01:35:17.059 INFO [stderr]    Compiling subtle v0.2.0
Dec 02 01:35:17.419 INFO [stderr]    Compiling toml v0.4.5
Dec 02 01:35:17.641 INFO [stderr]    Compiling serde_json v1.0.2
Dec 02 01:35:21.869 INFO [stderr]    Compiling derp v0.0.10
Dec 02 01:35:22.380 INFO [stderr]    Compiling uuid v0.5.1
Dec 02 01:35:22.535 INFO [stderr]    Compiling tempdir v0.3.5
Dec 02 01:35:27.743 INFO [stderr]    Compiling textwrap v0.7.0
Dec 02 01:35:27.835 INFO [stderr]    Compiling bytes v0.4.5
Dec 02 01:35:29.210 INFO [stderr]    Compiling thread_local v0.3.4
Dec 02 01:35:29.210 INFO [stderr]    Compiling aho-corasick v0.6.3
Dec 02 01:35:29.245 INFO [stderr]    Compiling backtrace-sys v0.1.12
Dec 02 01:35:31.991 INFO [stderr]    Compiling openssl-sys v0.9.17
Dec 02 01:35:40.414 INFO [stderr]    Compiling mio v0.6.10
Dec 02 01:35:40.471 INFO [stderr]    Compiling backtrace v0.3.3
Dec 02 01:35:40.473 INFO [stderr]    Compiling regex v0.2.2
Dec 02 01:35:40.479 INFO [stderr]    Compiling clap v2.26.0
Dec 02 01:35:40.755 INFO [stderr]    Compiling openssl v0.9.17
Dec 02 01:35:40.755 INFO [stderr]    Compiling serde_urlencoded v0.5.1
Dec 02 01:35:42.961 INFO [stderr]    Compiling chrono v0.4.0
Dec 02 01:35:43.159 INFO [stderr]    Compiling curve25519-dalek v0.10.0
Dec 02 01:35:47.055 INFO [stderr]    Compiling tokio-io v0.1.3
Dec 02 01:35:49.298 INFO [stderr]    Compiling rayon-core v1.2.1
Dec 02 01:35:58.319 INFO [stderr]    Compiling tokio-service v0.1.0
Dec 02 01:36:02.440 INFO [stderr]    Compiling futures-cpupool v0.1.5
Dec 02 01:36:02.448 INFO [stderr]    Compiling error-chain v0.10.0
Dec 02 01:36:02.508 INFO [stderr]    Compiling tokio-core v0.1.9
Dec 02 01:36:02.553 INFO [stderr]    Compiling rayon v0.7.1
Dec 02 01:36:12.911 INFO [stderr]    Compiling native-tls v0.1.4
Dec 02 01:36:18.480 INFO [stderr]    Compiling ring v0.11.0
Dec 02 01:36:19.180 INFO [stderr]    Compiling tokio-proto v0.1.1
Dec 02 01:36:19.180 INFO [stderr]    Compiling tokio-tls v0.1.3
Dec 02 01:36:19.581 INFO [stderr]    Compiling pem v0.4.1
Dec 02 01:36:19.581 INFO [stderr]    Compiling env_logger v0.4.3
Dec 02 01:36:30.867 INFO [stderr]    Compiling hyper v0.11.2
Dec 02 01:36:53.535 INFO [stderr]    Compiling hyper-tls v0.1.2
Dec 02 01:36:55.665 INFO [stderr]    Compiling reqwest v0.7.3
Dec 02 01:37:03.923 INFO [stderr]    Compiling ota-plus v0.0.1 (/source)
Dec 02 01:37:20.958 INFO [stderr] warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
Dec 02 01:37:20.959 INFO [stderr]   --> src/bin/ota_plus.rs:75:21
Dec 02 01:37:20.959 INFO [stderr]    |
Dec 02 01:37:20.959 INFO [stderr] 75 |         .or_else(|| env::home_dir().map(|path| path.join(".ota-plus")))
Dec 02 01:37:20.960 INFO [stderr]    |                     ^^^^^^^^^^^^^
Dec 02 01:37:20.960 INFO [stderr]    |
Dec 02 01:37:20.960 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 02 01:37:20.960 INFO [stderr] 
Dec 02 01:37:29.355 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 14s
Dec 02 01:37:29.386 INFO [stderr] su: No module specific data is present
Dec 02 01:37:30.460 INFO running `"docker" "inspect" "f73a0f89e76ec00db34811b1cc4c1321d5ea053f94ae84e7833841e5d01ff019"`
Dec 02 01:37:30.793 INFO running `"docker" "rm" "-f" "f73a0f89e76ec00db34811b1cc4c1321d5ea053f94ae84e7833841e5d01ff019"`
Dec 02 01:37:31.117 INFO [stdout] f73a0f89e76ec00db34811b1cc4c1321d5ea053f94ae84e7833841e5d01ff019
Dec 02 01:37:31.127 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-0/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 01:37:31.520 INFO [stdout] 1dda926e6660f748901b09fdc4f9f09875e8306d9a63396e54d742775c4b0fbf
Dec 02 01:37:31.523 INFO running `"docker" "start" "-a" "1dda926e6660f748901b09fdc4f9f09875e8306d9a63396e54d742775c4b0fbf"`
Dec 02 01:37:33.203 INFO [stderr] usermod: no changes
Dec 02 01:37:33.475 INFO [stderr]    Compiling ota-plus v0.0.1 (/source)
Dec 02 01:37:41.683 INFO [stderr] warning: use of deprecated item 'std::env::home_dir': This function's behavior is unexpected and probably not what you want. Consider using the home_dir function from https://crates.io/crates/dirs instead.
Dec 02 01:37:41.683 INFO [stderr]   --> src/bin/ota_plus.rs:75:21
Dec 02 01:37:41.683 INFO [stderr]    |
Dec 02 01:37:41.683 INFO [stderr] 75 |         .or_else(|| env::home_dir().map(|path| path.join(".ota-plus")))
Dec 02 01:37:41.683 INFO [stderr]    |                     ^^^^^^^^^^^^^
Dec 02 01:37:41.683 INFO [stderr]    |
Dec 02 01:37:41.683 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 02 01:37:41.683 INFO [stderr] 
Dec 02 01:37:52.042 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.81s
Dec 02 01:37:52.067 INFO [stderr] su: No module specific data is present
Dec 02 01:37:52.761 INFO running `"docker" "inspect" "1dda926e6660f748901b09fdc4f9f09875e8306d9a63396e54d742775c4b0fbf"`
Dec 02 01:37:52.991 INFO running `"docker" "rm" "-f" "1dda926e6660f748901b09fdc4f9f09875e8306d9a63396e54d742775c4b0fbf"`
Dec 02 01:37:53.265 INFO [stdout] 1dda926e6660f748901b09fdc4f9f09875e8306d9a63396e54d742775c4b0fbf
Dec 02 01:37:53.265 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-0/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 01:37:53.558 INFO [stdout] 341b28ef0ddf23a60956dd91d197b5a1641fb5de915ad36b18ba9e145db94c50
Dec 02 01:37:53.561 INFO running `"docker" "start" "-a" "341b28ef0ddf23a60956dd91d197b5a1641fb5de915ad36b18ba9e145db94c50"`
Dec 02 01:37:54.665 INFO [stderr] usermod: no changes
Dec 02 01:37:54.999 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.29s
Dec 02 01:37:55.028 INFO [stderr]      Running /target/debug/deps/ota_plus-34a5f0840bae904c
Dec 02 01:37:55.042 INFO [stdout] 
Dec 02 01:37:55.042 INFO [stdout] running 12 tests
Dec 02 01:37:55.080 INFO [stdout] test crypto::test::ed25519_as_public_key ... ok
Dec 02 01:37:55.080 INFO [stdout] test crypto::test::extract_pkcs1_from_rsa_2048_pkcs8 ... ok
Dec 02 01:37:55.080 INFO [stdout] test crypto::test::extract_pkcs1_from_rsa_4096_pkcs8 ... ok
Dec 02 01:37:55.112 INFO [stdout] test crypto::test::test_rsa_spki_to_pkcs1 ... ok
Dec 02 01:37:55.112 INFO [stdout] test crypto::test::test_pkcs8_wrap_rsa ... ok
Dec 02 01:37:55.119 INFO [stdout] test interchange::cjson::test::write_obj ... ok
Dec 02 01:37:55.123 INFO [stdout] test interchange::cjson::test::write_str ... ok
Dec 02 01:37:55.126 INFO [stdout] test interchange::cjson::test::write_arr ... ok
Dec 02 01:37:55.141 INFO [stdout] test crypto::test::rsa_as_public_key ... ok
Dec 02 01:37:55.182 INFO [stdout] test crypto::test::rsa_from_pkcs1 ... ok
Dec 02 01:37:56.091 INFO [stdout] test crypto::test::new_rsa_key ... ok
Dec 02 01:37:56.332 INFO [stderr]      Running /target/debug/deps/ota_plus-21f821e816cf44dc
Dec 02 01:37:56.335 INFO [stdout] test crypto::test::ring_to_bouncy_castle_conversion ... ok
Dec 02 01:37:56.335 INFO [stdout] 
Dec 02 01:37:56.335 INFO [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 01:37:56.335 INFO [stdout] 
Dec 02 01:37:56.335 INFO [stdout] 
Dec 02 01:37:56.335 INFO [stdout] running 5 tests
Dec 02 01:37:56.335 INFO [stdout] test test::check_parser ... ok
Dec 02 01:37:56.340 INFO [stdout] test test::init ... ok
Dec 02 01:37:56.343 INFO [stdout] test test::tuf_root_init ... ok
Dec 02 01:37:56.346 INFO [stdout] test test::tuf_key_gen_ed25519 ... ok
Dec 02 01:37:56.583 INFO [stdout] test test::tuf_key_gen_rsa ... ok
Dec 02 01:37:56.583 INFO [stdout] 
Dec 02 01:37:56.583 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 01:37:56.583 INFO [stdout] 
Dec 02 01:37:56.583 INFO [stderr]    Doc-tests ota_plus
Dec 02 01:38:03.646 INFO [stdout] 
Dec 02 01:38:03.646 INFO [stdout] running 9 tests
Dec 02 01:38:52.683 INFO [stdout] test src/tuf.rs - tuf::TargetDescription::from_reader (line 457) ... ok
Dec 02 01:39:03.651 INFO [stdout] test src/interchange/mod.rs - interchange::Json::canonicalize (line 88) ... test src/interchange/mod.rs - interchange::Json::canonicalize (line 88) has been running for over 60 seconds
Dec 02 01:39:03.651 INFO [stdout] test src/interchange/mod.rs - interchange::Json::extension (line 76) ... test src/interchange/mod.rs - interchange::Json::extension (line 76) has been running for over 60 seconds
Dec 02 01:39:03.651 INFO [stdout] test src/interchange/mod.rs - interchange::Json::from_reader (line 179) ... test src/interchange/mod.rs - interchange::Json::from_reader (line 179) has been running for over 60 seconds
Dec 02 01:39:03.652 INFO [stdout] test src/interchange/mod.rs - interchange::Json::deserialize (line 101) ... test src/interchange/mod.rs - interchange::Json::deserialize (line 101) has been running for over 60 seconds
Dec 02 01:39:03.671 INFO [stdout] test src/interchange/mod.rs - interchange::Json::serialize (line 131) ... test src/interchange/mod.rs - interchange::Json::serialize (line 131) has been running for over 60 seconds
Dec 02 01:39:03.671 INFO [stdout] test src/tuf.rs - tuf::TargetPath::components (line 343) ... test src/tuf.rs - tuf::TargetPath::components (line 343) has been running for over 60 seconds
Dec 02 01:39:03.672 INFO [stdout] test src/interchange/mod.rs - interchange::Json::to_writer (line 161) ... test src/interchange/mod.rs - interchange::Json::to_writer (line 161) has been running for over 60 seconds
Dec 02 01:39:06.069 INFO [stdout] test src/interchange/mod.rs - interchange::Json::extension (line 76) ... ok
Dec 02 01:39:06.082 INFO [stdout] test src/tuf.rs - tuf::TargetPath::components (line 343) ... ok
Dec 02 01:39:06.839 INFO [stdout] test src/interchange/mod.rs - interchange::Json::deserialize (line 101) ... ok
Dec 02 01:39:07.348 INFO [stdout] test src/interchange/mod.rs - interchange::Json::to_writer (line 161) ... ok
Dec 02 01:39:07.565 INFO [stdout] test src/interchange/mod.rs - interchange::Json::serialize (line 131) ... ok
Dec 02 01:39:07.717 INFO [stdout] test src/interchange/mod.rs - interchange::Json::canonicalize (line 88) ... ok
Dec 02 01:39:08.119 INFO [stdout] test src/interchange/mod.rs - interchange::Json::from_reader (line 179) ... ok
Dec 02 01:39:15.223 INFO [stdout] test src/tuf.rs - tuf::TargetPath::is_child (line 355) ... ok
Dec 02 01:39:15.223 INFO [stdout] 
Dec 02 01:39:15.223 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 01:39:15.223 INFO [stdout] 
Dec 02 01:39:15.255 INFO [stderr] su: No module specific data is present
Dec 02 01:39:16.405 INFO running `"docker" "inspect" "341b28ef0ddf23a60956dd91d197b5a1641fb5de915ad36b18ba9e145db94c50"`
Dec 02 01:39:16.645 INFO running `"docker" "rm" "-f" "341b28ef0ddf23a60956dd91d197b5a1641fb5de915ad36b18ba9e145db94c50"`
Dec 02 01:39:16.874 INFO [stdout] 341b28ef0ddf23a60956dd91d197b5a1641fb5de915ad36b18ba9e145db94c50
