Dec 15 18:38:11.250 INFO testing cocaine-0.9.2 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 18:38:11.250 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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.431 INFO [stdout] d81792c9d7b336dafb4b576e3f41b5169eb342a9f0e054c9e9751dda1fe9a3f0
Dec 15 18:38:11.433 INFO running `"docker" "start" "-a" "d81792c9d7b336dafb4b576e3f41b5169eb342a9f0e054c9e9751dda1fe9a3f0"`
Dec 15 18:38:11.829 INFO [stderr] usermod: no changes
Dec 15 18:38:12.040 INFO [stderr]    Compiling rmpv v0.4.0
Dec 15 18:38:14.207 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 15 18:38:24.935 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.07s
Dec 15 18:38:24.946 INFO [stderr] su: No module specific data is present
Dec 15 18:38:25.823 INFO running `"docker" "inspect" "d81792c9d7b336dafb4b576e3f41b5169eb342a9f0e054c9e9751dda1fe9a3f0"`
Dec 15 18:38:26.103 INFO running `"docker" "rm" "-f" "d81792c9d7b336dafb4b576e3f41b5169eb342a9f0e054c9e9751dda1fe9a3f0"`
Dec 15 18:38:26.414 INFO [stdout] d81792c9d7b336dafb4b576e3f41b5169eb342a9f0e054c9e9751dda1fe9a3f0
Dec 15 18:38:26.417 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:26.849 INFO [stdout] 07ae2ffeed334465d07a4fbdaff98a1d30f35cc574faeabfed3eeff9b22ce052
Dec 15 18:38:26.851 INFO running `"docker" "start" "-a" "07ae2ffeed334465d07a4fbdaff98a1d30f35cc574faeabfed3eeff9b22ce052"`
Dec 15 18:38:28.170 INFO [stderr] usermod: no changes
Dec 15 18:38:28.418 INFO [stderr]    Compiling slog-stdlog v1.1.0
Dec 15 18:38:31.056 INFO [stderr]    Compiling slog-envlogger v0.5.0
Dec 15 18:38:34.219 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 15 18:39:27.775 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 59.58s
Dec 15 18:39:27.792 INFO [stderr] su: No module specific data is present
Dec 15 18:39:28.617 INFO running `"docker" "inspect" "07ae2ffeed334465d07a4fbdaff98a1d30f35cc574faeabfed3eeff9b22ce052"`
Dec 15 18:39:28.829 INFO running `"docker" "rm" "-f" "07ae2ffeed334465d07a4fbdaff98a1d30f35cc574faeabfed3eeff9b22ce052"`
Dec 15 18:39:29.147 INFO [stdout] 07ae2ffeed334465d07a4fbdaff98a1d30f35cc574faeabfed3eeff9b22ce052
Dec 15 18:39:29.147 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:29.699 INFO [stdout] 56a4c475e97f80fbbcb9aefe69bd9a17238a09712308095b5c4c03862d7b82fe
Dec 15 18:39:29.707 INFO running `"docker" "start" "-a" "56a4c475e97f80fbbcb9aefe69bd9a17238a09712308095b5c4c03862d7b82fe"`
Dec 15 18:39:30.621 INFO [stderr] usermod: no changes
Dec 15 18:39:30.863 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.21s
Dec 15 18:39:30.871 INFO [stderr]      Running /target/debug/deps/cocaine-809d93a020cc2cc8
Dec 15 18:39:30.871 INFO [stdout] 
Dec 15 18:39:30.871 INFO [stdout] running 14 tests
Dec 15 18:39:30.879 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok
Dec 15 18:39:30.879 INFO [stdout] test frame::test::pass ... ok
Dec 15 18:39:30.879 INFO [stdout] test logging::tests::test_macro_with_args ... ok
Dec 15 18:39:30.899 INFO [stdout] test logging::tests::test_macro_without_args ... ok
Dec 15 18:39:30.900 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok
Dec 15 18:39:30.911 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok
Dec 15 18:39:30.911 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok
Dec 15 18:39:30.915 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok
Dec 15 18:39:30.959 INFO [stdout] test net::connect::tests::test_connection_refused ... ok
Dec 15 18:39:30.959 INFO [stdout] test net::connect::tests::test_connect_multi ... ok
Dec 15 18:39:30.959 INFO [stdout] test net::connect::tests::test_connect ... ok
Dec 15 18:39:31.022 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok
Dec 15 18:39:31.028 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok
Dec 15 18:39:31.028 INFO [stdout] test frame::test::fail_invalid_type_type ... ok
Dec 15 18:39:31.028 INFO [stdout] 
Dec 15 18:39:31.028 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:39:31.028 INFO [stdout] 
Dec 15 18:39:31.039 INFO [stdout] 
Dec 15 18:39:31.039 INFO [stderr]      Running /target/debug/deps/service-3c1b2d6af61b573b
Dec 15 18:39:31.039 INFO [stdout] running 4 tests
Dec 15 18:39:31.107 INFO [stdout] test connection_refused_because_invalid_framing ... ok
Dec 15 18:39:31.107 INFO [stdout] test connection_refused ... ok
Dec 15 18:39:31.114 INFO [stdout] test dispatch_receives_rst ... ok
Dec 15 18:39:31.116 INFO [stdout] test connect ... ok
Dec 15 18:39:31.116 INFO [stdout] 
Dec 15 18:39:31.116 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:39:31.116 INFO [stdout] 
Dec 15 18:39:31.123 INFO [stderr]    Doc-tests cocaine
Dec 15 18:39:39.223 INFO [stdout] 
Dec 15 18:39:39.224 INFO [stdout] running 19 tests
Dec 15 18:40:27.640 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok
Dec 15 18:40:29.191 INFO [stdout] test src/lib.rs -  (line 23) ... ok
Dec 15 18:40:29.231 INFO [stdout] test src/lib.rs -  (line 42) ... ok
Dec 15 18:40:29.814 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok
Dec 15 18:40:29.911 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok
Dec 15 18:40:31.291 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok
Dec 15 18:40:32.368 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok
Dec 15 18:40:35.803 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok
Dec 15 18:41:24.291 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok
Dec 15 18:41:24.323 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok
Dec 15 18:41:24.691 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok
Dec 15 18:41:24.707 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... ok
Dec 15 18:41:25.663 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok
Dec 15 18:41:29.238 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 15 18:41:29.815 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 15 18:41:29.893 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... ok
Dec 15 18:41:30.056 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok
Dec 15 18:41:31.659 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok
Dec 15 18:41:45.427 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok
Dec 15 18:41:45.731 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok
Dec 15 18:41:45.903 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok
Dec 15 18:41:45.903 INFO [stdout] 
Dec 15 18:41:45.903 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 18:41:45.903 INFO [stdout] 
Dec 15 18:41:45.955 INFO [stderr] su: No module specific data is present
Dec 15 18:41:47.075 INFO running `"docker" "inspect" "56a4c475e97f80fbbcb9aefe69bd9a17238a09712308095b5c4c03862d7b82fe"`
Dec 15 18:41:47.415 INFO running `"docker" "rm" "-f" "56a4c475e97f80fbbcb9aefe69bd9a17238a09712308095b5c4c03862d7b82fe"`
Dec 15 18:41:47.710 INFO [stdout] 56a4c475e97f80fbbcb9aefe69bd9a17238a09712308095b5c4c03862d7b82fe