Dec 03 15:45:12.832 INFO testing cocaine-0.9.2 against 1.30.1 for beta-1.31-5
Dec 03 15:45:12.832 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 03 15:45:13.074 INFO [stdout] d1de19bb3bce48671475eb56495c5f2376f4cc419ecf85be32168ce345330f38
Dec 03 15:45:13.076 INFO running `"docker" "start" "-a" "d1de19bb3bce48671475eb56495c5f2376f4cc419ecf85be32168ce345330f38"`
Dec 03 15:45:13.675 INFO [stderr] usermod: no changes
Dec 03 15:45:13.863 INFO [stderr]    Compiling rmpv v0.4.0
Dec 03 15:45:16.762 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 03 15:45:27.349 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.66s
Dec 03 15:45:27.365 INFO [stderr] su: No module specific data is present
Dec 03 15:45:28.032 INFO running `"docker" "inspect" "d1de19bb3bce48671475eb56495c5f2376f4cc419ecf85be32168ce345330f38"`
Dec 03 15:45:28.323 INFO running `"docker" "rm" "-f" "d1de19bb3bce48671475eb56495c5f2376f4cc419ecf85be32168ce345330f38"`
Dec 03 15:45:28.574 INFO [stdout] d1de19bb3bce48671475eb56495c5f2376f4cc419ecf85be32168ce345330f38
Dec 03 15:45:28.576 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 03 15:45:29.028 INFO [stdout] 18a73eaa0fd5ba3cf50844a401231e99cbea9cf8db01a2d13447113314049e84
Dec 03 15:45:29.030 INFO running `"docker" "start" "-a" "18a73eaa0fd5ba3cf50844a401231e99cbea9cf8db01a2d13447113314049e84"`
Dec 03 15:45:31.251 INFO [stderr] usermod: no changes
Dec 03 15:45:31.568 INFO [stderr]    Compiling slog v1.7.1
Dec 03 15:45:32.891 INFO [stderr]    Compiling slog-extra v0.1.2
Dec 03 15:45:34.974 INFO [stderr]    Compiling slog-stream v1.2.1
Dec 03 15:45:36.034 INFO [stderr]    Compiling slog-term v1.5.0
Dec 03 15:45:40.037 INFO [stderr]    Compiling slog-stdlog v1.1.0
Dec 03 15:45:42.647 INFO [stderr]    Compiling slog-envlogger v0.5.0
Dec 03 15:45:46.298 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 03 15:46:43.723 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 12s
Dec 03 15:46:43.746 INFO [stderr] su: No module specific data is present
Dec 03 15:46:44.396 INFO running `"docker" "inspect" "18a73eaa0fd5ba3cf50844a401231e99cbea9cf8db01a2d13447113314049e84"`
Dec 03 15:46:44.691 INFO running `"docker" "rm" "-f" "18a73eaa0fd5ba3cf50844a401231e99cbea9cf8db01a2d13447113314049e84"`
Dec 03 15:46:45.047 INFO [stdout] 18a73eaa0fd5ba3cf50844a401231e99cbea9cf8db01a2d13447113314049e84
Dec 03 15:46:45.055 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 03 15:46:45.592 INFO [stdout] fcf410150bedaa3a19f9037d8e7ba7b2b111943ad113dc4e79a1d3ba0416a6ed
Dec 03 15:46:45.595 INFO running `"docker" "start" "-a" "fcf410150bedaa3a19f9037d8e7ba7b2b111943ad113dc4e79a1d3ba0416a6ed"`
Dec 03 15:46:48.082 INFO [stderr] usermod: no changes
Dec 03 15:46:48.339 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Dec 03 15:46:48.351 INFO [stderr]      Running /target/debug/deps/cocaine-d2d8c4a5107b183d
Dec 03 15:46:48.361 INFO [stdout] 
Dec 03 15:46:48.361 INFO [stdout] running 14 tests
Dec 03 15:46:48.375 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok
Dec 03 15:46:48.375 INFO [stdout] test frame::test::pass ... ok
Dec 03 15:46:48.389 INFO [stdout] test logging::tests::test_macro_with_args ... ok
Dec 03 15:46:48.389 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok
Dec 03 15:46:48.404 INFO [stdout] test logging::tests::test_macro_without_args ... ok
Dec 03 15:46:48.443 INFO [stdout] test net::connect::tests::test_connect ... ok
Dec 03 15:46:48.445 INFO [stdout] test net::connect::tests::test_connection_refused ... ok
Dec 03 15:46:48.459 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok
Dec 03 15:46:48.459 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok
Dec 03 15:46:48.467 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok
Dec 03 15:46:48.495 INFO [stdout] test net::connect::tests::test_connect_multi ... ok
Dec 03 15:46:48.531 INFO [stdout] test frame::test::fail_invalid_type_type ... ok
Dec 03 15:46:48.531 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok
Dec 03 15:46:48.531 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok
Dec 03 15:46:48.531 INFO [stdout] 
Dec 03 15:46:48.531 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 15:46:48.531 INFO [stdout] 
Dec 03 15:46:48.539 INFO [stderr]      Running /target/debug/deps/service-468a0cfc9712b693
Dec 03 15:46:48.543 INFO [stdout] 
Dec 03 15:46:48.543 INFO [stdout] running 4 tests
Dec 03 15:46:48.571 INFO [stdout] test connect ... ok
Dec 03 15:46:48.571 INFO [stdout] test connection_refused ... ok
Dec 03 15:46:48.627 INFO [stdout] test dispatch_receives_rst ... ok
Dec 03 15:46:48.627 INFO [stdout] test connection_refused_because_invalid_framing ... ok
Dec 03 15:46:48.627 INFO [stdout] 
Dec 03 15:46:48.627 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 15:46:48.627 INFO [stdout] 
Dec 03 15:46:48.627 INFO [stderr]    Doc-tests cocaine
Dec 03 15:46:57.934 INFO [stdout] 
Dec 03 15:46:57.934 INFO [stdout] running 19 tests
Dec 03 15:47:45.775 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok
Dec 03 15:47:56.433 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok
Dec 03 15:47:56.981 INFO [stdout] test src/lib.rs -  (line 42) ... ok
Dec 03 15:47:57.935 INFO [stdout] test src/lib.rs -  (line 23) ... test src/lib.rs -  (line 23) has been running for over 60 seconds
Dec 03 15:47:57.935 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... test src/logging.rs - cocaine_log (line 371) has been running for over 60 seconds
Dec 03 15:47:57.935 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... test src/logging.rs - cocaine_log (line 354) has been running for over 60 seconds
Dec 03 15:47:57.935 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... test src/lib.rs - Service::new (line 1301) has been running for over 60 seconds
Dec 03 15:47:57.935 INFO [stdout] test src/request.rs - request::Request (line 16) ... test src/request.rs - request::Request (line 16) has been running for over 60 seconds
Dec 03 15:47:58.467 INFO [stdout] test src/lib.rs -  (line 23) ... ok
Dec 03 15:47:58.584 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok
Dec 03 15:48:01.170 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok
Dec 03 15:48:01.667 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok
Dec 03 15:48:04.015 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok
Dec 03 15:48:45.779 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) has been running for over 60 seconds
Dec 03 15:48:55.803 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok
Dec 03 15:48:56.435 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... test src/service/locator.rs - service::locator::Locator::resolve (line 48) has been running for over 60 seconds
Dec 03 15:48:56.987 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 03 15:48:58.467 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 03 15:48:58.583 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 03 15:49:01.171 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... test src/service/storage.rs - service::storage::Storage::read (line 31) has been running for over 60 seconds
Dec 03 15:49:01.667 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... test src/service/storage.rs - service::storage::Storage::write (line 60) has been running for over 60 seconds
Dec 03 15:49:04.019 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) has been running for over 60 seconds
Dec 03 15:49:05.065 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok
Dec 03 15:49:08.607 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok
Dec 03 15:49:09.067 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... ok
Dec 03 15:49:10.167 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok
Dec 03 15:49:11.163 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok
Dec 03 15:49:12.142 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok
Dec 03 15:49:13.906 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... ok
Dec 03 15:49:23.454 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok
Dec 03 15:49:23.670 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok
Dec 03 15:49:25.131 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok
Dec 03 15:49:25.131 INFO [stdout] 
Dec 03 15:49:25.131 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 15:49:25.131 INFO [stdout] 
Dec 03 15:49:25.147 INFO [stderr] su: No module specific data is present
Dec 03 15:49:25.440 INFO running `"docker" "inspect" "fcf410150bedaa3a19f9037d8e7ba7b2b111943ad113dc4e79a1d3ba0416a6ed"`
Dec 03 15:49:25.563 INFO running `"docker" "rm" "-f" "fcf410150bedaa3a19f9037d8e7ba7b2b111943ad113dc4e79a1d3ba0416a6ed"`
Dec 03 15:49:25.693 INFO [stdout] fcf410150bedaa3a19f9037d8e7ba7b2b111943ad113dc4e79a1d3ba0416a6ed
