Dec 07 20:18:49.312 INFO testing cocaine-0.9.2 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 20:18:49.312 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 07 20:18:49.579 INFO [stdout] 2e5e52ef5c3b49ba27e61b761f5a64d3072e067feb4fafe75fc8e85c395c7a0d
Dec 07 20:18:49.580 INFO running `"docker" "start" "-a" "2e5e52ef5c3b49ba27e61b761f5a64d3072e067feb4fafe75fc8e85c395c7a0d"`
Dec 07 20:18:50.004 INFO [stderr] usermod: no changes
Dec 07 20:18:50.254 INFO [stderr]    Compiling rmpv v0.4.0
Dec 07 20:18:52.429 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 07 20:19:03.877 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.85s
Dec 07 20:19:03.887 INFO [stderr] su: No module specific data is present
Dec 07 20:19:04.586 INFO running `"docker" "inspect" "2e5e52ef5c3b49ba27e61b761f5a64d3072e067feb4fafe75fc8e85c395c7a0d"`
Dec 07 20:19:04.914 INFO running `"docker" "rm" "-f" "2e5e52ef5c3b49ba27e61b761f5a64d3072e067feb4fafe75fc8e85c395c7a0d"`
Dec 07 20:19:05.248 INFO [stdout] 2e5e52ef5c3b49ba27e61b761f5a64d3072e067feb4fafe75fc8e85c395c7a0d
Dec 07 20:19:05.250 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 07 20:19:05.842 INFO [stdout] 60e773e09e1dd52b20467723a4f639940ffbb2e55ea4e1301646699ae2c90c11
Dec 07 20:19:05.846 INFO running `"docker" "start" "-a" "60e773e09e1dd52b20467723a4f639940ffbb2e55ea4e1301646699ae2c90c11"`
Dec 07 20:19:06.668 INFO [stderr] usermod: no changes
Dec 07 20:19:06.952 INFO [stderr]    Compiling slog-extra v0.1.2
Dec 07 20:19:09.012 INFO [stderr]    Compiling slog-stream v1.2.1
Dec 07 20:19:10.144 INFO [stderr]    Compiling slog-term v1.5.0
Dec 07 20:19:13.872 INFO [stderr]    Compiling slog-stdlog v1.1.0
Dec 07 20:19:16.404 INFO [stderr]    Compiling slog-envlogger v0.5.0
Dec 07 20:19:19.762 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 07 20:20:11.639 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 04s
Dec 07 20:20:11.644 INFO [stderr] su: No module specific data is present
Dec 07 20:20:12.081 INFO running `"docker" "inspect" "60e773e09e1dd52b20467723a4f639940ffbb2e55ea4e1301646699ae2c90c11"`
Dec 07 20:20:12.348 INFO running `"docker" "rm" "-f" "60e773e09e1dd52b20467723a4f639940ffbb2e55ea4e1301646699ae2c90c11"`
Dec 07 20:20:12.624 INFO [stdout] 60e773e09e1dd52b20467723a4f639940ffbb2e55ea4e1301646699ae2c90c11
Dec 07 20:20:12.626 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 07 20:20:13.068 INFO [stdout] 3376321a2a6fd83e809e4d85128367b711c6f01eedb366c183651f61b01140fe
Dec 07 20:20:13.072 INFO running `"docker" "start" "-a" "3376321a2a6fd83e809e4d85128367b711c6f01eedb366c183651f61b01140fe"`
Dec 07 20:20:14.143 INFO [stderr] usermod: no changes
Dec 07 20:20:14.455 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.27s
Dec 07 20:20:14.479 INFO [stderr]      Running /target/debug/deps/cocaine-adbf7d3643998318
Dec 07 20:20:14.490 INFO [stdout] 
Dec 07 20:20:14.490 INFO [stdout] running 14 tests
Dec 07 20:20:14.490 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok
Dec 07 20:20:14.490 INFO [stdout] test logging::tests::test_macro_with_args ... ok
Dec 07 20:20:14.490 INFO [stdout] test frame::test::pass ... ok
Dec 07 20:20:14.496 INFO [stdout] test logging::tests::test_macro_without_args ... ok
Dec 07 20:20:14.496 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok
Dec 07 20:20:14.496 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok
Dec 07 20:20:14.496 INFO [stdout] test net::connect::tests::test_connect ... ok
Dec 07 20:20:14.504 INFO [stdout] test net::connect::tests::test_connect_multi ... ok
Dec 07 20:20:14.504 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok
Dec 07 20:20:14.505 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok
Dec 07 20:20:14.505 INFO [stdout] test net::connect::tests::test_connection_refused ... ok
Dec 07 20:20:14.585 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok
Dec 07 20:20:14.585 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok
Dec 07 20:20:14.585 INFO [stdout] test frame::test::fail_invalid_type_type ... ok
Dec 07 20:20:14.585 INFO [stdout] 
Dec 07 20:20:14.585 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:20:14.585 INFO [stdout] 
Dec 07 20:20:14.589 INFO [stderr]      Running /target/debug/deps/service-95f8c46321d71392
Dec 07 20:20:14.596 INFO [stdout] 
Dec 07 20:20:14.596 INFO [stdout] running 4 tests
Dec 07 20:20:14.601 INFO [stdout] test connection_refused ... ok
Dec 07 20:20:14.605 INFO [stdout] test connect ... ok
Dec 07 20:20:14.616 INFO [stdout] test connection_refused_because_invalid_framing ... ok
Dec 07 20:20:14.616 INFO [stdout] test dispatch_receives_rst ... ok
Dec 07 20:20:14.616 INFO [stdout] 
Dec 07 20:20:14.616 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:20:14.616 INFO [stdout] 
Dec 07 20:20:14.624 INFO [stderr]    Doc-tests cocaine
Dec 07 20:20:22.548 INFO [stdout] 
Dec 07 20:20:22.548 INFO [stdout] running 19 tests
Dec 07 20:21:06.400 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok
Dec 07 20:21:07.740 INFO [stdout] test src/lib.rs -  (line 23) ... ok
Dec 07 20:21:08.516 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok
Dec 07 20:21:08.940 INFO [stdout] test src/lib.rs -  (line 42) ... ok
Dec 07 20:21:11.016 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok
Dec 07 20:21:14.212 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok
Dec 07 20:21:17.508 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok
Dec 07 20:21:17.725 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok
Dec 07 20:21:58.692 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok
Dec 07 20:22:03.469 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok
Dec 07 20:22:08.524 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... test src/service/mod.rs - service::ServiceBuilder (line 82) has been running for over 60 seconds
Dec 07 20:22:08.948 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) has been running for over 60 seconds
Dec 07 20:22:10.052 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok
Dec 07 20:22:11.028 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) has been running for over 60 seconds
Dec 07 20:22:11.256 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... ok
Dec 07 20:22:15.246 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok
Dec 07 20:22:16.006 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok
Dec 07 20:22:16.204 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok
Dec 07 20:22:16.700 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... ok
Dec 07 20:22:31.728 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok
Dec 07 20:22:32.464 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok
Dec 07 20:22:33.061 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok
Dec 07 20:22:33.061 INFO [stdout] 
Dec 07 20:22:33.061 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:22:33.061 INFO [stdout] 
Dec 07 20:22:33.095 INFO [stderr] su: No module specific data is present
Dec 07 20:22:33.378 INFO running `"docker" "inspect" "3376321a2a6fd83e809e4d85128367b711c6f01eedb366c183651f61b01140fe"`
Dec 07 20:22:33.514 INFO running `"docker" "rm" "-f" "3376321a2a6fd83e809e4d85128367b711c6f01eedb366c183651f61b01140fe"`
Dec 07 20:22:33.625 INFO [stdout] 3376321a2a6fd83e809e4d85128367b711c6f01eedb366c183651f61b01140fe