Nov 16 22:49:01.728 INFO testing kbacha/string-clone-performance-rust against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 22:49:01.730 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 22:49:02.208 INFO [stdout] 2fb2a3f8295755daff5a63316d9f6237b12a34e060b81d58c7bdc29a66369dc9
Nov 16 22:49:02.210 INFO running `"docker" "start" "-a" "2fb2a3f8295755daff5a63316d9f6237b12a34e060b81d58c7bdc29a66369dc9"`
Nov 16 22:49:04.111 INFO [stderr] usermod: no changes
Nov 16 22:49:04.183 INFO [stderr]    Compiling string-clone-performance v0.1.0 (/source)
Nov 16 22:49:04.554 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:04.554 INFO [stderr]   --> src/main.rs:13:38
Nov 16 22:49:04.554 INFO [stderr]    |
Nov 16 22:49:04.554 INFO [stderr] 13 |     time("100 rs", 100_000_000, || { s.clone(); });
Nov 16 22:49:04.554 INFO [stderr]    |                                      ^^^^^^^^^^
Nov 16 22:49:04.554 INFO [stderr]    |
Nov 16 22:49:04.554 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Nov 16 22:49:04.554 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:04.554 INFO [stderr] 
Nov 16 22:49:04.554 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:04.554 INFO [stderr]   --> src/main.rs:15:39
Nov 16 22:49:04.554 INFO [stderr]    |
Nov 16 22:49:04.554 INFO [stderr] 15 |     time("1000 rs", 100_000_000, || { s.clone(); });
Nov 16 22:49:04.554 INFO [stderr]    |                                       ^^^^^^^^^^
Nov 16 22:49:04.555 INFO [stderr]    |
Nov 16 22:49:04.555 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:04.555 INFO [stderr] 
Nov 16 22:49:04.555 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:04.555 INFO [stderr]   --> src/main.rs:17:41
Nov 16 22:49:04.556 INFO [stderr]    |
Nov 16 22:49:04.556 INFO [stderr] 17 |     time("10,000 rs", 100_000_000, || { s.clone(); });
Nov 16 22:49:04.556 INFO [stderr]    |                                         ^^^^^^^^^^
Nov 16 22:49:04.556 INFO [stderr]    |
Nov 16 22:49:04.556 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:04.556 INFO [stderr] 
Nov 16 22:49:04.556 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:04.556 INFO [stderr]   --> src/main.rs:19:41
Nov 16 22:49:04.556 INFO [stderr]    |
Nov 16 22:49:04.557 INFO [stderr] 19 |     time("100,000 rs", 10_000_000, || { s.clone(); });
Nov 16 22:49:04.557 INFO [stderr]    |                                         ^^^^^^^^^^
Nov 16 22:49:04.563 INFO [stderr]    |
Nov 16 22:49:04.563 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:04.563 INFO [stderr] 
Nov 16 22:49:04.859 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.71s
Nov 16 22:49:04.862 INFO [stderr] su: No module specific data is present
Nov 16 22:49:05.965 INFO running `"docker" "inspect" "2fb2a3f8295755daff5a63316d9f6237b12a34e060b81d58c7bdc29a66369dc9"`
Nov 16 22:49:06.302 INFO running `"docker" "rm" "-f" "2fb2a3f8295755daff5a63316d9f6237b12a34e060b81d58c7bdc29a66369dc9"`
Nov 16 22:49:06.571 INFO [stdout] 2fb2a3f8295755daff5a63316d9f6237b12a34e060b81d58c7bdc29a66369dc9
Nov 16 22:49:06.581 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 22:49:07.083 INFO [stdout] 11dc7b1a231291413b0879489dcd0b905a540f1009ea6376faa851d35a73bda5
Nov 16 22:49:07.089 INFO running `"docker" "start" "-a" "11dc7b1a231291413b0879489dcd0b905a540f1009ea6376faa851d35a73bda5"`
Nov 16 22:49:08.237 INFO [stderr] usermod: no changes
Nov 16 22:49:08.308 INFO [stderr]    Compiling string-clone-performance v0.1.0 (/source)
Nov 16 22:49:08.654 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:08.654 INFO [stderr]   --> src/main.rs:13:38
Nov 16 22:49:08.654 INFO [stderr]    |
Nov 16 22:49:08.654 INFO [stderr] 13 |     time("100 rs", 100_000_000, || { s.clone(); });
Nov 16 22:49:08.654 INFO [stderr]    |                                      ^^^^^^^^^^
Nov 16 22:49:08.654 INFO [stderr]    |
Nov 16 22:49:08.654 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Nov 16 22:49:08.654 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:08.656 INFO [stderr] 
Nov 16 22:49:08.656 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:08.656 INFO [stderr]   --> src/main.rs:15:39
Nov 16 22:49:08.656 INFO [stderr]    |
Nov 16 22:49:08.656 INFO [stderr] 15 |     time("1000 rs", 100_000_000, || { s.clone(); });
Nov 16 22:49:08.658 INFO [stderr]    |                                       ^^^^^^^^^^
Nov 16 22:49:08.658 INFO [stderr]    |
Nov 16 22:49:08.658 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:08.659 INFO [stderr] 
Nov 16 22:49:08.659 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:08.659 INFO [stderr]   --> src/main.rs:17:41
Nov 16 22:49:08.659 INFO [stderr]    |
Nov 16 22:49:08.660 INFO [stderr] 17 |     time("10,000 rs", 100_000_000, || { s.clone(); });
Nov 16 22:49:08.660 INFO [stderr]    |                                         ^^^^^^^^^^
Nov 16 22:49:08.660 INFO [stderr]    |
Nov 16 22:49:08.660 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:08.660 INFO [stderr] 
Nov 16 22:49:08.661 INFO [stderr] warning: unused return value of `std::clone::Clone::clone` that must be used
Nov 16 22:49:08.662 INFO [stderr]   --> src/main.rs:19:41
Nov 16 22:49:08.662 INFO [stderr]    |
Nov 16 22:49:08.662 INFO [stderr] 19 |     time("100,000 rs", 10_000_000, || { s.clone(); });
Nov 16 22:49:08.663 INFO [stderr]    |                                         ^^^^^^^^^^
Nov 16 22:49:08.663 INFO [stderr]    |
Nov 16 22:49:08.663 INFO [stderr]    = note: cloning is often expensive and is not expected to have side effects
Nov 16 22:49:08.663 INFO [stderr] 
Nov 16 22:49:08.962 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.68s
Nov 16 22:49:08.969 INFO [stderr] su: No module specific data is present
Nov 16 22:49:09.801 INFO running `"docker" "inspect" "11dc7b1a231291413b0879489dcd0b905a540f1009ea6376faa851d35a73bda5"`
Nov 16 22:49:09.946 INFO running `"docker" "rm" "-f" "11dc7b1a231291413b0879489dcd0b905a540f1009ea6376faa851d35a73bda5"`
Nov 16 22:49:10.090 INFO [stdout] 11dc7b1a231291413b0879489dcd0b905a540f1009ea6376faa851d35a73bda5
Nov 16 22:49:10.093 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 22:49:10.397 INFO [stdout] 2f04e26a1c5eab02e1cba29940dfe6202106978a2e71d02338756a08756aac73
Nov 16 22:49:10.397 INFO running `"docker" "start" "-a" "2f04e26a1c5eab02e1cba29940dfe6202106978a2e71d02338756a08756aac73"`
Nov 16 22:49:11.109 INFO [stderr] usermod: no changes
Nov 16 22:49:11.140 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Nov 16 22:49:11.145 INFO [stderr]      Running /target/debug/deps/string_clone_performance-853dfdc4cd48a5b2
Nov 16 22:49:11.150 INFO [stdout] 
Nov 16 22:49:11.150 INFO [stdout] running 0 tests
Nov 16 22:49:11.150 INFO [stdout] 
Nov 16 22:49:11.150 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 22:49:11.150 INFO [stdout] 
Nov 16 22:49:11.155 INFO [stderr] su: No module specific data is present
Nov 16 22:49:12.029 INFO running `"docker" "inspect" "2f04e26a1c5eab02e1cba29940dfe6202106978a2e71d02338756a08756aac73"`
Nov 16 22:49:12.303 INFO running `"docker" "rm" "-f" "2f04e26a1c5eab02e1cba29940dfe6202106978a2e71d02338756a08756aac73"`
Nov 16 22:49:12.624 INFO [stdout] 2f04e26a1c5eab02e1cba29940dfe6202106978a2e71d02338756a08756aac73