Dec 07 21:56:47.525 INFO testing cache-macro-0.4.1 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 21:56:47.525 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 21:56:47.966 INFO [stdout] adaf57fce5b31f4b6a91062788d29f49d04cd1b837b8763ff82d3019bf142890
Dec 07 21:56:47.972 INFO running `"docker" "start" "-a" "adaf57fce5b31f4b6a91062788d29f49d04cd1b837b8763ff82d3019bf142890"`
Dec 07 21:56:48.935 INFO [stderr] usermod: no changes
Dec 07 21:56:49.058 INFO [stderr]    Compiling cache-macro v0.4.1 (/source)
Dec 07 21:56:50.176 INFO [stderr] warning: the feature `extern_crate_item_prelude` has been stable since 1.31.0 and no longer requires an attribute to enable
Dec 07 21:56:50.176 INFO [stderr]    --> src/lib.rs:170:12
Dec 07 21:56:50.176 INFO [stderr]     |
Dec 07 21:56:50.176 INFO [stderr] 170 | #![feature(extern_crate_item_prelude)]
Dec 07 21:56:50.176 INFO [stderr]     |            ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 07 21:56:50.176 INFO [stderr]     |
Dec 07 21:56:50.176 INFO [stderr]     = note: #[warn(stable_features)] on by default
Dec 07 21:56:50.176 INFO [stderr] 
Dec 07 21:56:53.749 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.77s
Dec 07 21:56:53.764 INFO [stderr] su: No module specific data is present
Dec 07 21:56:54.574 INFO running `"docker" "inspect" "adaf57fce5b31f4b6a91062788d29f49d04cd1b837b8763ff82d3019bf142890"`
Dec 07 21:56:54.806 INFO running `"docker" "rm" "-f" "adaf57fce5b31f4b6a91062788d29f49d04cd1b837b8763ff82d3019bf142890"`
Dec 07 21:56:55.017 INFO [stdout] adaf57fce5b31f4b6a91062788d29f49d04cd1b837b8763ff82d3019bf142890
Dec 07 21:56:55.021 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 21:56:55.274 INFO [stdout] af383648301892e6451d3aa0d8273f62974cc55e6fb8eec668e6b3b6b2a76ea5
Dec 07 21:56:55.280 INFO running `"docker" "start" "-a" "af383648301892e6451d3aa0d8273f62974cc55e6fb8eec668e6b3b6b2a76ea5"`
Dec 07 21:56:55.859 INFO [stderr] usermod: no changes
Dec 07 21:56:55.910 INFO [stderr]    Compiling expiring_map v0.1.0
Dec 07 21:56:56.488 INFO [stderr]    Compiling cache-macro v0.4.1 (/source)
Dec 07 21:57:01.288 INFO [stderr] warning: the feature `extern_crate_item_prelude` has been stable since 1.31.0 and no longer requires an attribute to enable
Dec 07 21:57:01.288 INFO [stderr]    --> src/lib.rs:170:12
Dec 07 21:57:01.288 INFO [stderr]     |
Dec 07 21:57:01.288 INFO [stderr] 170 | #![feature(extern_crate_item_prelude)]
Dec 07 21:57:01.288 INFO [stderr]     |            ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 07 21:57:01.304 INFO [stderr]     |
Dec 07 21:57:01.304 INFO [stderr]     = note: #[warn(stable_features)] on by default
Dec 07 21:57:01.304 INFO [stderr] 
Dec 07 21:57:02.330 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.45s
Dec 07 21:57:02.341 INFO [stderr] su: No module specific data is present
Dec 07 21:57:02.776 INFO running `"docker" "inspect" "af383648301892e6451d3aa0d8273f62974cc55e6fb8eec668e6b3b6b2a76ea5"`
Dec 07 21:57:02.937 INFO running `"docker" "rm" "-f" "af383648301892e6451d3aa0d8273f62974cc55e6fb8eec668e6b3b6b2a76ea5"`
Dec 07 21:57:03.029 INFO [stdout] af383648301892e6451d3aa0d8273f62974cc55e6fb8eec668e6b3b6b2a76ea5
Dec 07 21:57:03.031 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 21:57:03.254 INFO [stdout] 1064b3571a23aaedd19389bec93ccf13f3a8b9c1c9d4b0f802670e91c035cd00
Dec 07 21:57:03.254 INFO running `"docker" "start" "-a" "1064b3571a23aaedd19389bec93ccf13f3a8b9c1c9d4b0f802670e91c035cd00"`
Dec 07 21:57:03.730 INFO [stderr] usermod: no changes
Dec 07 21:57:03.770 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 07 21:57:03.798 INFO [stderr]      Running /target/debug/deps/cache_macro-c0a7bdc4a4c3312d
Dec 07 21:57:03.808 INFO [stderr]      Running /target/debug/deps/args_as_ref-db3eecd6101be203
Dec 07 21:57:03.808 INFO [stdout] 
Dec 07 21:57:03.808 INFO [stdout] running 0 tests
Dec 07 21:57:03.808 INFO [stdout] 
Dec 07 21:57:03.808 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.808 INFO [stdout] 
Dec 07 21:57:03.810 INFO [stdout] 
Dec 07 21:57:03.810 INFO [stdout] running 1 test
Dec 07 21:57:03.814 INFO [stdout] test args_as_ref ... ok
Dec 07 21:57:03.814 INFO [stdout] 
Dec 07 21:57:03.814 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.814 INFO [stdout] 
Dec 07 21:57:03.816 INFO [stdout] 
Dec 07 21:57:03.816 INFO [stderr]      Running /target/debug/deps/cache_type-87474f6610518113
Dec 07 21:57:03.816 INFO [stdout] running 2 tests
Dec 07 21:57:03.818 INFO [stdout] test non_standard_cache_creation ... ok
Dec 07 21:57:03.818 INFO [stdout] test cache_type ... ok
Dec 07 21:57:03.818 INFO [stdout] 
Dec 07 21:57:03.818 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.818 INFO [stdout] 
Dec 07 21:57:03.820 INFO [stderr]      Running /target/debug/deps/clone_only_type-d80fda631dac00d0
Dec 07 21:57:03.820 INFO [stdout] 
Dec 07 21:57:03.820 INFO [stdout] running 1 test
Dec 07 21:57:03.821 INFO [stdout] test clone_only_type ... ok
Dec 07 21:57:03.821 INFO [stdout] 
Dec 07 21:57:03.821 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.821 INFO [stdout] 
Dec 07 21:57:03.823 INFO [stderr]      Running /target/debug/deps/config-433e511fa351e251
Dec 07 21:57:03.826 INFO [stdout] 
Dec 07 21:57:03.826 INFO [stdout] running 2 tests
Dec 07 21:57:03.826 INFO [stdout] test thread_local_ignore_args ... ok
Dec 07 21:57:03.845 INFO [stdout] test multithreaded ... ok
Dec 07 21:57:03.845 INFO [stdout] 
Dec 07 21:57:03.845 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.845 INFO [stdout] 
Dec 07 21:57:03.848 INFO [stderr]      Running /target/debug/deps/multiple_args-d6e0975500a27563
Dec 07 21:57:03.861 INFO [stdout] 
Dec 07 21:57:03.861 INFO [stdout] running 1 test
Dec 07 21:57:03.868 INFO [stdout] test multiple_args ... ok
Dec 07 21:57:03.868 INFO [stderr]      Running /target/debug/deps/multiple_caches-37fd1375b901ecd3
Dec 07 21:57:03.868 INFO [stdout] 
Dec 07 21:57:03.868 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.868 INFO [stdout] 
Dec 07 21:57:03.877 INFO [stdout] 
Dec 07 21:57:03.877 INFO [stderr]    Doc-tests cache-macro
Dec 07 21:57:03.877 INFO [stdout] running 1 test
Dec 07 21:57:03.877 INFO [stdout] test multiple_caches ... ok
Dec 07 21:57:03.877 INFO [stdout] 
Dec 07 21:57:03.877 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:03.877 INFO [stdout] 
Dec 07 21:57:05.252 INFO [stdout] 
Dec 07 21:57:05.252 INFO [stdout] running 5 tests
Dec 07 21:57:07.596 INFO [stdout] test src/lib.rs -  (line 8) ... ok
Dec 07 21:57:07.917 INFO [stdout] test src/lib.rs -  (line 141) ... ok
Dec 07 21:57:08.788 INFO [stdout] test src/lib.rs -  (line 86) ... ok
Dec 07 21:57:08.976 INFO [stdout] test src/lib.rs -  (line 110) ... ok
Dec 07 21:57:09.024 INFO [stdout] test src/lib.rs -  (line 58) ... ok
Dec 07 21:57:09.024 INFO [stdout] 
Dec 07 21:57:09.024 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 21:57:09.024 INFO [stdout] 
Dec 07 21:57:09.040 INFO [stderr] su: No module specific data is present
Dec 07 21:57:09.582 INFO running `"docker" "inspect" "1064b3571a23aaedd19389bec93ccf13f3a8b9c1c9d4b0f802670e91c035cd00"`
Dec 07 21:57:09.842 INFO running `"docker" "rm" "-f" "1064b3571a23aaedd19389bec93ccf13f3a8b9c1c9d4b0f802670e91c035cd00"`
Dec 07 21:57:10.020 INFO [stdout] 1064b3571a23aaedd19389bec93ccf13f3a8b9c1c9d4b0f802670e91c035cd00