Nov 18 17:32:03.695 INFO testing cocaine-0.9.2 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 17:32:03.695 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 17:32:03.866 INFO [stdout] b61fe81f04cb0ad09bebb7985b4aa2e2a2a8d607bf93f308688bd80a3671b28d
Nov 18 17:32:03.868 INFO running `"docker" "start" "-a" "b61fe81f04cb0ad09bebb7985b4aa2e2a2a8d607bf93f308688bd80a3671b28d"`
Nov 18 17:32:04.322 INFO [stderr] usermod: no changes
Nov 18 17:32:04.544 INFO [stderr]    Compiling rmpv v0.4.0
Nov 18 17:32:06.814 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Nov 18 17:32:18.039 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.69s
Nov 18 17:32:18.047 INFO [stderr] su: No module specific data is present
Nov 18 17:32:18.641 INFO running `"docker" "inspect" "b61fe81f04cb0ad09bebb7985b4aa2e2a2a8d607bf93f308688bd80a3671b28d"`
Nov 18 17:32:18.850 INFO running `"docker" "rm" "-f" "b61fe81f04cb0ad09bebb7985b4aa2e2a2a8d607bf93f308688bd80a3671b28d"`
Nov 18 17:32:19.077 INFO [stdout] b61fe81f04cb0ad09bebb7985b4aa2e2a2a8d607bf93f308688bd80a3671b28d
Nov 18 17:32:19.084 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 17:32:19.660 INFO [stdout] e0e1080e43e4f540471ea64d891bc40e129d895604fffe13c464ee8c27451980
Nov 18 17:32:19.671 INFO running `"docker" "start" "-a" "e0e1080e43e4f540471ea64d891bc40e129d895604fffe13c464ee8c27451980"`
Nov 18 17:32:20.728 INFO [stderr] usermod: no changes
Nov 18 17:32:20.987 INFO [stderr]    Compiling slog v1.7.1
Nov 18 17:32:21.936 INFO [stderr]    Compiling slog-extra v0.1.2
Nov 18 17:32:23.979 INFO [stderr]    Compiling slog-stream v1.2.1
Nov 18 17:32:24.808 INFO [stderr]    Compiling slog-term v1.5.0
Nov 18 17:32:28.083 INFO [stderr]    Compiling slog-stdlog v1.1.0
Nov 18 17:32:30.487 INFO [stderr]    Compiling slog-envlogger v0.5.0
Nov 18 17:32:33.387 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Nov 18 17:33:15.384 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 54.62s
Nov 18 17:33:15.414 INFO [stderr] su: No module specific data is present
Nov 18 17:33:16.166 INFO running `"docker" "inspect" "e0e1080e43e4f540471ea64d891bc40e129d895604fffe13c464ee8c27451980"`
Nov 18 17:33:16.395 INFO running `"docker" "rm" "-f" "e0e1080e43e4f540471ea64d891bc40e129d895604fffe13c464ee8c27451980"`
Nov 18 17:33:16.683 INFO [stdout] e0e1080e43e4f540471ea64d891bc40e129d895604fffe13c464ee8c27451980
Nov 18 17:33:16.690 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 18 17:33:17.113 INFO [stdout] 4071a25b0637649c818a844e22c8b6422a598e4d42a5cf80d394618cd1e1d93b
Nov 18 17:33:17.121 INFO running `"docker" "start" "-a" "4071a25b0637649c818a844e22c8b6422a598e4d42a5cf80d394618cd1e1d93b"`
Nov 18 17:33:18.362 INFO [stderr] usermod: no changes
Nov 18 17:33:18.588 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Nov 18 17:33:18.605 INFO [stderr]      Running /target/debug/deps/cocaine-dbe624a4ea2e1bcc
Nov 18 17:33:18.609 INFO [stdout] 
Nov 18 17:33:18.609 INFO [stdout] running 14 tests
Nov 18 17:33:18.619 INFO [stdout] test frame::test::pass ... ok
Nov 18 17:33:18.619 INFO [stdout] test logging::tests::test_macro_with_args ... ok
Nov 18 17:33:18.643 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok
Nov 18 17:33:18.643 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok
Nov 18 17:33:18.643 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok
Nov 18 17:33:18.643 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok
Nov 18 17:33:18.643 INFO [stdout] test logging::tests::test_macro_without_args ... ok
Nov 18 17:33:18.643 INFO [stdout] test net::connect::tests::test_connection_refused ... ok
Nov 18 17:33:18.643 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok
Nov 18 17:33:18.679 INFO [stdout] test net::connect::tests::test_connect ... ok
Nov 18 17:33:18.685 INFO [stdout] test net::connect::tests::test_connect_multi ... ok
Nov 18 17:33:18.783 INFO [stderr]      Running /target/debug/deps/service-81930efb2342e261
Nov 18 17:33:18.783 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok
Nov 18 17:33:18.783 INFO [stdout] test frame::test::fail_invalid_type_type ... ok
Nov 18 17:33:18.783 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok
Nov 18 17:33:18.783 INFO [stdout] 
Nov 18 17:33:18.784 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:33:18.784 INFO [stdout] 
Nov 18 17:33:18.785 INFO [stdout] 
Nov 18 17:33:18.785 INFO [stdout] running 4 tests
Nov 18 17:33:18.790 INFO [stdout] test dispatch_receives_rst ... ok
Nov 18 17:33:18.791 INFO [stdout] test connection_refused ... ok
Nov 18 17:33:18.795 INFO [stdout] test connection_refused_because_invalid_framing ... ok
Nov 18 17:33:18.796 INFO [stdout] test connect ... ok
Nov 18 17:33:18.796 INFO [stdout] 
Nov 18 17:33:18.796 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:33:18.796 INFO [stdout] 
Nov 18 17:33:18.803 INFO [stderr]    Doc-tests cocaine
Nov 18 17:33:25.171 INFO [stdout] 
Nov 18 17:33:25.171 INFO [stdout] running 19 tests
Nov 18 17:34:01.095 INFO [stdout] test src/lib.rs -  (line 42) ... ok
Nov 18 17:34:02.639 INFO [stdout] test src/lib.rs -  (line 23) ... ok
Nov 18 17:34:03.059 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok
Nov 18 17:34:03.387 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok
Nov 18 17:34:04.183 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok
Nov 18 17:34:04.399 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok
Nov 18 17:34:04.623 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok
Nov 18 17:34:11.311 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok
Nov 18 17:34:41.229 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok
Nov 18 17:34:45.435 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok
Nov 18 17:34:46.395 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok
Nov 18 17:34:46.819 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok
Nov 18 17:34:50.925 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... ok
Nov 18 17:34:54.111 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... ok
Nov 18 17:34:55.253 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok
Nov 18 17:34:55.473 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok
Nov 18 17:35:05.119 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok
Nov 18 17:35:05.546 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok
Nov 18 17:35:06.131 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok
Nov 18 17:35:06.131 INFO [stdout] 
Nov 18 17:35:06.131 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 17:35:06.131 INFO [stdout] 
Nov 18 17:35:06.160 INFO [stderr] su: No module specific data is present
Nov 18 17:35:06.602 INFO running `"docker" "inspect" "4071a25b0637649c818a844e22c8b6422a598e4d42a5cf80d394618cd1e1d93b"`
Nov 18 17:35:06.789 INFO running `"docker" "rm" "-f" "4071a25b0637649c818a844e22c8b6422a598e4d42a5cf80d394618cd1e1d93b"`
Nov 18 17:35:06.927 INFO [stdout] 4071a25b0637649c818a844e22c8b6422a598e4d42a5cf80d394618cd1e1d93b