[INFO] fetching crate env_logger 0.11.5...
[INFO] testing env_logger-0.11.5 against 1.83.0 for beta-1.84.0-4-retry
[INFO] extracting crate env_logger 0.11.5 into /workspace/builds/worker-0-tc1/source
[INFO] validating manifest of crates.io crate env_logger 0.11.5 on toolchain 1.83.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.83.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate env_logger 0.11.5
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate env_logger 0.11.5
[INFO] tweaked toml for crates.io crate env_logger 0.11.5 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] crate crates.io crate env_logger 0.11.5 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.83.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+1.83.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b842d6ef62ff54f8c00816e79ba9cd358d1a4ff564aa821b43e82e1b14fe7837
[INFO] running `Command { std: "docker" "start" "-a" "b842d6ef62ff54f8c00816e79ba9cd358d1a4ff564aa821b43e82e1b14fe7837", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b842d6ef62ff54f8c00816e79ba9cd358d1a4ff564aa821b43e82e1b14fe7837", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b842d6ef62ff54f8c00816e79ba9cd358d1a4ff564aa821b43e82e1b14fe7837", kill_on_drop: false }`
[INFO] [stdout] b842d6ef62ff54f8c00816e79ba9cd358d1a4ff564aa821b43e82e1b14fe7837
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+1.83.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 227e9adad5dcec79df12d7e6d27f4aca48c265345d9a3cbca6a6d133330f14af
[INFO] running `Command { std: "docker" "start" "-a" "227e9adad5dcec79df12d7e6d27f4aca48c265345d9a3cbca6a6d133330f14af", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.5.0
[INFO] [stderr]    Compiling regex-syntax v0.6.28
[INFO] [stderr]    Compiling utf8parse v0.2.1
[INFO] [stderr]    Compiling anstyle-query v1.0.2
[INFO] [stderr]    Compiling log v0.4.21
[INFO] [stderr]    Compiling colorchoice v1.0.0
[INFO] [stderr]    Compiling anstyle v1.0.6
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling anstyle-parse v0.2.3
[INFO] [stderr]    Compiling anstream v0.6.11
[INFO] [stderr]    Compiling aho-corasick v0.7.19
[INFO] [stderr]    Compiling regex v1.7.0
[INFO] [stderr]    Compiling env_filter v0.1.2
[INFO] [stderr]    Compiling env_logger v0.11.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 5.73s
[INFO] running `Command { std: "docker" "inspect" "227e9adad5dcec79df12d7e6d27f4aca48c265345d9a3cbca6a6d133330f14af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "227e9adad5dcec79df12d7e6d27f4aca48c265345d9a3cbca6a6d133330f14af", kill_on_drop: false }`
[INFO] [stdout] 227e9adad5dcec79df12d7e6d27f4aca48c265345d9a3cbca6a6d133330f14af
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+1.83.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7089d0784c8d96338ca98cff27937cfc1ff63df4079dd2dda4edfd3ac71f2e4d
[INFO] running `Command { std: "docker" "start" "-a" "7089d0784c8d96338ca98cff27937cfc1ff63df4079dd2dda4edfd3ac71f2e4d", kill_on_drop: false }`
[INFO] [stderr]    Compiling env_logger v0.11.5 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.94s
[INFO] running `Command { std: "docker" "inspect" "7089d0784c8d96338ca98cff27937cfc1ff63df4079dd2dda4edfd3ac71f2e4d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7089d0784c8d96338ca98cff27937cfc1ff63df4079dd2dda4edfd3ac71f2e4d", kill_on_drop: false }`
[INFO] [stdout] 7089d0784c8d96338ca98cff27937cfc1ff63df4079dd2dda4edfd3ac71f2e4d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/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" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:f3a9d4ad9d972b27faf3965f35b62e55ba32bbce8f20bc8fe909558a86702fde" "/opt/rustwide/cargo-home/bin/cargo" "+1.83.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] c9df0db81035c0c77c800ba8759631f906a636554bcc5bbfb2fc51ba4684967f
[INFO] running `Command { std: "docker" "start" "-a" "c9df0db81035c0c77c800ba8759631f906a636554bcc5bbfb2fc51ba4684967f", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/env_logger-07a8734fe41e343c)
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test fmt::tests::format_no_header ... ok
[INFO] [stdout] test fmt::tests::format_indent_spaces ... ok
[INFO] [stdout] test fmt::tests::format_indent_spaces_no_header ... ok
[INFO] [stdout] test fmt::tests::format_suffix ... ok
[INFO] [stdout] test fmt::tests::format_suffix_with_indent ... ok
[INFO] [stdout] test fmt::tests::format_target ... ok
[INFO] [stdout] test fmt::tests::format_with_header ... ok
[INFO] [stdout] test fmt::writer::tests::parse_write_style_valid ... ok
[INFO] [stdout] test logger::tests::builder_parse_env_overrides_existing_filters ... ok
[INFO] [stdout] test logger::tests::env_get_write_style_reads_from_var_if_set ... ok
[INFO] [stdout] test fmt::tests::format_empty_target ... ok
[INFO] [stdout] test fmt::writer::tests::parse_write_style_invalid ... ok
[INFO] [stdout] test logger::tests::env_get_filter_reads_from_default_if_var_not_set ... ok
[INFO] [stdout] test logger::tests::env_get_filter_reads_from_var_if_set ... ok
[INFO] [stdout] test logger::tests::env_get_write_style_reads_from_default_if_var_not_set ... ok
[INFO] [stdout] test fmt::tests::format_indent_zero_spaces ... ok
[INFO] [stdout] test fmt::tests::format_no_target ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/init-twice-retains-filter.rs (/opt/rustwide/target/debug/deps/init_twice_retains_filter-d9253eb4ced77b7b)
[INFO] [stderr]      Running tests/log-in-log.rs (/opt/rustwide/target/debug/deps/log_in_log-ffee3fada3760c9e)
[INFO] [stderr]      Running tests/log_tls_dtors.rs (/opt/rustwide/target/debug/deps/log_tls_dtors-c4f47dde4b28ef01)
[INFO] [stderr]      Running tests/regexp_filter.rs (/opt/rustwide/target/debug/deps/regexp_filter-259d4e591c2d9db3)
[INFO] [stderr]    Doc-tests env_logger
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test src/lib.rs - (line 63) ... FAILED
[INFO] [stdout] test src/lib.rs - (line 40) ... FAILED
[INFO] [stdout] test src/lib.rs - (line 46) ... FAILED
[INFO] [stdout] test src/lib.rs - (line 73) ... FAILED
[INFO] [stdout] test src/lib.rs - (line 35) ... FAILED
[INFO] [stdout] test src/lib.rs - (line 55) ... FAILED
[INFO] [stdout] test src/fmt/mod.rs - fmt (line 42) ... ok
[INFO] [stdout] test src/lib.rs - (line 175) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::filter_module (line 341) ... ok
[INFO] [stdout] test src/fmt/mod.rs - fmt (line 21) ... ok
[INFO] [stdout] test src/lib.rs - (line 214) ... ok
[INFO] [stdout] test src/lib.rs - (line 253) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::filter (line 382) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::from_env (line 96) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder (line 22) ... ok
[INFO] [stdout] test src/lib.rs - (line 19) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::target (line 415) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::new (line 56) ... ok
[INFO] [stdout] test src/lib.rs - (line 234) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::filter_level (line 360) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::format (line 231) ... ok
[INFO] [stdout] test src/fmt/humantime.rs - fmt::humantime::Formatter::timestamp (line 17) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::write_style (line 436) ... ok
[INFO] [stdout] test src/fmt/mod.rs - fmt::Formatter (line 121) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::from_env (line 86) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::from_default_env (line 176) ... ok
[INFO] [stdout] test src/logger.rs - logger::Logger::from_env (line 567) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::parse_env (line 123) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::parse_env (line 138) ... ok
[INFO] [stdout] test src/logger.rs - logger::Builder::parse_default_env (line 199) ... ok
[INFO] [stdout] test src/logger.rs - logger::Logger::from_env (line 576) ... ok
[INFO] [stdout] test src/logger.rs - logger::Logger::from_default_env (line 600) ... ok
[INFO] [stdout] test src/logger.rs - logger::init_from_env (line 927) ... ok
[INFO] [stdout] test src/logger.rs - logger::try_init_from_env (line 890) ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- src/lib.rs - (line 63) stdout ----
[INFO] [stdout] error: expected expression, found `$`
[INFO] [stdout]  --> src/lib.rs:64:1
[INFO] [stdout]   |
[INFO] [stdout] 3 | $ RUST_LOG=main ./main
[INFO] [stdout]   | ^ expected expression
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/lib.rs - (line 40) stdout ----
[INFO] [stdout] error: expected expression, found `$`
[INFO] [stdout]  --> src/lib.rs:41:1
[INFO] [stdout]   |
[INFO] [stdout] 3 | $ RUST_LOG=info ./main
[INFO] [stdout]   | ^ expected expression
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/lib.rs - (line 46) stdout ----
[INFO] [stdout] error: expected expression, found `$`
[INFO] [stdout]  --> src/lib.rs:47:1
[INFO] [stdout]   |
[INFO] [stdout] 3 | $ RUST_LOG=debug ./main
[INFO] [stdout]   | ^ expected expression
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/lib.rs - (line 73) stdout ----
[INFO] [stdout] error: expected expression, found `$`
[INFO] [stdout]  --> src/lib.rs:74:1
[INFO] [stdout]   |
[INFO] [stdout] 3 | $ RUST_LOG=my_app ./my-app
[INFO] [stdout]   | ^ expected expression
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/lib.rs - (line 35) stdout ----
[INFO] [stdout] error: expected expression, found `$`
[INFO] [stdout]  --> src/lib.rs:36:1
[INFO] [stdout]   |
[INFO] [stdout] 3 | $ RUST_LOG=error ./main
[INFO] [stdout]   | ^ expected expression
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] ---- src/lib.rs - (line 55) stdout ----
[INFO] [stdout] error: expected expression, found `$`
[INFO] [stdout]  --> src/lib.rs:56:1
[INFO] [stdout]   |
[INFO] [stdout] 3 | $ RUST_LOG=main=info ./main
[INFO] [stdout]   | ^ expected expression
[INFO] [stdout] 
[INFO] [stdout] error: aborting due to 1 previous error
[INFO] [stdout] 
[INFO] [stdout] Couldn't compile the test.
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     src/lib.rs - (line 35)
[INFO] [stdout]     src/lib.rs - (line 40)
[INFO] [stdout]     src/lib.rs - (line 46)
[INFO] [stdout]     src/lib.rs - (line 55)
[INFO] [stdout]     src/lib.rs - (line 63)
[INFO] [stdout]     src/lib.rs - (line 73)
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 28 passed; 6 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.26s
[INFO] [stdout] 
[INFO] [stderr] error: doctest failed, to rerun pass `--doc`
[INFO] running `Command { std: "docker" "inspect" "c9df0db81035c0c77c800ba8759631f906a636554bcc5bbfb2fc51ba4684967f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c9df0db81035c0c77c800ba8759631f906a636554bcc5bbfb2fc51ba4684967f", kill_on_drop: false }`
[INFO] [stdout] c9df0db81035c0c77c800ba8759631f906a636554bcc5bbfb2fc51ba4684967f
