Dec 15 18:38:11.252 INFO testing cocaine-0.9.2 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 18:38:11.252 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 18:38:11.466 INFO [stdout] 008b3b38a5d0fc7137b5e67a154aa0bc3e8a639c1b947135b70801a4a760941c Dec 15 18:38:11.468 INFO running `"docker" "start" "-a" "008b3b38a5d0fc7137b5e67a154aa0bc3e8a639c1b947135b70801a4a760941c"` Dec 15 18:38:11.863 INFO [stderr] usermod: no changes Dec 15 18:38:12.071 INFO [stderr] Compiling cocaine v0.9.2 (/source) Dec 15 18:38:20.726 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 8.83s Dec 15 18:38:20.731 INFO [stderr] su: No module specific data is present Dec 15 18:38:21.268 INFO running `"docker" "inspect" "008b3b38a5d0fc7137b5e67a154aa0bc3e8a639c1b947135b70801a4a760941c"` Dec 15 18:38:21.587 INFO running `"docker" "rm" "-f" "008b3b38a5d0fc7137b5e67a154aa0bc3e8a639c1b947135b70801a4a760941c"` Dec 15 18:38:21.862 INFO [stdout] 008b3b38a5d0fc7137b5e67a154aa0bc3e8a639c1b947135b70801a4a760941c Dec 15 18:38:21.871 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 18:38:22.399 INFO [stdout] 021ba0f5843d7f498b67230444e0790884c170bcfd6d766e0f2cdfd6f081f9b7 Dec 15 18:38:22.401 INFO running `"docker" "start" "-a" "021ba0f5843d7f498b67230444e0790884c170bcfd6d766e0f2cdfd6f081f9b7"` Dec 15 18:38:23.695 INFO [stderr] usermod: no changes Dec 15 18:38:23.996 INFO [stderr] Compiling slog-extra v0.1.2 Dec 15 18:38:26.039 INFO [stderr] Compiling slog-stream v1.2.1 Dec 15 18:38:27.086 INFO [stderr] Compiling slog-term v1.5.0 Dec 15 18:38:30.855 INFO [stderr] Compiling slog-stdlog v1.1.0 Dec 15 18:38:33.308 INFO [stderr] Compiling slog-envlogger v0.5.0 Dec 15 18:38:36.628 INFO [stderr] Compiling cocaine v0.9.2 (/source) Dec 15 18:39:28.543 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 04s Dec 15 18:39:28.563 INFO [stderr] su: No module specific data is present Dec 15 18:39:29.264 INFO running `"docker" "inspect" "021ba0f5843d7f498b67230444e0790884c170bcfd6d766e0f2cdfd6f081f9b7"` Dec 15 18:39:29.675 INFO running `"docker" "rm" "-f" "021ba0f5843d7f498b67230444e0790884c170bcfd6d766e0f2cdfd6f081f9b7"` Dec 15 18:39:30.062 INFO [stdout] 021ba0f5843d7f498b67230444e0790884c170bcfd6d766e0f2cdfd6f081f9b7 Dec 15 18:39:30.131 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 18:39:30.467 INFO [stdout] 03c22b5d86ad5dc930b026fdfe78e90c160e5a4b8dc0bb01a24bf53c7ceb468c Dec 15 18:39:30.472 INFO running `"docker" "start" "-a" "03c22b5d86ad5dc930b026fdfe78e90c160e5a4b8dc0bb01a24bf53c7ceb468c"` Dec 15 18:39:31.520 INFO [stderr] usermod: no changes Dec 15 18:39:31.727 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.17s Dec 15 18:39:31.735 INFO [stderr] Running /target/debug/deps/cocaine-e138a2304d7943c5 Dec 15 18:39:31.739 INFO [stdout] Dec 15 18:39:31.739 INFO [stdout] running 14 tests Dec 15 18:39:31.739 INFO [stdout] test logging::tests::test_macro_with_args ... ok Dec 15 18:39:31.739 INFO [stdout] test frame::test::pass ... ok Dec 15 18:39:31.739 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok Dec 15 18:39:31.747 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok Dec 15 18:39:31.756 INFO [stdout] test logging::tests::test_macro_without_args ... ok Dec 15 18:39:31.756 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok Dec 15 18:39:31.756 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok Dec 15 18:39:31.767 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok Dec 15 18:39:31.803 INFO [stdout] test net::connect::tests::test_connection_refused ... ok Dec 15 18:39:31.804 INFO [stdout] test net::connect::tests::test_connect_multi ... ok Dec 15 18:39:31.807 INFO [stdout] test net::connect::tests::test_connect ... ok Dec 15 18:39:31.939 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok Dec 15 18:39:31.939 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok Dec 15 18:39:31.939 INFO [stdout] test frame::test::fail_invalid_type_type ... ok Dec 15 18:39:31.939 INFO [stdout] Dec 15 18:39:31.939 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 18:39:31.939 INFO [stdout] Dec 15 18:39:31.947 INFO [stdout] Dec 15 18:39:31.947 INFO [stderr] Running /target/debug/deps/service-530b0c8eec7fe4c9 Dec 15 18:39:31.947 INFO [stdout] running 4 tests Dec 15 18:39:32.027 INFO [stdout] test connection_refused ... ok Dec 15 18:39:32.041 INFO [stdout] test connection_refused_because_invalid_framing ... ok Dec 15 18:39:32.047 INFO [stdout] test dispatch_receives_rst ... ok Dec 15 18:39:32.055 INFO [stdout] test connect ... ok Dec 15 18:39:32.055 INFO [stdout] Dec 15 18:39:32.055 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 18:39:32.055 INFO [stdout] Dec 15 18:39:32.057 INFO [stderr] Doc-tests cocaine Dec 15 18:39:39.999 INFO [stdout] Dec 15 18:39:39.999 INFO [stdout] running 19 tests Dec 15 18:40:21.407 INFO [stdout] test src/lib.rs - (line 42) ... ok Dec 15 18:40:23.460 INFO [stdout] test src/lib.rs - (line 23) ... ok Dec 15 18:40:23.983 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok Dec 15 18:40:28.199 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok Dec 15 18:40:29.735 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok Dec 15 18:40:31.083 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok Dec 15 18:40:32.999 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok Dec 15 18:40:33.947 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok Dec 15 18:41:01.648 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok Dec 15 18:41:11.923 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok Dec 15 18:41:22.572 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok Dec 15 18:41:25.071 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok Dec 15 18:41:28.187 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder::locator_addrs (line 124) ... test src/service/mod.rs - service::ServiceBuilder::locator_addrs (line 124) has been running for over 60 seconds Dec 15 18:41:29.630 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok Dec 15 18:41:29.731 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder::resolver (line 176) ... test src/service/mod.rs - service::ServiceBuilder::resolver (line 176) has been running for over 60 seconds Dec 15 18:41:31.122 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder::resolver (line 176) ... ok Dec 15 18:41:31.137 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder::locator_addrs (line 124) ... ok Dec 15 18:41:31.311 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok Dec 15 18:41:41.039 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok Dec 15 18:41:41.704 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok Dec 15 18:41:44.046 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok Dec 15 18:41:44.046 INFO [stdout] Dec 15 18:41:44.046 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 18:41:44.046 INFO [stdout] Dec 15 18:41:44.091 INFO [stderr] su: No module specific data is present Dec 15 18:41:44.688 INFO running `"docker" "inspect" "03c22b5d86ad5dc930b026fdfe78e90c160e5a4b8dc0bb01a24bf53c7ceb468c"` Dec 15 18:41:44.979 INFO running `"docker" "rm" "-f" "03c22b5d86ad5dc930b026fdfe78e90c160e5a4b8dc0bb01a24bf53c7ceb468c"` Dec 15 18:41:45.291 INFO [stdout] 03c22b5d86ad5dc930b026fdfe78e90c160e5a4b8dc0bb01a24bf53c7ceb468c