Dec 07 08:11:10.614 INFO testing log-0.4.6 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514
Dec 07 08:11:10.614 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 08:11:10.845 INFO [stdout] 4122b9f5098ed25dbaa74fa473a9e9492e977db79e39ce6881136eb890344d39
Dec 07 08:11:10.849 INFO running `"docker" "start" "-a" "4122b9f5098ed25dbaa74fa473a9e9492e977db79e39ce6881136eb890344d39"`
Dec 07 08:11:11.372 INFO [stderr] usermod: no changes
Dec 07 08:11:11.426 INFO [stderr]    Compiling log v0.4.6 (/source)
Dec 07 08:11:11.913 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.50s
Dec 07 08:11:11.914 INFO [stderr] su: No module specific data is present
Dec 07 08:11:12.270 INFO running `"docker" "inspect" "4122b9f5098ed25dbaa74fa473a9e9492e977db79e39ce6881136eb890344d39"`
Dec 07 08:11:12.386 INFO running `"docker" "rm" "-f" "4122b9f5098ed25dbaa74fa473a9e9492e977db79e39ce6881136eb890344d39"`
Dec 07 08:11:12.484 INFO [stdout] 4122b9f5098ed25dbaa74fa473a9e9492e977db79e39ce6881136eb890344d39
Dec 07 08:11:12.486 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 08:11:12.699 INFO [stdout] c21b05fed380b1ad034d33ae670dbce70d146d543b3739c578441708a613c495
Dec 07 08:11:12.702 INFO running `"docker" "start" "-a" "c21b05fed380b1ad034d33ae670dbce70d146d543b3739c578441708a613c495"`
Dec 07 08:11:13.260 INFO [stderr] usermod: no changes
Dec 07 08:11:13.380 INFO [stderr]    Compiling log v0.4.6 (/source)
Dec 07 08:11:14.769 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.47s
Dec 07 08:11:14.772 INFO [stderr] su: No module specific data is present
Dec 07 08:11:15.167 INFO running `"docker" "inspect" "c21b05fed380b1ad034d33ae670dbce70d146d543b3739c578441708a613c495"`
Dec 07 08:11:15.251 INFO running `"docker" "rm" "-f" "c21b05fed380b1ad034d33ae670dbce70d146d543b3739c578441708a613c495"`
Dec 07 08:11:15.331 INFO [stdout] c21b05fed380b1ad034d33ae670dbce70d146d543b3739c578441708a613c495
Dec 07 08:11:15.332 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 08:11:15.523 INFO [stdout] 7e554f2e13793e692286ab74a3742381259fc3c8dda769b4f6a3d1d18fc58911
Dec 07 08:11:15.525 INFO running `"docker" "start" "-a" "7e554f2e13793e692286ab74a3742381259fc3c8dda769b4f6a3d1d18fc58911"`
Dec 07 08:11:16.242 INFO [stderr] usermod: no changes
Dec 07 08:11:16.285 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 07 08:11:16.300 INFO [stderr]      Running /target/debug/deps/log-b6ce805dbc85a2f5
Dec 07 08:11:16.305 INFO [stdout] 
Dec 07 08:11:16.306 INFO [stdout] running 13 tests
Dec 07 08:11:16.306 INFO [stdout] test tests::test_metadata_builder ... ok
Dec 07 08:11:16.306 INFO [stdout] test tests::test_levelfilter_show ... ok
Dec 07 08:11:16.306 INFO [stdout] test tests::test_levelfilter_from_str ... ok
Dec 07 08:11:16.306 INFO [stdout] test tests::test_cross_cmp ... ok
Dec 07 08:11:16.306 INFO [stdout] test tests::test_level_show ... ok
Dec 07 08:11:16.306 INFO [stdout] test tests::test_record_builder ... ok
Dec 07 08:11:16.306 INFO [stdout] test tests::test_record_complete_builder ... ok
Dec 07 08:11:16.308 INFO [stdout] test tests::test_cross_eq ... ok
Dec 07 08:11:16.308 INFO [stdout] test tests::test_record_convenience_builder ... ok
Dec 07 08:11:16.308 INFO [stdout] test tests::test_to_level ... ok
Dec 07 08:11:16.308 INFO [stdout] test tests::test_to_level_filter ... ok
Dec 07 08:11:16.309 INFO [stdout] test tests::test_metadata_convenience_builder ... ok
Dec 07 08:11:16.309 INFO [stdout] test tests::test_level_from_str ... ok
Dec 07 08:11:16.309 INFO [stdout] 
Dec 07 08:11:16.309 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:16.309 INFO [stdout] 
Dec 07 08:11:16.313 INFO [stderr]      Running /target/debug/deps/filters-cc6893ad0ac5001b
Dec 07 08:11:16.324 INFO [stderr]    Doc-tests log
Dec 07 08:11:16.512 INFO [stdout] 
Dec 07 08:11:16.512 INFO [stdout] running 17 tests
Dec 07 08:11:19.585 INFO [stdout] test src/lib.rs - MetadataBuilder (line 970) ... ok
Dec 07 08:11:19.676 INFO [stdout] test src/lib.rs - Metadata (line 913) ... ok
Dec 07 08:11:20.404 INFO [stdout] test src/lib.rs - Record (line 681) ... ok
Dec 07 08:11:20.423 INFO [stdout] test src/lib.rs -  (line 118) ... ok
Dec 07 08:11:21.026 INFO [stdout] test src/lib.rs -  (line 153) ... ok
Dec 07 08:11:21.388 INFO [stdout] test src/lib.rs -  (line 184) ... ok
Dec 07 08:11:21.612 INFO [stdout] test src/lib.rs -  (line 51) ... ok
Dec 07 08:11:21.909 INFO [stdout] test src/lib.rs - RecordBuilder (line 777) ... ok
Dec 07 08:11:24.060 INFO [stdout] test src/lib.rs - RecordBuilder (line 792) ... ok
Dec 07 08:11:24.193 INFO [stdout] test src/lib.rs - set_logger (line 1113) ... ok
Dec 07 08:11:24.980 INFO [stdout] test src/macros.rs - debug (line 125) ... ok
Dec 07 08:11:25.302 INFO [stdout] test src/macros.rs - log (line 18) ... ok
Dec 07 08:11:25.372 INFO [stdout] test src/macros.rs - info (line 99) ... ok
Dec 07 08:11:25.412 INFO [stdout] test src/macros.rs - log_enabled (line 181) ... ok
Dec 07 08:11:25.541 INFO [stdout] test src/macros.rs - error (line 51) ... ok
Dec 07 08:11:25.591 INFO [stdout] test src/macros.rs - trace (line 150) ... ok
Dec 07 08:11:26.002 INFO [stdout] test src/macros.rs - warn (line 75) ... ok
Dec 07 08:11:26.002 INFO [stdout] 
Dec 07 08:11:26.052 INFO [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 08:11:26.053 INFO [stdout] 
Dec 07 08:11:26.053 INFO [stderr] su: No module specific data is present
Dec 07 08:11:26.353 INFO running `"docker" "inspect" "7e554f2e13793e692286ab74a3742381259fc3c8dda769b4f6a3d1d18fc58911"`
Dec 07 08:11:26.441 INFO running `"docker" "rm" "-f" "7e554f2e13793e692286ab74a3742381259fc3c8dda769b4f6a3d1d18fc58911"`
Dec 07 08:11:26.562 INFO [stdout] 7e554f2e13793e692286ab74a3742381259fc3c8dda769b4f6a3d1d18fc58911