Dec 14 17:57:32.353 INFO testing swagger-2.0.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 14 17:57:32.354 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 14 17:57:32.749 INFO [stdout] 3af0bcbcacbc7acc606a7b509e91f57b06991504bbddf395a8f74db3331bc1b8
Dec 14 17:57:32.752 INFO running `"docker" "start" "-a" "3af0bcbcacbc7acc606a7b509e91f57b06991504bbddf395a8f74db3331bc1b8"`
Dec 14 17:57:34.049 INFO [stderr] usermod: no changes
Dec 14 17:57:34.487 INFO [stderr]    Compiling slog v2.4.1
Dec 14 17:57:34.507 INFO [stderr]    Compiling uuid v0.5.1
Dec 14 17:57:37.522 INFO [stderr]    Compiling swagger v2.0.1 (/source)
Dec 14 17:57:44.693 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.61s
Dec 14 17:57:44.706 INFO [stderr] su: No module specific data is present
Dec 14 17:57:45.089 INFO running `"docker" "inspect" "3af0bcbcacbc7acc606a7b509e91f57b06991504bbddf395a8f74db3331bc1b8"`
Dec 14 17:57:45.160 INFO running `"docker" "rm" "-f" "3af0bcbcacbc7acc606a7b509e91f57b06991504bbddf395a8f74db3331bc1b8"`
Dec 14 17:57:45.281 INFO [stdout] 3af0bcbcacbc7acc606a7b509e91f57b06991504bbddf395a8f74db3331bc1b8
Dec 14 17:57:45.284 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 14 17:57:45.587 INFO [stdout] 2800208c51a6ca70e8c791c19028e1d39d63f413213d6af33054ffb3a4fc1a7c
Dec 14 17:57:45.593 INFO running `"docker" "start" "-a" "2800208c51a6ca70e8c791c19028e1d39d63f413213d6af33054ffb3a4fc1a7c"`
Dec 14 17:57:46.141 INFO [stderr] usermod: no changes
Dec 14 17:57:46.314 INFO [stderr]    Compiling swagger v2.0.1 (/source)
Dec 14 17:57:55.129 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.98s
Dec 14 17:57:55.150 INFO [stderr] su: No module specific data is present
Dec 14 17:57:55.618 INFO running `"docker" "inspect" "2800208c51a6ca70e8c791c19028e1d39d63f413213d6af33054ffb3a4fc1a7c"`
Dec 14 17:57:55.746 INFO running `"docker" "rm" "-f" "2800208c51a6ca70e8c791c19028e1d39d63f413213d6af33054ffb3a4fc1a7c"`
Dec 14 17:57:55.971 INFO [stdout] 2800208c51a6ca70e8c791c19028e1d39d63f413213d6af33054ffb3a4fc1a7c
Dec 14 17:57:55.991 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 14 17:57:56.259 INFO [stdout] 9d78063b81612142c4e27fcc34fdeeb4fdc58425a21310a9daf1a7dac6dce549
Dec 14 17:57:56.261 INFO running `"docker" "start" "-a" "9d78063b81612142c4e27fcc34fdeeb4fdc58425a21310a9daf1a7dac6dce549"`
Dec 14 17:57:56.878 INFO [stderr] usermod: no changes
Dec 14 17:57:57.100 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.19s
Dec 14 17:57:57.117 INFO [stderr]      Running /target/debug/deps/swagger-0fca12736aba6d48
Dec 14 17:57:57.145 INFO [stdout] 
Dec 14 17:57:57.145 INFO [stdout] running 9 tests
Dec 14 17:57:57.155 INFO [stdout] test context::context_tests::send_request ... ok
Dec 14 17:57:57.155 INFO [stdout] test nullable_format::serde_tests::missing_optionalnullable_value ... ok
Dec 14 17:57:57.155 INFO [stdout] test nullable_format::serde_tests::string_optionalnullable_value ... ok
Dec 14 17:57:57.159 INFO [stdout] test nullable_format::serde_tests::null_nullable_value ... ok
Dec 14 17:57:57.159 INFO [stdout] test nullable_format::serde_tests::object_nullable_value ... ok
Dec 14 17:57:57.159 INFO [stdout] test nullable_format::serde_tests::string_nullable_value ... ok
Dec 14 17:57:57.159 INFO [stdout] test nullable_format::serde_tests::object_optionalnullable_value ... ok
Dec 14 17:57:57.188 INFO [stdout] test nullable_format::serde_tests::null_optionalnullable_value ... ok
Dec 14 17:57:57.291 INFO [stderr]    Doc-tests swagger
Dec 14 17:57:57.291 INFO [stdout] test nullable_format::serde_tests::missing_nullable_value ... ok
Dec 14 17:57:57.291 INFO [stdout] 
Dec 14 17:57:57.291 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 17:57:57.291 INFO [stdout] 
Dec 14 17:58:06.698 INFO [stdout] 
Dec 14 17:58:06.698 INFO [stdout] running 31 tests
Dec 14 17:58:06.698 INFO [stdout] test src/composites.rs - composites::CompositeNewService (line 80) ... ignored
Dec 14 17:58:06.698 INFO [stdout] test src/drop_context.rs - drop_context::DropContext (line 22) ... ignored
Dec 14 17:58:43.259 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<&'a T>::cloned (line 508) ... ok
Dec 14 17:58:46.019 INFO [stdout] test src/context.rs - context::Has (line 19) ... ok
Dec 14 17:58:50.099 INFO [stdout] test src/context.rs - new_context_type (line 178) ... ok
Dec 14 17:58:50.319 INFO [stdout] test src/context.rs - context::Pop (line 63) ... ok
Dec 14 17:58:50.871 INFO [stdout] test src/context.rs - context::SwaggerService (line 503) ... ok
Dec 14 17:58:54.143 INFO [stdout] test src/context.rs - make_context (line 432) ... ok
Dec 14 17:59:00.439 INFO [stdout] test src/context.rs - make_context_ty (line 397) ... ok
Dec 14 17:59:01.475 INFO [stdout] test src/context.rs - context::Push (line 115) ... ok
Dec 14 17:59:40.607 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::and (line 369) ... ok
Dec 14 17:59:44.087 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::as_mut (line 107) ... ok
Dec 14 17:59:45.562 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::as_ref (line 86) ... ok
Dec 14 17:59:46.044 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::and_then (line 403) ... test src/nullable_format.rs - nullable_format::Nullable<T>::and_then (line 403) has been running for over 60 seconds
Dec 14 17:59:46.639 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::and_then (line 403) ... ok
Dec 14 17:59:48.155 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::expect (line 147) ... ok
Dec 14 17:59:50.063 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::expect (line 140) ... ok
Dec 14 17:59:50.703 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::is_present (line 34) ... ok
Dec 14 17:59:55.351 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::is_null (line 55) ... ok
Dec 14 18:00:26.291 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map (line 246) ... ok
Dec 14 18:00:38.468 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map_or_else (line 290) ... ok
Dec 14 18:00:38.991 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::ok_or (line 318) ... ok
Dec 14 18:00:41.651 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::or_else (line 458) ... ok
Dec 14 18:00:43.359 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::or (line 426) ... ok
Dec 14 18:00:44.099 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map_or (line 268) ... test src/nullable_format.rs - nullable_format::Nullable<T>::map_or (line 268) has been running for over 60 seconds
Dec 14 18:00:44.931 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map_or (line 268) ... ok
Dec 14 18:00:47.943 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::ok_or_else (line 344) ... ok
Dec 14 18:00:52.283 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::take (line 485) ... ok
Dec 14 18:01:02.323 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap (line 175) ... ok
Dec 14 18:01:08.902 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap_or (line 200) ... ok
Dec 14 18:01:09.934 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap (line 182) ... ok
Dec 14 18:01:10.267 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap_or_else (line 218) ... ok
Dec 14 18:01:10.452 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap_or_default (line 531) ... ok
Dec 14 18:01:10.452 INFO [stdout] 
Dec 14 18:01:10.452 INFO [stdout] test result: ok. 29 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 14 18:01:10.452 INFO [stdout] 
Dec 14 18:01:10.469 INFO [stderr] su: No module specific data is present
Dec 14 18:01:10.833 INFO running `"docker" "inspect" "9d78063b81612142c4e27fcc34fdeeb4fdc58425a21310a9daf1a7dac6dce549"`
Dec 14 18:01:10.922 INFO running `"docker" "rm" "-f" "9d78063b81612142c4e27fcc34fdeeb4fdc58425a21310a9daf1a7dac6dce549"`
Dec 14 18:01:10.980 INFO [stdout] 9d78063b81612142c4e27fcc34fdeeb4fdc58425a21310a9daf1a7dac6dce549