Dec 02 23:24:49.817 INFO testing oysterpack_log-0.1.0 against 1.30.1 for beta-1.31-5
Dec 02 23:24:49.817 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 23:24:50.051 INFO [stdout] f75aad34552c6cb6462f26ff2e48b501d085ce150de8f44ba1f2dac5a4b22398
Dec 02 23:24:50.053 INFO running `"docker" "start" "-a" "f75aad34552c6cb6462f26ff2e48b501d085ce150de8f44ba1f2dac5a4b22398"`
Dec 02 23:24:50.464 INFO [stderr] usermod: no changes
Dec 02 23:24:50.847 INFO [stderr]    Compiling hex v0.3.2
Dec 02 23:24:50.847 INFO [stderr]    Compiling rustc-workspace-hack v1.0.0
Dec 02 23:24:50.847 INFO [stderr]    Compiling home v0.3.3
Dec 02 23:24:50.847 INFO [stderr]    Compiling lazycell v1.2.0
Dec 02 23:24:50.875 INFO [stderr]    Compiling shell-escape v0.1.4
Dec 02 23:24:50.875 INFO [stderr]    Compiling dot v0.1.4
Dec 02 23:24:50.875 INFO [stderr]    Compiling oysterpack_macros v0.1.0
Dec 02 23:24:50.875 INFO [stderr]    Compiling rand_isaac v0.1.1
Dec 02 23:24:51.523 INFO [stderr]    Compiling rand_hc v0.1.0
Dec 02 23:24:52.443 INFO [stderr]    Compiling rand_xorshift v0.1.0
Dec 02 23:24:53.443 INFO [stderr]    Compiling owning_ref v0.4.0
Dec 02 23:24:56.397 INFO [stderr]    Compiling crossbeam-utils v0.6.1
Dec 02 23:24:56.425 INFO [stderr]    Compiling num-traits v0.2.6
Dec 02 23:24:56.447 INFO [stderr]    Compiling serde_ignored v0.0.4
Dec 02 23:24:56.447 INFO [stderr]    Compiling toml v0.4.9
Dec 02 23:24:56.479 INFO [stderr]    Compiling socket2 v0.3.8
Dec 02 23:24:56.539 INFO [stderr]    Compiling syntex_errors v0.58.1
Dec 02 23:24:58.243 INFO [stderr]    Compiling fs2 v0.4.3
Dec 02 23:24:59.299 INFO [stderr]    Compiling libz-sys v1.0.25
Dec 02 23:25:00.495 INFO [stderr]    Compiling log v0.3.9
Dec 02 23:25:02.343 INFO [stderr]    Compiling jobserver v0.1.12
Dec 02 23:25:02.382 INFO [stderr]    Compiling fern v0.5.7
Dec 02 23:25:02.382 INFO [stderr]    Compiling lock_api v0.1.5
Dec 02 23:25:03.295 INFO [stderr]    Compiling tar v0.4.20
Dec 02 23:25:11.535 INFO [stderr]    Compiling crossbeam-epoch v0.6.1
Dec 02 23:25:12.599 INFO [stderr]    Compiling openssl v0.10.15
Dec 02 23:25:12.607 INFO [stderr]    Compiling flate2 v1.0.5
Dec 02 23:25:12.640 INFO [stderr]    Compiling curl-sys v0.4.15
Dec 02 23:25:12.803 INFO [stderr]    Compiling libssh2-sys v0.2.11
Dec 02 23:25:12.864 INFO [stderr]    Compiling num-integer v0.1.39
Dec 02 23:25:18.599 INFO [stderr]    Compiling syntex_syntax v0.58.1
Dec 02 23:25:18.920 INFO [stderr]    Compiling petgraph v0.4.13
Dec 02 23:25:18.979 INFO [stderr]    Compiling chrono v0.4.6
Dec 02 23:25:18.991 INFO [stderr]    Compiling libgit2-sys v0.7.10
Dec 02 23:25:19.039 INFO [stderr]    Compiling curl v0.4.19
Dec 02 23:25:19.123 INFO [stderr]    Compiling globset v0.4.2
Dec 02 23:25:26.749 INFO [stderr]    Compiling env_logger v0.5.13
Dec 02 23:25:27.695 INFO [stderr]    Compiling parking_lot_core v0.3.1
Dec 02 23:25:44.076 INFO [stderr]    Compiling rand_chacha v0.1.0
Dec 02 23:25:44.093 INFO [stderr]    Compiling rand_pcg v0.1.1
Dec 02 23:25:44.212 INFO [stderr]    Compiling git2 v0.7.5
Dec 02 23:25:44.217 INFO [stderr]    Compiling parking_lot v0.6.4
Dec 02 23:25:44.292 INFO [stderr]    Compiling rustfix v0.4.2
Dec 02 23:25:50.873 INFO [stderr]    Compiling crates-io v0.18.0
Dec 02 23:25:50.919 INFO [stderr]    Compiling rand v0.6.1
Dec 02 23:25:51.255 INFO [stderr]    Compiling crypto-hash v0.3.1
Dec 02 23:25:54.177 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Dec 02 23:26:07.392 INFO [stderr]    Compiling ignore v0.4.4
Dec 02 23:26:07.658 INFO [stderr]    Compiling tempfile v3.0.5
Dec 02 23:26:15.984 INFO [stderr]    Compiling git2-curl v0.8.2
Dec 02 23:26:15.985 INFO [stderr]    Compiling built v0.3.0
Dec 02 23:26:28.699 INFO [stderr]    Compiling cargo v0.30.0
Dec 02 23:26:53.683 INFO [stderr]    Compiling mml v0.1.41
Dec 02 23:27:01.155 INFO [stderr]    Compiling oysterpack_app_metadata v0.3.1
Dec 02 23:27:12.676 INFO [stderr] su: No module specific data is present
Dec 02 23:27:13.387 INFO running `"docker" "inspect" "f75aad34552c6cb6462f26ff2e48b501d085ce150de8f44ba1f2dac5a4b22398"`
Dec 02 23:27:13.804 INFO running `"docker" "rm" "-f" "f75aad34552c6cb6462f26ff2e48b501d085ce150de8f44ba1f2dac5a4b22398"`
Dec 02 23:27:14.098 INFO [stdout] f75aad34552c6cb6462f26ff2e48b501d085ce150de8f44ba1f2dac5a4b22398
Dec 02 23:27:14.101 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 23:27:14.532 INFO [stdout] e94e9fb788db83921a907e1faa64c84479647dbba426695f909a1a18ce510848
Dec 02 23:27:14.534 INFO running `"docker" "start" "-a" "e94e9fb788db83921a907e1faa64c84479647dbba426695f909a1a18ce510848"`
Dec 02 23:27:16.155 INFO [stderr] usermod: no changes
Dec 02 23:27:16.518 INFO [stderr]    Compiling synom v0.11.3
Dec 02 23:27:16.519 INFO [stderr]    Compiling itertools v0.7.11
Dec 02 23:27:16.531 INFO [stderr]    Compiling oysterpack_app_metadata v0.3.1
Dec 02 23:27:16.532 INFO [stderr]    Compiling cargo v0.30.0
Dec 02 23:27:19.905 INFO [stderr]    Compiling syn v0.11.11
Dec 02 23:27:24.857 INFO [stderr]    Compiling oysterpack_app_metadata_macros v0.1.0
Dec 02 23:27:38.719 INFO [stderr]    Compiling version-sync v0.5.0
Dec 02 23:28:31.363 INFO [stderr]    Compiling oysterpack_built v0.3.2
Dec 02 23:28:40.374 INFO [stderr]    Compiling oysterpack_log v0.1.0 (/source)
Dec 02 23:29:06.919 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 50s
Dec 02 23:29:06.935 INFO [stderr] su: No module specific data is present
Dec 02 23:29:07.675 INFO running `"docker" "inspect" "e94e9fb788db83921a907e1faa64c84479647dbba426695f909a1a18ce510848"`
Dec 02 23:29:07.978 INFO running `"docker" "rm" "-f" "e94e9fb788db83921a907e1faa64c84479647dbba426695f909a1a18ce510848"`
Dec 02 23:29:08.303 INFO [stdout] e94e9fb788db83921a907e1faa64c84479647dbba426695f909a1a18ce510848
Dec 02 23:29:08.311 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 23:29:08.737 INFO [stdout] a6e5859632923fade535dc11fff8fe1733fb9dc56d85fe6dd1ba2e560ea27477
Dec 02 23:29:08.739 INFO running `"docker" "start" "-a" "a6e5859632923fade535dc11fff8fe1733fb9dc56d85fe6dd1ba2e560ea27477"`
Dec 02 23:29:10.051 INFO [stderr] usermod: no changes
Dec 02 23:29:10.383 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.28s
Dec 02 23:29:10.423 INFO [stderr]      Running /target/debug/deps/oysterpack_log-a23f5c41e3592fa7
Dec 02 23:29:10.432 INFO [stdout] 
Dec 02 23:29:10.432 INFO [stdout] running 5 tests
Dec 02 23:29:10.456 INFO [stdout] [23:29:10.455][INFO][oysterpack_log::manager][src/manager.rs:62] logging has been initialized using config: {
Dec 02 23:29:10.456 INFO [stdout]   "root_level": "WARN",
Dec 02 23:29:10.456 INFO [stdout]   "crate_level": "DEBUG",
Dec 02 23:29:10.456 INFO [stdout]   "output": {
Dec 02 23:29:10.456 INFO [stdout]     "Stdout": "\n"
Dec 02 23:29:10.456 INFO [stdout]   }
Dec 02 23:29:10.456 INFO [stdout] }
Dec 02 23:29:10.456 INFO [stdout] [23:29:10.456][INFO][oysterpack_log::config::tests][src/config.rs:212] {
Dec 02 23:29:10.456 INFO [stdout]   "root_level": "INFO",
Dec 02 23:29:10.456 INFO [stdout]   "crate_level": "INFO",
Dec 02 23:29:10.456 INFO [stdout]   "target_levels": {
Dec 02 23:29:10.456 INFO [stdout]     "a": "WARN",
Dec 02 23:29:10.456 INFO [stdout]     "b": "ERROR",
Dec 02 23:29:10.456 INFO [stdout]     "c": "DEBUG"
Dec 02 23:29:10.456 INFO [stdout]   },
Dec 02 23:29:10.456 INFO [stdout]   "output": {
Dec 02 23:29:10.456 INFO [stdout]     "Stdout": "\n"
Dec 02 23:29:10.456 INFO [stdout]   }
Dec 02 23:29:10.456 INFO [stdout] }
Dec 02 23:29:10.456 INFO [stdout] [23:29:10.456][INFO][oysterpack_log::tests][src/lib.rs:110] default_log_config: test run time: 108.416µs
Dec 02 23:29:10.459 INFO [stdout] test config::tests::log_config_with_all_fields_configured ... ok
Dec 02 23:29:10.463 INFO [stdout] [23:29:10.463][WARN][oysterpack_log::manager][src/manager.rs:70] logging has already been initialized
Dec 02 23:29:10.464 INFO [stdout] [23:29:10.464][INFO][oysterpack_log::foo][src/config.rs:236] foo says hello
Dec 02 23:29:10.464 INFO [stdout] [23:29:10.464][INFO][oysterpack_log::tests][src/lib.rs:110] for_crate: test run time: 325.853µs
Dec 02 23:29:10.465 INFO [stdout] [23:29:10.464][WARN][oysterpack_log::manager][src/manager.rs:70] logging has already been initialized
Dec 02 23:29:10.465 INFO [stdout] [23:29:10.465][INFO][oysterpack_log::config::tests][src/config.rs:195] {"root_level":"WARN","output":{"Stdout":"\n"}}
Dec 02 23:29:10.465 INFO [stdout] [23:29:10.465][INFO][oysterpack_log::tests][src/lib.rs:110] default_log_config: test run time: 290.86µs
Dec 02 23:29:10.466 INFO [stdout] [23:29:10.466][WARN][oysterpack_log::manager][src/manager.rs:70] logging has already been initialized
Dec 02 23:29:10.466 INFO [stdout] [23:29:10.466][INFO][oysterpack_log::config::tests][src/config.rs:186] {"root_level":"INFO","output":{"Stdout":"\n"}}
Dec 02 23:29:10.466 INFO [stdout] [23:29:10.466][INFO][oysterpack_log::tests][src/lib.rs:110] root_log_level_configured: test run time: 285.826µs
Dec 02 23:29:10.467 INFO [stdout] [23:29:10.467][WARN][oysterpack_log::manager][src/manager.rs:70] logging has already been initialized
Dec 02 23:29:10.467 INFO [stdout] [23:29:10.467][INFO][oysterpack_log::tests][src/lib.rs:119] it compiles :)
Dec 02 23:29:10.468 INFO [stdout] [23:29:10.467][INFO][oysterpack_log::tests][src/lib.rs:110] compiles: test run time: 232.113µs
Dec 02 23:29:10.486 INFO [stdout] test config::tests::for_crate ... ok
Dec 02 23:29:10.487 INFO [stdout] test config::tests::default_log_config ... ok
Dec 02 23:29:10.487 INFO [stdout] test config::tests::root_log_level_configured ... ok
Dec 02 23:29:10.487 INFO [stdout] test tests::compiles ... ok
Dec 02 23:29:10.487 INFO [stdout] 
Dec 02 23:29:10.487 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 23:29:10.487 INFO [stdout] 
Dec 02 23:29:10.487 INFO [stdout] 
Dec 02 23:29:10.487 INFO [stdout] running 1 test
Dec 02 23:29:10.487 INFO [stderr]      Running /target/debug/deps/version_numbers-c66498b1c21c544d
Dec 02 23:29:10.507 INFO [stdout] test test_html_root_url ... ok
Dec 02 23:29:10.507 INFO [stderr]    Doc-tests oysterpack_log
Dec 02 23:29:10.507 INFO [stdout] 
Dec 02 23:29:10.507 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 23:29:10.507 INFO [stdout] 
Dec 02 23:29:14.138 INFO [stdout] 
Dec 02 23:29:14.138 INFO [stdout] running 2 tests
Dec 02 23:29:22.622 INFO [stdout] test src/lib.rs -  (line 18) ... ok
Dec 02 23:29:22.950 INFO [stdout] test src/config.rs - config::Target::append (line 111) ... ok
Dec 02 23:29:22.950 INFO [stdout] 
Dec 02 23:29:22.950 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 23:29:22.950 INFO [stdout] 
Dec 02 23:29:22.967 INFO [stderr] su: No module specific data is present
Dec 02 23:29:23.715 INFO running `"docker" "inspect" "a6e5859632923fade535dc11fff8fe1733fb9dc56d85fe6dd1ba2e560ea27477"`
Dec 02 23:29:23.997 INFO running `"docker" "rm" "-f" "a6e5859632923fade535dc11fff8fe1733fb9dc56d85fe6dd1ba2e560ea27477"`
Dec 02 23:29:24.427 INFO [stdout] a6e5859632923fade535dc11fff8fe1733fb9dc56d85fe6dd1ba2e560ea27477
