Dec 15 02:48:28.762 INFO testing opentracingrust-0.3.1 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 02:48:28.762 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 02:48:29.026 INFO [stdout] 35a1de2067e023daab5e70efe3c8803774c7f3d42f0db45cfbdda66592b83edf
Dec 15 02:48:29.028 INFO running `"docker" "start" "-a" "35a1de2067e023daab5e70efe3c8803774c7f3d42f0db45cfbdda66592b83edf"`
Dec 15 02:48:30.163 INFO [stderr] usermod: no changes
Dec 15 02:48:30.259 INFO [stderr]    Compiling crossbeam-utils v0.3.2
Dec 15 02:48:31.302 INFO [stderr]    Compiling crossbeam-epoch v0.4.3
Dec 15 02:48:33.031 INFO [stderr]    Compiling crossbeam-channel v0.1.3
Dec 15 02:48:35.735 INFO [stderr]    Compiling opentracingrust v0.3.1 (/source)
Dec 15 02:48:39.850 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.66s
Dec 15 02:48:39.854 INFO [stderr] su: No module specific data is present
Dec 15 02:48:40.637 INFO running `"docker" "inspect" "35a1de2067e023daab5e70efe3c8803774c7f3d42f0db45cfbdda66592b83edf"`
Dec 15 02:48:40.888 INFO running `"docker" "rm" "-f" "35a1de2067e023daab5e70efe3c8803774c7f3d42f0db45cfbdda66592b83edf"`
Dec 15 02:48:41.185 INFO [stdout] 35a1de2067e023daab5e70efe3c8803774c7f3d42f0db45cfbdda66592b83edf
Dec 15 02:48:41.185 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 02:48:41.640 INFO [stdout] 25a447cbda66c296e0685e424ab78e3ba55b7d1931899f51d3db47d85879d632
Dec 15 02:48:41.685 INFO running `"docker" "start" "-a" "25a447cbda66c296e0685e424ab78e3ba55b7d1931899f51d3db47d85879d632"`
Dec 15 02:48:42.782 INFO [stderr] usermod: no changes
Dec 15 02:48:42.839 INFO [stderr]    Compiling opentracingrust v0.3.1 (/source)
Dec 15 02:48:49.393 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.59s
Dec 15 02:48:49.398 INFO [stderr] su: No module specific data is present
Dec 15 02:48:49.807 INFO running `"docker" "inspect" "25a447cbda66c296e0685e424ab78e3ba55b7d1931899f51d3db47d85879d632"`
Dec 15 02:48:49.932 INFO running `"docker" "rm" "-f" "25a447cbda66c296e0685e424ab78e3ba55b7d1931899f51d3db47d85879d632"`
Dec 15 02:48:50.098 INFO [stdout] 25a447cbda66c296e0685e424ab78e3ba55b7d1931899f51d3db47d85879d632
Dec 15 02:48:50.107 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 02:48:50.510 INFO [stdout] f9b46b272dd57b5c242e2151f51b7fbf74b3ec5839a18bb3c9749e9f2aff6d26
Dec 15 02:48:50.512 INFO running `"docker" "start" "-a" "f9b46b272dd57b5c242e2151f51b7fbf74b3ec5839a18bb3c9749e9f2aff6d26"`
Dec 15 02:48:51.459 INFO [stderr] usermod: no changes
Dec 15 02:48:51.535 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Dec 15 02:48:51.539 INFO [stderr]      Running /target/debug/deps/opentracingrust-3218a9ea1c965574
Dec 15 02:48:51.540 INFO [stdout] 
Dec 15 02:48:51.540 INFO [stdout] running 67 tests
Dec 15 02:48:51.543 INFO [stdout] test carrier::tests::hash_map::inject_keys ... ok
Dec 15 02:48:51.543 INFO [stdout] test carrier::tests::hash_map::find_keys ... ok
Dec 15 02:48:51.544 INFO [stdout] test carrier::tests::hash_map::extract_keys ... ok
Dec 15 02:48:51.544 INFO [stdout] test carrier::tests::tree_map::inject_keys ... ok
Dec 15 02:48:51.547 INFO [stdout] test span::log::tests::set_default_timestamp ... ok
Dec 15 02:48:51.547 INFO [stdout] test span::log::tests::add_field ... ok
Dec 15 02:48:51.547 INFO [stdout] test span::log::tests::defults_to_no_time ... ok
Dec 15 02:48:51.547 INFO [stdout] test carrier::tests::tree_map::extract_keys ... ok
Dec 15 02:48:51.547 INFO [stdout] test span::tests::autofinish_as_mut ... ok
Dec 15 02:48:51.547 INFO [stdout] test span::tag::tests::set_tag ... ok
Dec 15 02:48:51.547 INFO [stdout] test carrier::tests::tree_map::find_keys ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::autoclose_finish_span_on_drop ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::references::follows ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::references::multi_refs ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::autofinish_deref ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::references::child_of ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::set_span_name ... ok
Dec 15 02:48:51.548 INFO [stdout] test span::tests::span_child_of_another ... ok
Dec 15 02:48:51.549 INFO [stdout] test span::tests::span_follows_another ... ok
Dec 15 02:48:51.549 INFO [stdout] test span::tests::start_span_on_creation ... ok
Dec 15 02:48:51.549 INFO [stdout] test span::tests::send_span_on_finish ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tests::tags::add_bool_tag ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tests::tags::add_string_tag ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tests::times::finish_span_at_finish_time ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tests::times::finish_span_on_finish ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tests::times::start_time_set ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tests::times::starts_now_by_default ... ok
Dec 15 02:48:51.556 INFO [stdout] test span_context::impl_context::tests::clone_context ... ok
Dec 15 02:48:51.556 INFO [stdout] test span_context::impl_context::tests::unwrap_context ... ok
Dec 15 02:48:51.556 INFO [stdout] test span_context::tests::clone_span_context ... ok
Dec 15 02:48:51.556 INFO [stdout] test span::tag::tests::iterate_over_tags ... ok
Dec 15 02:48:51.556 INFO [stdout] test span_context::tests::debug_formatting ... ok
Dec 15 02:48:51.556 INFO [stdout] test span_context::tests::extract_implementation_context ... ok
Dec 15 02:48:51.563 INFO [stdout] test span_context::tests::set_baggage_item ... ok
Dec 15 02:48:51.563 INFO [stdout] test span::log::tests::set_log_timestamp ... ok
Dec 15 02:48:51.564 INFO [stdout] test span::tests::tags::add_integer_tag ... ok
Dec 15 02:48:51.564 INFO [stdout] test span::tests::tags::add_generic_tag ... ok
Dec 15 02:48:51.564 INFO [stdout] test span::tests::tags::add_float_tag ... ok
Dec 15 02:48:51.564 INFO [stdout] test span::tag::tests::get_missing_tag ... ok
Dec 15 02:48:51.564 INFO [stdout] test span::tests::tags::add_str_tag ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::inject_binary ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::extract_binary ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::extract_textmap ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::extract_http_headers ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::inject_textmap ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::inject_http_headers ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracer::tests::create_span ... ok
Dec 15 02:48:51.571 INFO [stdout] test tracers::file::tests::span::extract::invalid::fails_if_invalid_trace_id ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::extract::http_headers ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::extract::invalid::returns_none_without_span_id ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::extract::invalid::fails_if_invalid_span_id ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::inject::http_headers ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::extract::invalid::returns_none_without_trace_id ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::create ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::write ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::file::tests::span::inject::text_map ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::noop::tests::inner_context::child_of_updates_trace_id ... ok
Dec 15 02:48:51.583 INFO [stdout] test utils::fail::tests::fail_spans ... ok
Dec 15 02:48:51.583 INFO [stdout] test tracers::noop::tests::inner_context::follows_updates_trace_id ... ok
Dec 15 02:48:51.591 INFO [stdout] test utils::global_tracer::tests::tracer_is_returned ... ok
Dec 15 02:48:51.616 INFO [stdout] test utils::global_tracer::tests::tracer_is_returned_to_many_threads ... ok
Dec 15 02:48:51.625 INFO [stdout] test utils::reporter::tests::receive_span ... ok
Dec 15 02:48:51.719 INFO [stdout] test tracers::file::tests::span::inject::binary_not_supported ... ok
Dec 15 02:48:51.735 INFO [stdout] test utils::global_tracer::tests::tracer_cannot_be_set_twice ... ok
Dec 15 02:48:51.736 INFO [stdout] test tracers::file::tests::span::extract::binary_not_supported ... ok
Dec 15 02:48:51.736 INFO [stdout] test utils::global_tracer::tests::tracer_must_be_set ... ok
Dec 15 02:48:51.736 INFO [stdout] test tracers::noop::tests::inner_context::panics_if_invalid_context ... ok
Dec 15 02:48:51.736 INFO [stdout] 
Dec 15 02:48:51.736 INFO [stdout] test result: ok. 67 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 02:48:51.736 INFO [stdout] 
Dec 15 02:48:51.748 INFO [stderr]    Doc-tests opentracingrust
Dec 15 02:48:54.595 INFO [stdout] 
Dec 15 02:48:54.595 INFO [stdout] running 14 tests
Dec 15 02:49:01.359 INFO [stdout] test src/span/mod.rs - span::StartOptions (line 350) ... ok
Dec 15 02:49:01.420 INFO [stdout] test src/carrier.rs - carrier::ExtractFormat (line 13) ... ok
Dec 15 02:49:02.079 INFO [stdout] test src/lib.rs -  (line 68) ... ok
Dec 15 02:49:03.132 INFO [stdout] test src/span/mod.rs - span::Span::tag (line 300) ... ok
Dec 15 02:49:03.582 INFO [stdout] test src/carrier.rs - carrier::InjectFormat (line 43) ... ok
Dec 15 02:49:03.749 INFO [stdout] test src/lib.rs -  (line 102) ... ok
Dec 15 02:49:04.599 INFO [stdout] test src/span/log.rs - span::log::Log (line 17) ... ok
Dec 15 02:49:05.688 INFO [stdout] test src/span_context/impl_context.rs - span_context::impl_context::ImplContext (line 27) ... ok
Dec 15 02:49:08.174 INFO [stdout] test src/utils/global_tracer.rs - utils::global_tracer::GlobalTracer (line 29) ... ok
Dec 15 02:49:08.402 INFO [stdout] test src/span_context/impl_context.rs - span_context::impl_context::ImplContextBox (line 108) ... ok
Dec 15 02:49:08.563 INFO [stdout] test src/span_context/mod.rs - span_context::SpanContext (line 39) ... ok
Dec 15 02:49:09.437 INFO [stdout] test src/tracers/file.rs - tracers::file::FileTracer (line 42) ... ok
Dec 15 02:49:09.471 INFO [stdout] test src/utils/fail.rs - utils::fail::FailSpan (line 13) ... ok
Dec 15 02:49:09.623 INFO [stdout] test src/tracers/noop.rs - tracers::noop::NoopTracer (line 35) ... ok
Dec 15 02:49:09.623 INFO [stdout] 
Dec 15 02:49:09.623 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 02:49:09.623 INFO [stdout] 
Dec 15 02:49:09.645 INFO [stderr] su: No module specific data is present
Dec 15 02:49:10.104 INFO running `"docker" "inspect" "f9b46b272dd57b5c242e2151f51b7fbf74b3ec5839a18bb3c9749e9f2aff6d26"`
Dec 15 02:49:10.212 INFO running `"docker" "rm" "-f" "f9b46b272dd57b5c242e2151f51b7fbf74b3ec5839a18bb3c9749e9f2aff6d26"`
Dec 15 02:49:10.368 INFO [stdout] f9b46b272dd57b5c242e2151f51b7fbf74b3ec5839a18bb3c9749e9f2aff6d26