[INFO] crate tracing 0.1.13 is already in cache [INFO] testing tracing-0.1.13 against try#41997647ba6a77908f6ef64401414feb00bccf16 for pr-71274 [INFO] extracting crate tracing 0.1.13 into /workspace/builds/worker-14/source [INFO] validating manifest of crates.io crate tracing 0.1.13 on toolchain 41997647ba6a77908f6ef64401414feb00bccf16 [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "read-manifest" "--manifest-path" "Cargo.toml"` [INFO] started tweaking crates.io crate tracing 0.1.13 [INFO] finished tweaking crates.io crate tracing 0.1.13 [INFO] tweaked toml for crates.io crate tracing 0.1.13 written to /workspace/builds/worker-14/source/Cargo.toml [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "fetch" "--locked" "--manifest-path" "Cargo.toml"` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "build" "--frozen"` [INFO] [stdout] 49a40794248549941e7ce877d891f081b95ecee3e49c31c568a3a6f465c9fdae [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `"docker" "start" "-a" "49a40794248549941e7ce877d891f081b95ecee3e49c31c568a3a6f465c9fdae"` [INFO] [stderr] Compiling proc-macro2 v1.0.10 [INFO] [stderr] Compiling unicode-xid v0.2.0 [INFO] [stderr] Compiling syn v1.0.17 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling cfg-if v0.1.10 [INFO] [stderr] Compiling tracing-core v0.1.10 [INFO] [stderr] Compiling quote v1.0.3 [INFO] [stderr] Compiling tracing-attributes v0.1.7 [INFO] [stderr] Compiling tracing v0.1.13 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 27.82s [INFO] running `"docker" "inspect" "49a40794248549941e7ce877d891f081b95ecee3e49c31c568a3a6f465c9fdae"` [INFO] running `"docker" "rm" "-f" "49a40794248549941e7ce877d891f081b95ecee3e49c31c568a3a6f465c9fdae"` [INFO] [stdout] 49a40794248549941e7ce877d891f081b95ecee3e49c31c568a3a6f465c9fdae [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "test" "--frozen" "--no-run"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 17abbb7b9b2e5e807d80d1b9c1af05b06560f09376b8d9995f3d5433eadf5a08 [INFO] running `"docker" "start" "-a" "17abbb7b9b2e5e807d80d1b9c1af05b06560f09376b8d9995f3d5433eadf5a08"` [INFO] [stderr] Compiling autocfg v1.0.0 [INFO] [stderr] Compiling semver-parser v0.7.0 [INFO] [stderr] Compiling libc v0.2.69 [INFO] [stderr] Compiling serde v1.0.106 [INFO] [stderr] Compiling maybe-uninit v2.0.0 [INFO] [stderr] Compiling ryu v1.0.3 [INFO] [stderr] Compiling byteorder v1.3.4 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling rayon-core v1.7.0 [INFO] [stderr] Compiling itoa v0.4.5 [INFO] [stderr] Compiling bitflags v1.2.1 [INFO] [stderr] Compiling either v1.5.3 [INFO] [stderr] Compiling unicode-width v0.1.7 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling oorandom v11.1.0 [INFO] [stderr] Compiling log v0.4.8 [INFO] [stderr] Compiling serde_derive v1.0.106 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling itertools v0.8.2 [INFO] [stderr] Compiling walkdir v2.3.1 [INFO] [stderr] Compiling semver v0.9.0 [INFO] [stderr] Compiling clap v2.33.0 [INFO] [stderr] Compiling rustc_version v0.2.3 [INFO] [stderr] Compiling crossbeam-utils v0.7.2 [INFO] [stderr] Compiling memoffset v0.5.4 [INFO] [stderr] Compiling crossbeam-epoch v0.8.2 [INFO] [stderr] Compiling num-traits v0.2.11 [INFO] [stderr] Compiling cast v0.2.3 [INFO] [stderr] Compiling regex-automata v0.1.9 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling crossbeam-queue v0.2.1 [INFO] [stderr] Compiling criterion-plot v0.4.1 [INFO] [stderr] Compiling crossbeam-deque v0.7.3 [INFO] [stderr] Compiling rayon v1.3.0 [INFO] [stderr] Compiling plotters v0.2.12 [INFO] [stderr] Compiling serde_json v1.0.51 [INFO] [stderr] Compiling bstr v0.2.12 [INFO] [stderr] Compiling csv v1.1.3 [INFO] [stderr] Compiling tinytemplate v1.0.3 [INFO] [stderr] Compiling criterion v0.3.1 [INFO] [stderr] Compiling tracing v0.1.13 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 1m 53s [INFO] running `"docker" "inspect" "17abbb7b9b2e5e807d80d1b9c1af05b06560f09376b8d9995f3d5433eadf5a08"` [INFO] running `"docker" "rm" "-f" "17abbb7b9b2e5e807d80d1b9c1af05b06560f09376b8d9995f3d5433eadf5a08"` [INFO] [stdout] 17abbb7b9b2e5e807d80d1b9c1af05b06560f09376b8d9995f3d5433eadf5a08 [INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-14/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "test" "--frozen"` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 814e8032d08a73f9a5e684c599620b888985268068b17119b3941571fe9edfac [INFO] running `"docker" "start" "-a" "814e8032d08a73f9a5e684c599620b888985268068b17119b3941571fe9edfac"` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.53s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/tracing-2d8588f5da0ab1ac [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test level_filters::tests::filter_level_conversion ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/event-b6e360b596f980f9 [INFO] [stdout] [INFO] [stdout] running 16 tests [INFO] [stdout] test both_shorthands ... ok [INFO] [stdout] test borrowed_field ... ok [INFO] [stdout] test debug_shorthand ... ok [INFO] [stdout] test wrapping_event_without_message ... ok [INFO] [stdout] test string_message_without_delims ... ok [INFO] [stdout] test one_with_everything ... ok [INFO] [stdout] test explicit_child_at_levels ... ok [INFO] [stdout] test event_without_message ... ok [INFO] [stdout] test nonzeroi32_event_without_message ... ok [INFO] [stdout] test event_with_message ... ok [INFO] [stdout] test explicit_child ... ok [INFO] [stdout] test moved_field ... ok [INFO] [stdout] test move_field_out_of_struct ... ok [INFO] [stdout] test message_without_delims ... ok [INFO] [stdout] test display_shorthand ... ok [INFO] [stdout] test dotted_field_name ... ok [INFO] [stderr] Running /opt/rustwide/target/debug/deps/filter_caching_is_lexically_scoped-7f282eeb0f6e486f [INFO] [stdout] [INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/filters_are_not_reevaluated_for_the_same_span-792d29e515bf4b9b [INFO] [stdout] running 1 test [INFO] [stdout] test filter_caching_is_lexically_scoped ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/filters_are_reevaluated_for_different_call_sites-00b8de85ffacbd46 [INFO] [stdout] running 1 test [INFO] [stdout] test filters_are_not_reevaluated_for_the_same_span ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test filters_are_reevaluated_for_different_call_sites ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/macro_imports-35329a30b3952fcd [INFO] [stderr] Running /opt/rustwide/target/debug/deps/macros-efd14d468941e663 [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test prefixed_event_macros ... ok [INFO] [stdout] test prefixed_span_macros ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 40 tests [INFO] [stdout] test callsite_macro_api ... ok [INFO] [stdout] test debug ... ok [INFO] [stdout] test debug_root ... ok [INFO] [stdout] test error_span_root ... ok [INFO] [stdout] test error_span_with_parent ... ok [INFO] [stdout] test error_with_parent ... ok [INFO] [stdout] test event ... ok [INFO] [stdout] test error_span ... ok [INFO] [stdout] test event_root ... ok [INFO] [stdout] test error_root ... ok [INFO] [stdout] test error ... ok [INFO] [stdout] test debug_with_parent ... ok [INFO] [stdout] test info_span ... ok [INFO] [stdout] test info_span_root ... ok [INFO] [stdout] test info_span_with_parent ... ok [INFO] [stdout] test debug_span_with_parent ... ok [INFO] [stdout] test info_with_parent ... ok [INFO] [stdout] test trace_span ... ok [INFO] [stdout] test locals_no_message ... ok [INFO] [stdout] test trace_with_parent ... ok [INFO] [stdout] test locals_with_message ... ok [INFO] [stdout] test span ... ok [INFO] [stdout] test trace_span_root ... ok [INFO] [stdout] test trace_span_with_parent ... ok [INFO] [stdout] test warn ... ok [INFO] [stdout] test warn_root ... ok [INFO] [stdout] test span_root ... ok [INFO] [stdout] test span_with_parent ... ok [INFO] [stdout] test trace ... ok [INFO] [stdout] test trace_root ... ok [INFO] [stdout] test warn_span_root ... ok [INFO] [stdout] test warn_with_parent ... ok [INFO] [stdout] test field_shorthand_only ... ok [INFO] [stdout] test debug_span_root ... ok [INFO] [stdout] test info_root ... ok [INFO] [stdout] test warn_span_with_parent ... ok [INFO] [stdout] test warn_span ... ok [INFO] [stdout] test debug_span ... ok [INFO] [stdout] test info ... ok [INFO] [stdout] test event_with_parent ... ok [INFO] [stderr] Running /opt/rustwide/target/debug/deps/span-3bb63d9d22bf1b49 [INFO] [stdout] [INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 31 tests [INFO] [stdout] test dotted_field_name ... ok [INFO] [stdout] test explicit_child_at_levels ... ok [INFO] [stdout] test explicit_child ... ok [INFO] [stdout] test both_shorthands ... ok [INFO] [stdout] test borrowed_field ... ok [INFO] [stdout] test cloning_a_span_calls_clone_span ... ok [INFO] [stdout] test display_shorthand ... ok [INFO] [stdout] test debug_shorthand ... ok [INFO] [stdout] test contextual_root ... ok [INFO] [stdout] test enter ... ok [INFO] [stdout] test dropping_a_span_calls_drop_span ... ok [INFO] [stdout] test explicit_child_regardless_of_ctx ... ok [INFO] [stdout] test event_outside_of_span ... ok [INFO] [stdout] test clone_and_drop_span_always_go_to_the_subscriber_that_tagged_the_span ... ok [INFO] [stdout] test contextual_child ... ok [INFO] [stdout] test drop_span_when_exiting_dispatchers_context ... ok [INFO] [stdout] test explicit_root_span_is_root ... ok [INFO] [stdout] test handles_to_different_spans_with_the_same_metadata_are_not_equal ... ok [INFO] [stdout] test handles_to_the_same_span_are_equal ... ok [INFO] [stdout] test moved_field ... ok [INFO] [stdout] test explicit_root_span_is_root_regardless_of_ctx ... ok [INFO] [stdout] test record_new_values_for_fields ... ok [INFO] [stdout] test move_field_out_of_struct ... ok [INFO] [stdout] test span_closes_after_event ... ok [INFO] [stdout] test span_closes_when_exited ... ok [INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them ... ok [INFO] [stdout] test new_span_with_target_and_log_level ... ok [INFO] [stdout] test record_new_value_for_field ... ok [INFO] [stdout] enter: foo; id=Id(1); [INFO] [stdout] exit: foo; id=Id(1); [INFO] [stdout] drop_span: foo; id=Id(1); refs=1; [INFO] [stdout] test spans_always_go_to_the_subscriber_that_tagged_them_even_across_threads ... ok [INFO] [stdout] test handles_to_different_spans_are_not_equal ... ok [INFO] [stdout] test new_span_after_event ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/subscriber-426643be8d15f67d [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test event_macros_dont_infinite_loop ... ok [INFO] [stderr] Doc-tests tracing [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 60 tests [INFO] [stdout] test src/lib.rs - (line 152) ... ignored [INFO] [stdout] test src/lib.rs - (line 367) ... ok [INFO] [stdout] test src/dispatcher.rs - dispatcher (line 83) ... ok [INFO] [stdout] test src/dispatcher.rs - dispatcher (line 25) ... ok [INFO] [stdout] test src/lib.rs - (line 457) ... ignored [INFO] [stdout] test src/lib.rs - (line 242) ... ok [INFO] [stdout] test src/lib.rs - (line 203) ... ok [INFO] [stdout] test src/lib.rs - (line 39) ... ok [INFO] [stdout] test src/dispatcher.rs - dispatcher (line 48) ... ok [INFO] [stdout] test src/lib.rs - (line 287) ... ok [INFO] [stdout] test src/lib.rs - (line 180) ... ok [INFO] [stdout] test src/lib.rs - (line 330) ... ok [INFO] [stdout] test src/lib.rs - (line 128) ... ok [INFO] [stdout] test src/lib.rs - (line 228) ... ok [INFO] [stdout] test src/lib.rs - (line 309) ... ok [INFO] [stdout] test src/lib.rs - (line 267) ... ok [INFO] [stdout] test src/lib.rs - (line 211) ... ok [INFO] [stdout] test src/lib.rs - (line 351) ... ok [INFO] [stdout] test src/lib.rs - (line 255) ... ok [INFO] [stdout] test src/lib.rs - (line 535) ... ok [INFO] [stdout] test src/lib.rs - (line 390) ... ok [INFO] [stdout] test src/macros.rs - debug (line 955) ... ok [INFO] [stdout] test src/macros.rs - info (line 1156) ... ok [INFO] [stdout] test src/macros.rs - info_span (line 255) ... ok [INFO] [stdout] test src/macros.rs - debug_span (line 174) ... ok [INFO] [stdout] test src/macros.rs - info_span (line 264) ... ok [INFO] [stdout] test src/lib.rs - (line 572) ... ok [INFO] [stdout] test src/macros.rs - trace_span (line 102) ... ok [INFO] [stdout] test src/macros.rs - error_span (line 416) ... ok [INFO] [stdout] test src/lib.rs - (line 62) ... ok [INFO] [stdout] test src/macros.rs - span (line 11) ... ok [INFO] [stdout] test src/macros.rs - debug_span (line 183) ... ok [INFO] [stdout] test src/macros.rs - trace (line 755) ... ok [INFO] [stdout] test src/macros.rs - error_span (line 425) ... ok [INFO] [stdout] test src/macros.rs - error (line 1573) ... ok [INFO] [stdout] test src/macros.rs - event (line 495) ... ok [INFO] [stdout] test src/span.rs - span (line 137) ... ok [INFO] [stdout] test src/macros.rs - warn (line 1368) ... ok [INFO] [stdout] test src/span.rs - span (line 181) ... ok [INFO] [stdout] test src/macros.rs - warn_span (line 336) ... ok [INFO] [stdout] test src/macros.rs - trace_span (line 93) ... ok [INFO] [stdout] test src/macros.rs - warn_span (line 345) ... ok [INFO] [stdout] test src/span.rs - span (line 84) ... ok [INFO] [stdout] test src/span.rs - span (line 31) ... ok [INFO] [stdout] test src/span.rs - span::Span::enter (line 519) ... ok [INFO] [stdout] test src/span.rs - span (line 115) ... ok [INFO] [stdout] test src/span.rs - span (line 258) ... ok [INFO] [stdout] test src/span.rs - span (line 223) ... ok [INFO] [stdout] test src/span.rs - span (line 271) ... ok [INFO] [stdout] test src/span.rs - span (line 66) ... ok [INFO] [stdout] test src/span.rs - span::Span::enter (line 503) ... ok [INFO] [stdout] test src/span.rs - span::Span::enter (line 541) ... ok [INFO] [stdout] test src/span.rs - span::Span::follows_from (line 760) ... ok [INFO] [stdout] test src/span.rs - span::Span::record (line 640) ... ok [INFO] [stdout] test src/span.rs - span::Span::follows_from (line 775) ... ok [INFO] [stdout] test src/span.rs - span::Span::follows_from (line 752) ... ok [INFO] [stdout] test src/span.rs - span::Span::follows_from (line 767) ... ok [INFO] [stdout] test src/span.rs - span::Span::in_scope (line 586) ... ok [INFO] [stdout] test src/span.rs - span::Span::record (line 655) ... ok [INFO] [stdout] test src/span.rs - span::Span::in_scope (line 601) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 58 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out [INFO] [stdout] [INFO] running `"docker" "inspect" "814e8032d08a73f9a5e684c599620b888985268068b17119b3941571fe9edfac"` [INFO] running `"docker" "rm" "-f" "814e8032d08a73f9a5e684c599620b888985268068b17119b3941571fe9edfac"` [INFO] [stdout] 814e8032d08a73f9a5e684c599620b888985268068b17119b3941571fe9edfac