Dec 07 20:18:48.953 INFO testing cocaine-0.9.2 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 20:18:48.953 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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.126 INFO [stdout] 726947e197967d9513a722f77910a3c09ef36670058bfec03011b4255e173124
Dec 07 20:18:49.130 INFO running `"docker" "start" "-a" "726947e197967d9513a722f77910a3c09ef36670058bfec03011b4255e173124"`
Dec 07 20:18:49.580 INFO [stderr] usermod: no changes
Dec 07 20:18:49.783 INFO [stderr]    Compiling rmpv v0.4.0
Dec 07 20:18:51.877 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 07 20:19:03.307 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.71s
Dec 07 20:19:03.324 INFO [stderr] su: No module specific data is present
Dec 07 20:19:04.176 INFO running `"docker" "inspect" "726947e197967d9513a722f77910a3c09ef36670058bfec03011b4255e173124"`
Dec 07 20:19:04.420 INFO running `"docker" "rm" "-f" "726947e197967d9513a722f77910a3c09ef36670058bfec03011b4255e173124"`
Dec 07 20:19:04.708 INFO [stdout] 726947e197967d9513a722f77910a3c09ef36670058bfec03011b4255e173124
Dec 07 20:19:04.714 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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.120 INFO [stdout] ebb76fce79780c52d43d965c4d64b62d77f94a32eab0fe6da9b1ab0c3e8f6af7
Dec 07 20:19:05.122 INFO running `"docker" "start" "-a" "ebb76fce79780c52d43d965c4d64b62d77f94a32eab0fe6da9b1ab0c3e8f6af7"`
Dec 07 20:19:06.420 INFO [stderr] usermod: no changes
Dec 07 20:19:06.676 INFO [stderr]    Compiling slog v1.7.1
Dec 07 20:19:07.745 INFO [stderr]    Compiling slog-extra v0.1.2
Dec 07 20:19:09.633 INFO [stderr]    Compiling slog-stream v1.2.1
Dec 07 20:19:10.662 INFO [stderr]    Compiling slog-term v1.5.0
Dec 07 20:19:14.196 INFO [stderr]    Compiling slog-stdlog v1.1.0
Dec 07 20:19:16.765 INFO [stderr]    Compiling slog-envlogger v0.5.0
Dec 07 20:19:20.031 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 07 20:20:09.544 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 03s
Dec 07 20:20:09.559 INFO [stderr] su: No module specific data is present
Dec 07 20:20:10.448 INFO running `"docker" "inspect" "ebb76fce79780c52d43d965c4d64b62d77f94a32eab0fe6da9b1ab0c3e8f6af7"`
Dec 07 20:20:10.698 INFO running `"docker" "rm" "-f" "ebb76fce79780c52d43d965c4d64b62d77f94a32eab0fe6da9b1ab0c3e8f6af7"`
Dec 07 20:20:10.864 INFO [stdout] ebb76fce79780c52d43d965c4d64b62d77f94a32eab0fe6da9b1ab0c3e8f6af7
Dec 07 20:20:10.874 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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:11.449 INFO [stdout] b5c7634c42b28230d629519e1af6142ea26ec99612b869dedbc3a8e4b1fbbc72
Dec 07 20:20:11.451 INFO running `"docker" "start" "-a" "b5c7634c42b28230d629519e1af6142ea26ec99612b869dedbc3a8e4b1fbbc72"`
Dec 07 20:20:12.116 INFO [stderr] usermod: no changes
Dec 07 20:20:12.308 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Dec 07 20:20:12.324 INFO [stderr]      Running /target/debug/deps/cocaine-15cb3da8a5328c83
Dec 07 20:20:12.332 INFO [stdout] 
Dec 07 20:20:12.332 INFO [stdout] running 14 tests
Dec 07 20:20:12.349 INFO [stdout] test frame::test::pass ... ok
Dec 07 20:20:12.360 INFO [stdout] test logging::tests::test_macro_without_args ... ok
Dec 07 20:20:12.360 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok
Dec 07 20:20:12.360 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok
Dec 07 20:20:12.360 INFO [stdout] test logging::tests::test_macro_with_args ... ok
Dec 07 20:20:12.360 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok
Dec 07 20:20:12.368 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok
Dec 07 20:20:12.368 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok
Dec 07 20:20:12.373 INFO [stdout] test net::connect::tests::test_connect ... ok
Dec 07 20:20:12.418 INFO [stdout] test net::connect::tests::test_connection_refused ... ok
Dec 07 20:20:12.426 INFO [stdout] test net::connect::tests::test_connect_multi ... ok
Dec 07 20:20:12.499 INFO [stdout] test frame::test::fail_invalid_type_type ... ok
Dec 07 20:20:12.505 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok
Dec 07 20:20:12.505 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok
Dec 07 20:20:12.505 INFO [stdout] 
Dec 07 20:20:12.505 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:20:12.505 INFO [stdout] 
Dec 07 20:20:12.524 INFO [stderr]      Running /target/debug/deps/service-1e4d563235087116
Dec 07 20:20:12.524 INFO [stdout] 
Dec 07 20:20:12.524 INFO [stdout] running 4 tests
Dec 07 20:20:12.533 INFO [stdout] test connection_refused ... ok
Dec 07 20:20:12.567 INFO [stdout] test connect ... ok
Dec 07 20:20:12.572 INFO [stdout] test dispatch_receives_rst ... ok
Dec 07 20:20:12.577 INFO [stdout] test connection_refused_because_invalid_framing ... ok
Dec 07 20:20:12.577 INFO [stdout] 
Dec 07 20:20:12.577 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:20:12.577 INFO [stdout] 
Dec 07 20:20:12.581 INFO [stderr]    Doc-tests cocaine
Dec 07 20:20:20.715 INFO [stdout] 
Dec 07 20:20:20.715 INFO [stdout] running 19 tests
Dec 07 20:21:04.608 INFO [stdout] test src/lib.rs -  (line 23) ... ok
Dec 07 20:21:05.492 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok
Dec 07 20:21:06.728 INFO [stdout] test src/lib.rs -  (line 42) ... ok
Dec 07 20:21:09.516 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok
Dec 07 20:21:12.380 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok
Dec 07 20:21:12.908 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok
Dec 07 20:21:15.412 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok
Dec 07 20:21:16.550 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok
Dec 07 20:21:51.555 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok
Dec 07 20:21:57.601 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok
Dec 07 20:22:06.724 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:09.501 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... ok
Dec 07 20:22:12.376 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:12.497 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok
Dec 07 20:22:14.456 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... ok
Dec 07 20:22:15.416 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 07 20:22:15.508 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok
Dec 07 20:22:15.974 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok
Dec 07 20:22:15.974 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok
Dec 07 20:22:28.748 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok
Dec 07 20:22:29.264 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok
Dec 07 20:22:30.270 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok
Dec 07 20:22:30.270 INFO [stdout] 
Dec 07 20:22:30.270 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:22:30.270 INFO [stdout] 
Dec 07 20:22:30.302 INFO [stderr] su: No module specific data is present
Dec 07 20:22:31.136 INFO running `"docker" "inspect" "b5c7634c42b28230d629519e1af6142ea26ec99612b869dedbc3a8e4b1fbbc72"`
Dec 07 20:22:31.512 INFO running `"docker" "rm" "-f" "b5c7634c42b28230d629519e1af6142ea26ec99612b869dedbc3a8e4b1fbbc72"`
Dec 07 20:22:31.672 INFO [stdout] b5c7634c42b28230d629519e1af6142ea26ec99612b869dedbc3a8e4b1fbbc72