Nov 18 04:43:03.213 INFO testing opentracingrust-0.3.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 04:43:03.213 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 04:43:03.631 INFO [stdout] c427c57261032cbc4d5cf63781cae2ec9c581ce9401e6355820b850665e2ed1f
Nov 18 04:43:03.632 INFO running `"docker" "start" "-a" "c427c57261032cbc4d5cf63781cae2ec9c581ce9401e6355820b850665e2ed1f"`
Nov 18 04:43:04.392 INFO [stderr] usermod: no changes
Nov 18 04:43:04.519 INFO [stderr]    Compiling crossbeam-channel v0.1.3
Nov 18 04:43:07.389 INFO [stderr]    Compiling opentracingrust v0.3.1 (/source)
Nov 18 04:43:11.357 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.93s
Nov 18 04:43:11.362 INFO [stderr] su: No module specific data is present
Nov 18 04:43:12.639 INFO running `"docker" "inspect" "c427c57261032cbc4d5cf63781cae2ec9c581ce9401e6355820b850665e2ed1f"`
Nov 18 04:43:12.905 INFO running `"docker" "rm" "-f" "c427c57261032cbc4d5cf63781cae2ec9c581ce9401e6355820b850665e2ed1f"`
Nov 18 04:43:13.239 INFO [stdout] c427c57261032cbc4d5cf63781cae2ec9c581ce9401e6355820b850665e2ed1f
Nov 18 04:43:13.243 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 04:43:13.780 INFO [stdout] 4bcd2dc76f5ecd34e93f7b8bfad385eea9ae401861a80fe2d7788c4e9229b743
Nov 18 04:43:13.783 INFO running `"docker" "start" "-a" "4bcd2dc76f5ecd34e93f7b8bfad385eea9ae401861a80fe2d7788c4e9229b743"`
Nov 18 04:43:15.171 INFO [stderr] usermod: no changes
Nov 18 04:43:15.283 INFO [stderr]    Compiling opentracingrust v0.3.1 (/source)
Nov 18 04:43:22.386 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.17s
Nov 18 04:43:22.390 INFO [stderr] su: No module specific data is present
Nov 18 04:43:22.884 INFO running `"docker" "inspect" "4bcd2dc76f5ecd34e93f7b8bfad385eea9ae401861a80fe2d7788c4e9229b743"`
Nov 18 04:43:23.045 INFO running `"docker" "rm" "-f" "4bcd2dc76f5ecd34e93f7b8bfad385eea9ae401861a80fe2d7788c4e9229b743"`
Nov 18 04:43:23.255 INFO [stdout] 4bcd2dc76f5ecd34e93f7b8bfad385eea9ae401861a80fe2d7788c4e9229b743
Nov 18 04:43:23.263 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 04:43:23.528 INFO [stdout] 36922e14768def32eaba9cfef9ee3d2bc93c2d4506f5027d4d6315892986fbb0
Nov 18 04:43:23.532 INFO running `"docker" "start" "-a" "36922e14768def32eaba9cfef9ee3d2bc93c2d4506f5027d4d6315892986fbb0"`
Nov 18 04:43:24.095 INFO [stderr] usermod: no changes
Nov 18 04:43:24.163 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.04s
Nov 18 04:43:24.175 INFO [stderr]      Running /target/debug/deps/opentracingrust-078d9e0b83ce367a
Nov 18 04:43:24.175 INFO [stdout] 
Nov 18 04:43:24.175 INFO [stdout] running 67 tests
Nov 18 04:43:24.175 INFO [stdout] test carrier::tests::hash_map::extract_keys ... ok
Nov 18 04:43:24.175 INFO [stdout] test carrier::tests::hash_map::find_keys ... ok
Nov 18 04:43:24.175 INFO [stdout] test carrier::tests::hash_map::inject_keys ... ok
Nov 18 04:43:24.175 INFO [stdout] test span::log::tests::set_default_timestamp ... ok
Nov 18 04:43:24.175 INFO [stdout] test span::log::tests::set_log_timestamp ... ok
Nov 18 04:43:24.175 INFO [stdout] test span::log::tests::defults_to_no_time ... ok
Nov 18 04:43:24.175 INFO [stdout] test span::tag::tests::get_missing_tag ... ok
Nov 18 04:43:24.175 INFO [stdout] test span::log::tests::add_field ... ok
Nov 18 04:43:24.175 INFO [stdout] test carrier::tests::tree_map::inject_keys ... ok
Nov 18 04:43:24.175 INFO [stdout] test carrier::tests::tree_map::find_keys ... ok
Nov 18 04:43:24.175 INFO [stdout] test span::tag::tests::iterate_over_tags ... ok
Nov 18 04:43:24.175 INFO [stdout] test carrier::tests::tree_map::extract_keys ... ok
Nov 18 04:43:24.180 INFO [stdout] test span::tag::tests::set_tag ... ok
Nov 18 04:43:24.180 INFO [stdout] test span::tests::send_span_on_finish ... ok
Nov 18 04:43:24.180 INFO [stdout] test span::tests::autofinish_as_mut ... ok
Nov 18 04:43:24.180 INFO [stdout] test span::tests::autofinish_deref ... ok
Nov 18 04:43:24.180 INFO [stdout] test span::tests::references::multi_refs ... ok
Nov 18 04:43:24.180 INFO [stdout] test span::tests::references::child_of ... ok
Nov 18 04:43:24.188 INFO [stdout] test span::tests::references::follows ... ok
Nov 18 04:43:24.188 INFO [stdout] test span::tests::span_follows_another ... ok
Nov 18 04:43:24.188 INFO [stdout] test span::tests::autoclose_finish_span_on_drop ... ok
Nov 18 04:43:24.188 INFO [stdout] test span::tests::span_child_of_another ... ok
Nov 18 04:43:24.188 INFO [stdout] test span::tests::tags::add_bool_tag ... ok
Nov 18 04:43:24.188 INFO [stdout] test span::tests::start_span_on_creation ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::set_span_name ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::tags::add_string_tag ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::tags::add_generic_tag ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::tags::add_integer_tag ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::tags::add_str_tag ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::times::finish_span_at_finish_time ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::tags::add_float_tag ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::times::finish_span_on_finish ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::times::start_time_set ... ok
Nov 18 04:43:24.199 INFO [stdout] test span::tests::times::starts_now_by_default ... ok
Nov 18 04:43:24.199 INFO [stdout] test span_context::impl_context::tests::clone_context ... ok
Nov 18 04:43:24.199 INFO [stdout] test span_context::tests::clone_span_context ... ok
Nov 18 04:43:24.199 INFO [stdout] test span_context::tests::extract_implementation_context ... ok
Nov 18 04:43:24.199 INFO [stdout] test span_context::tests::debug_formatting ... ok
Nov 18 04:43:24.199 INFO [stdout] test span_context::impl_context::tests::unwrap_context ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::inject_textmap ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::inject_http_headers ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::inject_binary ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::extract_textmap ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracers::file::tests::span::create ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::create_span ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::extract_binary ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracers::file::tests::span::extract::http_headers ... ok
Nov 18 04:43:24.199 INFO [stdout] test tracer::tests::extract_http_headers ... ok
Nov 18 04:43:24.200 INFO [stdout] test tracers::file::tests::span::extract::invalid::fails_if_invalid_span_id ... ok
Nov 18 04:43:24.200 INFO [stdout] test tracers::file::tests::span::inject::text_map ... ok
Nov 18 04:43:24.200 INFO [stdout] test tracers::file::tests::span::inject::http_headers ... ok
Nov 18 04:43:24.202 INFO [stdout] test tracers::file::tests::span::write ... ok
Nov 18 04:43:24.202 INFO [stdout] test tracers::noop::tests::inner_context::follows_updates_trace_id ... ok
Nov 18 04:43:24.202 INFO [stdout] test tracers::file::tests::span::extract::invalid::returns_none_without_span_id ... ok
Nov 18 04:43:24.202 INFO [stdout] test tracers::file::tests::span::extract::invalid::fails_if_invalid_trace_id ... ok
Nov 18 04:43:24.211 INFO [stdout] test tracers::file::tests::span::extract::invalid::returns_none_without_trace_id ... ok
Nov 18 04:43:24.211 INFO [stdout] test span_context::tests::set_baggage_item ... ok
Nov 18 04:43:24.211 INFO [stdout] test tracers::noop::tests::inner_context::child_of_updates_trace_id ... ok
Nov 18 04:43:24.215 INFO [stdout] test utils::fail::tests::fail_spans ... ok
Nov 18 04:43:24.231 INFO [stdout] test utils::global_tracer::tests::tracer_is_returned ... ok
Nov 18 04:43:24.247 INFO [stdout] test utils::global_tracer::tests::tracer_is_returned_to_many_threads ... ok
Nov 18 04:43:24.332 INFO [stdout] test tracers::file::tests::span::inject::binary_not_supported ... ok
Nov 18 04:43:24.332 INFO [stdout] test utils::global_tracer::tests::tracer_cannot_be_set_twice ... ok
Nov 18 04:43:24.332 INFO [stdout] test tracers::file::tests::span::extract::binary_not_supported ... ok
Nov 18 04:43:24.334 INFO [stdout] test utils::reporter::tests::receive_span ... ok
Nov 18 04:43:24.363 INFO [stdout] test tracers::noop::tests::inner_context::panics_if_invalid_context ... ok
Nov 18 04:43:24.363 INFO [stderr]    Doc-tests opentracingrust
Nov 18 04:43:24.363 INFO [stdout] test utils::global_tracer::tests::tracer_must_be_set ... ok
Nov 18 04:43:24.363 INFO [stdout] 
Nov 18 04:43:24.363 INFO [stdout] test result: ok. 67 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:43:24.363 INFO [stdout] 
Nov 18 04:43:26.695 INFO [stdout] 
Nov 18 04:43:26.696 INFO [stdout] running 14 tests
Nov 18 04:43:35.071 INFO [stdout] test src/span/mod.rs - span::StartOptions (line 350) ... ok
Nov 18 04:43:35.959 INFO [stdout] test src/carrier.rs - carrier::ExtractFormat (line 13) ... ok
Nov 18 04:43:35.975 INFO [stdout] test src/lib.rs -  (line 102) ... ok
Nov 18 04:43:36.127 INFO [stdout] test src/span_context/impl_context.rs - span_context::impl_context::ImplContext (line 27) ... ok
Nov 18 04:43:36.599 INFO [stdout] test src/span/mod.rs - span::Span::tag (line 300) ... ok
Nov 18 04:43:38.619 INFO [stdout] test src/lib.rs -  (line 68) ... ok
Nov 18 04:43:38.840 INFO [stdout] test src/carrier.rs - carrier::InjectFormat (line 43) ... ok
Nov 18 04:43:39.847 INFO [stdout] test src/span/log.rs - span::log::Log (line 17) ... ok
Nov 18 04:43:44.102 INFO [stdout] test src/utils/fail.rs - utils::fail::FailSpan (line 13) ... ok
Nov 18 04:43:44.177 INFO [stdout] test src/span_context/impl_context.rs - span_context::impl_context::ImplContextBox (line 108) ... ok
Nov 18 04:43:44.280 INFO [stdout] test src/span_context/mod.rs - span_context::SpanContext (line 39) ... ok
Nov 18 04:43:44.573 INFO [stdout] test src/tracers/noop.rs - tracers::noop::NoopTracer (line 35) ... ok
Nov 18 04:43:44.609 INFO [stdout] test src/tracers/file.rs - tracers::file::FileTracer (line 42) ... ok
Nov 18 04:43:44.779 INFO [stdout] test src/utils/global_tracer.rs - utils::global_tracer::GlobalTracer (line 29) ... ok
Nov 18 04:43:44.779 INFO [stdout] 
Nov 18 04:43:44.779 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 04:43:44.779 INFO [stdout] 
Nov 18 04:43:44.802 INFO [stderr] su: No module specific data is present
Nov 18 04:43:45.081 INFO running `"docker" "inspect" "36922e14768def32eaba9cfef9ee3d2bc93c2d4506f5027d4d6315892986fbb0"`
Nov 18 04:43:45.156 INFO running `"docker" "rm" "-f" "36922e14768def32eaba9cfef9ee3d2bc93c2d4506f5027d4d6315892986fbb0"`
Nov 18 04:43:45.325 INFO [stdout] 36922e14768def32eaba9cfef9ee3d2bc93c2d4506f5027d4d6315892986fbb0