Dec 06 21:06:57.126 INFO testing swagger-2.0.1 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 06 21:06:57.126 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 06 21:06:57.582 INFO [stdout] bed212fa30af96c0faf49945af941fedb00e75384e3834439ad58f6c5b017c57
Dec 06 21:06:57.588 INFO running `"docker" "start" "-a" "bed212fa30af96c0faf49945af941fedb00e75384e3834439ad58f6c5b017c57"`
Dec 06 21:06:59.136 INFO [stderr] usermod: no changes
Dec 06 21:06:59.456 INFO [stderr]    Compiling slog v2.4.1
Dec 06 21:06:59.464 INFO [stderr]    Compiling uuid v0.5.1
Dec 06 21:07:02.504 INFO [stderr]    Compiling swagger v2.0.1 (/source)
Dec 06 21:07:10.625 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.47s
Dec 06 21:07:10.638 INFO [stderr] su: No module specific data is present
Dec 06 21:07:11.224 INFO running `"docker" "inspect" "bed212fa30af96c0faf49945af941fedb00e75384e3834439ad58f6c5b017c57"`
Dec 06 21:07:11.488 INFO running `"docker" "rm" "-f" "bed212fa30af96c0faf49945af941fedb00e75384e3834439ad58f6c5b017c57"`
Dec 06 21:07:11.696 INFO [stdout] bed212fa30af96c0faf49945af941fedb00e75384e3834439ad58f6c5b017c57
Dec 06 21:07:11.702 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 06 21:07:12.036 INFO [stdout] fdc72738182288ffbfd062e0ac6558fd7311cfe0a23029b6f2d8b4ed8cf105b3
Dec 06 21:07:12.042 INFO running `"docker" "start" "-a" "fdc72738182288ffbfd062e0ac6558fd7311cfe0a23029b6f2d8b4ed8cf105b3"`
Dec 06 21:07:12.801 INFO [stderr] usermod: no changes
Dec 06 21:07:13.020 INFO [stderr]    Compiling swagger v2.0.1 (/source)
Dec 06 21:07:22.276 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.44s
Dec 06 21:07:22.292 INFO [stderr] su: No module specific data is present
Dec 06 21:07:22.976 INFO running `"docker" "inspect" "fdc72738182288ffbfd062e0ac6558fd7311cfe0a23029b6f2d8b4ed8cf105b3"`
Dec 06 21:07:23.306 INFO running `"docker" "rm" "-f" "fdc72738182288ffbfd062e0ac6558fd7311cfe0a23029b6f2d8b4ed8cf105b3"`
Dec 06 21:07:23.459 INFO [stdout] fdc72738182288ffbfd062e0ac6558fd7311cfe0a23029b6f2d8b4ed8cf105b3
Dec 06 21:07:23.462 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-7/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 06 21:07:23.824 INFO [stdout] 3329151425abc041c64b0d59f80be6bc1d93c61fec4198738a1137a96cc07fad
Dec 06 21:07:23.830 INFO running `"docker" "start" "-a" "3329151425abc041c64b0d59f80be6bc1d93c61fec4198738a1137a96cc07fad"`
Dec 06 21:07:24.732 INFO [stderr] usermod: no changes
Dec 06 21:07:24.908 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Dec 06 21:07:24.921 INFO [stderr]      Running /target/debug/deps/swagger-681bf02fb5440f4e
Dec 06 21:07:24.927 INFO [stdout] 
Dec 06 21:07:24.927 INFO [stdout] running 9 tests
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::string_nullable_value ... ok
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::object_nullable_value ... ok
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::string_optionalnullable_value ... ok
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::object_optionalnullable_value ... ok
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::null_nullable_value ... ok
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::null_optionalnullable_value ... ok
Dec 06 21:07:24.930 INFO [stdout] test nullable_format::serde_tests::missing_optionalnullable_value ... ok
Dec 06 21:07:24.936 INFO [stdout] test context::context_tests::send_request ... ok
Dec 06 21:07:25.025 INFO [stdout] test nullable_format::serde_tests::missing_nullable_value ... ok
Dec 06 21:07:25.026 INFO [stdout] 
Dec 06 21:07:25.026 INFO [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 21:07:25.026 INFO [stdout] 
Dec 06 21:07:25.027 INFO [stderr]    Doc-tests swagger
Dec 06 21:07:35.106 INFO [stdout] 
Dec 06 21:07:35.106 INFO [stdout] running 31 tests
Dec 06 21:07:35.128 INFO [stdout] test src/composites.rs - composites::CompositeNewService (line 80) ... ignored
Dec 06 21:07:35.128 INFO [stdout] test src/drop_context.rs - drop_context::DropContext (line 22) ... ignored
Dec 06 21:08:21.040 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<&'a T>::cloned (line 508) ... ok
Dec 06 21:08:21.660 INFO [stdout] test src/context.rs - make_context_ty (line 397) ... ok
Dec 06 21:08:23.216 INFO [stdout] test src/context.rs - make_context (line 432) ... ok
Dec 06 21:08:23.868 INFO [stdout] test src/context.rs - new_context_type (line 178) ... ok
Dec 06 21:08:25.208 INFO [stdout] test src/context.rs - context::Push (line 115) ... ok
Dec 06 21:08:26.112 INFO [stdout] test src/context.rs - context::Has (line 19) ... ok
Dec 06 21:08:27.736 INFO [stdout] test src/context.rs - context::SwaggerService (line 503) ... ok
Dec 06 21:08:27.803 INFO [stdout] test src/context.rs - context::Pop (line 63) ... ok
Dec 06 21:09:12.217 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::is_null (line 55) ... ok
Dec 06 21:09:15.808 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::and_then (line 403) ... ok
Dec 06 21:09:21.048 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::and (line 369) ... test src/nullable_format.rs - nullable_format::Nullable<T>::and (line 369) has been running for over 60 seconds
Dec 06 21:09:21.304 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::and (line 369) ... ok
Dec 06 21:09:22.316 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::as_ref (line 86) ... ok
Dec 06 21:09:22.388 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::as_mut (line 107) ... ok
Dec 06 21:09:22.468 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::is_present (line 34) ... ok
Dec 06 21:09:24.701 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::expect (line 147) ... ok
Dec 06 21:09:25.201 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::expect (line 140) ... test src/nullable_format.rs - nullable_format::Nullable<T>::expect (line 140) has been running for over 60 seconds
Dec 06 21:09:27.723 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::expect (line 140) ... ok
Dec 06 21:10:03.608 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map_or_else (line 290) ... ok
Dec 06 21:10:04.132 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::ok_or (line 318) ... ok
Dec 06 21:10:05.332 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::or_else (line 458) ... ok
Dec 06 21:10:06.104 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map_or (line 268) ... ok
Dec 06 21:10:06.104 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::map (line 246) ... ok
Dec 06 21:10:07.292 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::or (line 426) ... ok
Dec 06 21:10:08.932 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::ok_or_else (line 344) ... ok
Dec 06 21:10:10.732 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::take (line 485) ... ok
Dec 06 21:10:27.916 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap (line 175) ... ok
Dec 06 21:10:28.072 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap_or_else (line 218) ... ok
Dec 06 21:10:30.268 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap (line 182) ... ok
Dec 06 21:10:30.268 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap_or_default (line 531) ... ok
Dec 06 21:10:30.368 INFO [stdout] test src/nullable_format.rs - nullable_format::Nullable<T>::unwrap_or (line 200) ... ok
Dec 06 21:10:30.369 INFO [stdout] 
Dec 06 21:10:30.372 INFO [stdout] test result: ok. 29 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out
Dec 06 21:10:30.372 INFO [stdout] 
Dec 06 21:10:30.471 INFO [stderr] su: No module specific data is present
Dec 06 21:10:31.102 INFO running `"docker" "inspect" "3329151425abc041c64b0d59f80be6bc1d93c61fec4198738a1137a96cc07fad"`
Dec 06 21:10:31.394 INFO running `"docker" "rm" "-f" "3329151425abc041c64b0d59f80be6bc1d93c61fec4198738a1137a96cc07fad"`
Dec 06 21:10:31.676 INFO [stdout] 3329151425abc041c64b0d59f80be6bc1d93c61fec4198738a1137a96cc07fad