Dec 15 20:24:45.409 INFO testing cache-macro-0.4.1 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 20:24:45.409 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 20:24:45.923 INFO [stdout] b6aaf37d6f6b259ef50a7832f9a66f4a0dc30e8f1fec981e8defdc358349b73a
Dec 15 20:24:45.931 INFO running `"docker" "start" "-a" "b6aaf37d6f6b259ef50a7832f9a66f4a0dc30e8f1fec981e8defdc358349b73a"`
Dec 15 20:24:47.110 INFO [stderr] usermod: no changes
Dec 15 20:24:47.224 INFO [stderr]    Compiling cache-macro v0.4.1 (/source)
Dec 15 20:24:48.141 INFO [stderr] warning: unused import: `proc_macro2`
Dec 15 20:24:48.141 INFO [stderr]  --> src/config.rs:4:5
Dec 15 20:24:48.141 INFO [stderr]   |
Dec 15 20:24:48.141 INFO [stderr] 4 | use proc_macro2;
Dec 15 20:24:48.141 INFO [stderr]   |     ^^^^^^^^^^^
Dec 15 20:24:48.141 INFO [stderr]   |
Dec 15 20:24:48.141 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 15 20:24:48.141 INFO [stderr] 
Dec 15 20:24:48.343 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 15 20:24:48.343 INFO [stderr]    --> src/lib.rs:170:12
Dec 15 20:24:48.343 INFO [stderr]     |
Dec 15 20:24:48.343 INFO [stderr] 170 | #![feature(extern_crate_item_prelude)]
Dec 15 20:24:48.343 INFO [stderr]     |            ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 15 20:24:48.343 INFO [stderr]     |
Dec 15 20:24:48.343 INFO [stderr]     = note: #[warn(stable_features)] on by default
Dec 15 20:24:48.343 INFO [stderr] 
Dec 15 20:24:51.895 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.76s
Dec 15 20:24:51.903 INFO [stderr] su: No module specific data is present
Dec 15 20:24:52.428 INFO running `"docker" "inspect" "b6aaf37d6f6b259ef50a7832f9a66f4a0dc30e8f1fec981e8defdc358349b73a"`
Dec 15 20:24:52.706 INFO running `"docker" "rm" "-f" "b6aaf37d6f6b259ef50a7832f9a66f4a0dc30e8f1fec981e8defdc358349b73a"`
Dec 15 20:24:52.914 INFO [stdout] b6aaf37d6f6b259ef50a7832f9a66f4a0dc30e8f1fec981e8defdc358349b73a
Dec 15 20:24:52.917 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 20:24:53.219 INFO [stdout] 17177937d7162dcd68a79fd8de616def44114d195f569d009eb8822991c355f7
Dec 15 20:24:53.221 INFO running `"docker" "start" "-a" "17177937d7162dcd68a79fd8de616def44114d195f569d009eb8822991c355f7"`
Dec 15 20:24:54.135 INFO [stderr] usermod: no changes
Dec 15 20:24:54.279 INFO [stderr]    Compiling expiring_map v0.1.0
Dec 15 20:24:54.970 INFO [stderr]    Compiling cache-macro v0.4.1 (/source)
Dec 15 20:24:57.873 INFO [stderr] warning: unused import: `proc_macro2`
Dec 15 20:24:57.873 INFO [stderr]  --> src/config.rs:4:5
Dec 15 20:24:57.873 INFO [stderr]   |
Dec 15 20:24:57.873 INFO [stderr] 4 | use proc_macro2;
Dec 15 20:24:57.873 INFO [stderr]   |     ^^^^^^^^^^^
Dec 15 20:24:57.873 INFO [stderr]   |
Dec 15 20:24:57.873 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 15 20:24:57.873 INFO [stderr] 
Dec 15 20:24:58.991 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 15 20:24:58.991 INFO [stderr]    --> src/lib.rs:170:12
Dec 15 20:24:58.991 INFO [stderr]     |
Dec 15 20:24:58.991 INFO [stderr] 170 | #![feature(extern_crate_item_prelude)]
Dec 15 20:24:58.991 INFO [stderr]     |            ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 15 20:24:58.991 INFO [stderr]     |
Dec 15 20:24:58.991 INFO [stderr]     = note: #[warn(stable_features)] on by default
Dec 15 20:24:58.991 INFO [stderr] 
Dec 15 20:25:02.039 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.86s
Dec 15 20:25:02.051 INFO [stderr] su: No module specific data is present
Dec 15 20:25:02.711 INFO running `"docker" "inspect" "17177937d7162dcd68a79fd8de616def44114d195f569d009eb8822991c355f7"`
Dec 15 20:25:02.946 INFO running `"docker" "rm" "-f" "17177937d7162dcd68a79fd8de616def44114d195f569d009eb8822991c355f7"`
Dec 15 20:25:03.069 INFO [stdout] 17177937d7162dcd68a79fd8de616def44114d195f569d009eb8822991c355f7
Dec 15 20:25:03.070 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-2/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 15 20:25:03.302 INFO [stdout] c8a08302fdc4426f7277c41ce88c83aa99fc59e19dbc547716a54f01fecf430c
Dec 15 20:25:03.304 INFO running `"docker" "start" "-a" "c8a08302fdc4426f7277c41ce88c83aa99fc59e19dbc547716a54f01fecf430c"`
Dec 15 20:25:03.709 INFO [stderr] usermod: no changes
Dec 15 20:25:03.761 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 15 20:25:03.777 INFO [stderr]      Running /target/debug/deps/cache_macro-f90ccaabb4814d9d
Dec 15 20:25:03.787 INFO [stderr]      Running /target/debug/deps/args_as_ref-85b36f2e2a9a04e9
Dec 15 20:25:03.787 INFO [stdout] 
Dec 15 20:25:03.787 INFO [stdout] running 0 tests
Dec 15 20:25:03.787 INFO [stdout] 
Dec 15 20:25:03.787 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.787 INFO [stdout] 
Dec 15 20:25:03.795 INFO [stdout] 
Dec 15 20:25:03.795 INFO [stdout] running 1 test
Dec 15 20:25:03.799 INFO [stdout] test args_as_ref ... ok
Dec 15 20:25:03.799 INFO [stdout] 
Dec 15 20:25:03.799 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.799 INFO [stdout] 
Dec 15 20:25:03.801 INFO [stdout] 
Dec 15 20:25:03.801 INFO [stderr]      Running /target/debug/deps/cache_type-adb613fecca60532
Dec 15 20:25:03.801 INFO [stdout] running 2 tests
Dec 15 20:25:03.801 INFO [stderr]      Running /target/debug/deps/clone_only_type-51163b149c36f827
Dec 15 20:25:03.801 INFO [stdout] test non_standard_cache_creation ... ok
Dec 15 20:25:03.802 INFO [stdout] test cache_type ... ok
Dec 15 20:25:03.802 INFO [stdout] 
Dec 15 20:25:03.802 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.802 INFO [stdout] 
Dec 15 20:25:03.804 INFO [stdout] 
Dec 15 20:25:03.804 INFO [stdout] running 1 test
Dec 15 20:25:03.816 INFO [stdout] test clone_only_type ... ok
Dec 15 20:25:03.816 INFO [stdout] 
Dec 15 20:25:03.816 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.816 INFO [stdout] 
Dec 15 20:25:03.823 INFO [stderr]      Running /target/debug/deps/config-6c93a192ef402697
Dec 15 20:25:03.823 INFO [stdout] 
Dec 15 20:25:03.823 INFO [stdout] running 2 tests
Dec 15 20:25:03.824 INFO [stdout] test thread_local_ignore_args ... ok
Dec 15 20:25:03.839 INFO [stdout] test multithreaded ... ok
Dec 15 20:25:03.839 INFO [stdout] 
Dec 15 20:25:03.839 INFO [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.839 INFO [stdout] 
Dec 15 20:25:03.840 INFO [stderr]      Running /target/debug/deps/multiple_args-33a850eb1c6f2fe2
Dec 15 20:25:03.852 INFO [stdout] 
Dec 15 20:25:03.852 INFO [stdout] running 1 test
Dec 15 20:25:03.867 INFO [stdout] test multiple_args ... ok
Dec 15 20:25:03.867 INFO [stdout] 
Dec 15 20:25:03.867 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.867 INFO [stdout] 
Dec 15 20:25:03.868 INFO [stderr]      Running /target/debug/deps/multiple_caches-d4d9fe15e022d757
Dec 15 20:25:03.881 INFO [stderr]    Doc-tests cache-macro
Dec 15 20:25:03.881 INFO [stdout] 
Dec 15 20:25:03.881 INFO [stdout] running 1 test
Dec 15 20:25:03.881 INFO [stdout] test multiple_caches ... ok
Dec 15 20:25:03.881 INFO [stdout] 
Dec 15 20:25:03.881 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:03.881 INFO [stdout] 
Dec 15 20:25:05.316 INFO [stdout] 
Dec 15 20:25:05.316 INFO [stdout] running 5 tests
Dec 15 20:25:08.087 INFO [stdout] test src/lib.rs -  (line 141) ... ok
Dec 15 20:25:08.239 INFO [stdout] test src/lib.rs -  (line 58) ... ok
Dec 15 20:25:08.364 INFO [stdout] test src/lib.rs -  (line 110) ... ok
Dec 15 20:25:08.563 INFO [stdout] test src/lib.rs -  (line 86) ... ok
Dec 15 20:25:08.863 INFO [stdout] test src/lib.rs -  (line 8) ... ok
Dec 15 20:25:08.863 INFO [stdout] 
Dec 15 20:25:08.863 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 20:25:08.863 INFO [stdout] 
Dec 15 20:25:08.901 INFO [stderr] su: No module specific data is present
Dec 15 20:25:09.819 INFO running `"docker" "inspect" "c8a08302fdc4426f7277c41ce88c83aa99fc59e19dbc547716a54f01fecf430c"`
Dec 15 20:25:10.033 INFO running `"docker" "rm" "-f" "c8a08302fdc4426f7277c41ce88c83aa99fc59e19dbc547716a54f01fecf430c"`
Dec 15 20:25:10.347 INFO [stdout] c8a08302fdc4426f7277c41ce88c83aa99fc59e19dbc547716a54f01fecf430c