Dec 06 00:54:51.028 INFO testing ecsumed/Raceplayer against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 00:54:51.028 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 06 00:54:51.165 INFO [stdout] 6e976ad050b425b5710ee50b43ea1e1797a3b3ef8c2f0729f6378a8d90538e78
Dec 06 00:54:51.167 INFO running `"docker" "start" "-a" "6e976ad050b425b5710ee50b43ea1e1797a3b3ef8c2f0729f6378a8d90538e78"`
Dec 06 00:54:51.592 INFO [stderr] usermod: no changes
Dec 06 00:54:51.747 INFO [stderr]    Compiling nix v0.9.0
Dec 06 00:54:51.747 INFO [stderr]    Compiling clap v2.31.2
Dec 06 00:54:51.747 INFO [stderr]    Compiling regex v0.2.11
Dec 06 00:54:51.760 INFO [stderr]    Compiling rust-crypto v0.2.36
Dec 06 00:55:06.704 INFO [stderr]    Compiling rexpect v0.3.0
Dec 06 00:55:06.799 INFO [stderr]    Compiling ctrlc v3.1.0
Dec 06 00:55:10.651 INFO [stderr]    Compiling acestream_player v0.1.0 (/source)
Dec 06 00:55:11.871 INFO [stderr] warning: unused import: `SubCommand`
Dec 06 00:55:11.871 INFO [stderr]  --> src/main.rs:6:22
Dec 06 00:55:11.871 INFO [stderr]   |
Dec 06 00:55:11.871 INFO [stderr] 6 | use clap::{Arg, App, SubCommand};
Dec 06 00:55:11.871 INFO [stderr]   |                      ^^^^^^^^^^
Dec 06 00:55:11.871 INFO [stderr]   |
Dec 06 00:55:11.871 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 06 00:55:11.871 INFO [stderr] 
Dec 06 00:55:11.871 INFO [stderr] warning: unused import: `rexpect::errors::*`
Dec 06 00:55:11.871 INFO [stderr]  --> src/main.rs:9:5
Dec 06 00:55:11.871 INFO [stderr]   |
Dec 06 00:55:11.871 INFO [stderr] 9 | use rexpect::errors::*;
Dec 06 00:55:11.871 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^
Dec 06 00:55:11.871 INFO [stderr] 
Dec 06 00:55:11.944 INFO [stderr] warning: unused variable: `player_process`
Dec 06 00:55:11.944 INFO [stderr]   --> src/main.rs:90:7
Dec 06 00:55:11.944 INFO [stderr]    |
Dec 06 00:55:11.944 INFO [stderr] 90 |         let player_process = run_cmd(&format!("{}", player), args).wait()
Dec 06 00:55:11.944 INFO [stderr]    |             ^^^^^^^^^^^^^^ help: consider using `_player_process` instead
Dec 06 00:55:11.944 INFO [stderr]    |
Dec 06 00:55:11.944 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Dec 06 00:55:11.944 INFO [stderr] 
Dec 06 00:55:11.962 INFO [stderr] warning: field is never used: `public_key`
Dec 06 00:55:11.962 INFO [stderr]   --> src/main.rs:30:5
Dec 06 00:55:11.962 INFO [stderr]    |
Dec 06 00:55:11.962 INFO [stderr] 30 |     public_key: str
Dec 06 00:55:11.962 INFO [stderr]    |     ^^^^^^^^^^^^^^^
Dec 06 00:55:11.962 INFO [stderr]    |
Dec 06 00:55:11.962 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 06 00:55:11.962 INFO [stderr] 
Dec 06 00:55:11.962 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:11.962 INFO [stderr]   --> src/main.rs:65:3
Dec 06 00:55:11.962 INFO [stderr]    |
Dec 06 00:55:11.962 INFO [stderr] 65 |         p.send_line("HELLOBG version=3");
Dec 06 00:55:11.962 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:11.962 INFO [stderr]    |
Dec 06 00:55:11.962 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Dec 06 00:55:11.962 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:11.962 INFO [stderr] 
Dec 06 00:55:11.963 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:11.963 INFO [stderr]   --> src/main.rs:79:3
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr] 79 |         p.send_line(&format!("READY key={}", resp_key));
Dec 06 00:55:11.963 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:11.963 INFO [stderr] 
Dec 06 00:55:11.963 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:11.963 INFO [stderr]   --> src/main.rs:80:3
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr] 80 |         p.exp_regex("AUTH.*");
Dec 06 00:55:11.963 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:11.963 INFO [stderr] 
Dec 06 00:55:11.963 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:11.963 INFO [stderr]   --> src/main.rs:81:3
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr] 81 |         p.send_line("USERDATA [{\"gender\": \"1\"}, {\"age\": \"3\"}]");
Dec 06 00:55:11.963 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:11.963 INFO [stderr] 
Dec 06 00:55:11.963 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:11.963 INFO [stderr]   --> src/main.rs:83:3
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr] 83 |         p.send_line(&format!("START PID {} 0", stream_id));
Dec 06 00:55:11.963 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:11.963 INFO [stderr]    |
Dec 06 00:55:11.963 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:11.963 INFO [stderr] 
Dec 06 00:55:13.791 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.18s
Dec 06 00:55:13.800 INFO [stderr] su: No module specific data is present
Dec 06 00:55:14.196 INFO running `"docker" "inspect" "6e976ad050b425b5710ee50b43ea1e1797a3b3ef8c2f0729f6378a8d90538e78"`
Dec 06 00:55:14.265 INFO running `"docker" "rm" "-f" "6e976ad050b425b5710ee50b43ea1e1797a3b3ef8c2f0729f6378a8d90538e78"`
Dec 06 00:55:14.335 INFO [stdout] 6e976ad050b425b5710ee50b43ea1e1797a3b3ef8c2f0729f6378a8d90538e78
Dec 06 00:55:14.338 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 06 00:55:14.508 INFO [stdout] 2fc6c6cb889945c2ff67a609687bbc551b0f5c4cf3039d426b180b3e38915f78
Dec 06 00:55:14.510 INFO running `"docker" "start" "-a" "2fc6c6cb889945c2ff67a609687bbc551b0f5c4cf3039d426b180b3e38915f78"`
Dec 06 00:55:14.881 INFO [stderr] usermod: no changes
Dec 06 00:55:14.945 INFO [stderr]    Compiling acestream_player v0.1.0 (/source)
Dec 06 00:55:15.904 INFO [stderr] warning: unused import: `SubCommand`
Dec 06 00:55:15.904 INFO [stderr]  --> src/main.rs:6:22
Dec 06 00:55:15.904 INFO [stderr]   |
Dec 06 00:55:15.904 INFO [stderr] 6 | use clap::{Arg, App, SubCommand};
Dec 06 00:55:15.904 INFO [stderr]   |                      ^^^^^^^^^^
Dec 06 00:55:15.904 INFO [stderr]   |
Dec 06 00:55:15.904 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 06 00:55:15.904 INFO [stderr] 
Dec 06 00:55:15.904 INFO [stderr] warning: unused import: `rexpect::errors::*`
Dec 06 00:55:15.904 INFO [stderr]  --> src/main.rs:9:5
Dec 06 00:55:15.904 INFO [stderr]   |
Dec 06 00:55:15.904 INFO [stderr] 9 | use rexpect::errors::*;
Dec 06 00:55:15.904 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^
Dec 06 00:55:15.904 INFO [stderr] 
Dec 06 00:55:15.979 INFO [stderr] warning: unused variable: `player_process`
Dec 06 00:55:15.979 INFO [stderr]   --> src/main.rs:90:7
Dec 06 00:55:15.979 INFO [stderr]    |
Dec 06 00:55:15.979 INFO [stderr] 90 |         let player_process = run_cmd(&format!("{}", player), args).wait()
Dec 06 00:55:15.979 INFO [stderr]    |             ^^^^^^^^^^^^^^ help: consider using `_player_process` instead
Dec 06 00:55:15.979 INFO [stderr]    |
Dec 06 00:55:15.980 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Dec 06 00:55:15.980 INFO [stderr] 
Dec 06 00:55:16.000 INFO [stderr] warning: field is never used: `public_key`
Dec 06 00:55:16.000 INFO [stderr]   --> src/main.rs:30:5
Dec 06 00:55:16.000 INFO [stderr]    |
Dec 06 00:55:16.000 INFO [stderr] 30 |     public_key: str
Dec 06 00:55:16.000 INFO [stderr]    |     ^^^^^^^^^^^^^^^
Dec 06 00:55:16.000 INFO [stderr]    |
Dec 06 00:55:16.000 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 06 00:55:16.000 INFO [stderr] 
Dec 06 00:55:16.008 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:16.008 INFO [stderr]   --> src/main.rs:65:3
Dec 06 00:55:16.008 INFO [stderr]    |
Dec 06 00:55:16.008 INFO [stderr] 65 |         p.send_line("HELLOBG version=3");
Dec 06 00:55:16.008 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:16.008 INFO [stderr]    |
Dec 06 00:55:16.008 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Dec 06 00:55:16.008 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:16.008 INFO [stderr] 
Dec 06 00:55:16.008 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:16.008 INFO [stderr]   --> src/main.rs:79:3
Dec 06 00:55:16.008 INFO [stderr]    |
Dec 06 00:55:16.008 INFO [stderr] 79 |         p.send_line(&format!("READY key={}", resp_key));
Dec 06 00:55:16.008 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:16.008 INFO [stderr]    |
Dec 06 00:55:16.008 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:16.009 INFO [stderr] 
Dec 06 00:55:16.009 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:16.009 INFO [stderr]   --> src/main.rs:80:3
Dec 06 00:55:16.009 INFO [stderr]    |
Dec 06 00:55:16.009 INFO [stderr] 80 |         p.exp_regex("AUTH.*");
Dec 06 00:55:16.009 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:16.009 INFO [stderr]    |
Dec 06 00:55:16.009 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:16.009 INFO [stderr] 
Dec 06 00:55:16.009 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:16.009 INFO [stderr]   --> src/main.rs:81:3
Dec 06 00:55:16.009 INFO [stderr]    |
Dec 06 00:55:16.009 INFO [stderr] 81 |         p.send_line("USERDATA [{\"gender\": \"1\"}, {\"age\": \"3\"}]");
Dec 06 00:55:16.009 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:16.009 INFO [stderr]    |
Dec 06 00:55:16.009 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:16.009 INFO [stderr] 
Dec 06 00:55:16.009 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 06 00:55:16.009 INFO [stderr]   --> src/main.rs:83:3
Dec 06 00:55:16.009 INFO [stderr]    |
Dec 06 00:55:16.009 INFO [stderr] 83 |         p.send_line(&format!("START PID {} 0", stream_id));
Dec 06 00:55:16.009 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 00:55:16.009 INFO [stderr]    |
Dec 06 00:55:16.009 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Dec 06 00:55:16.009 INFO [stderr] 
Dec 06 00:55:16.314 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.41s
Dec 06 00:55:16.328 INFO [stderr] su: No module specific data is present
Dec 06 00:55:16.835 INFO running `"docker" "inspect" "2fc6c6cb889945c2ff67a609687bbc551b0f5c4cf3039d426b180b3e38915f78"`
Dec 06 00:55:17.139 INFO running `"docker" "rm" "-f" "2fc6c6cb889945c2ff67a609687bbc551b0f5c4cf3039d426b180b3e38915f78"`
Dec 06 00:55:17.360 INFO [stdout] 2fc6c6cb889945c2ff67a609687bbc551b0f5c4cf3039d426b180b3e38915f78
Dec 06 00:55:17.368 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 06 00:55:17.727 INFO [stdout] 42dca8cb562c7195f0e960bda072cf235e62edd4ce8b202a3c3931888fac178a
Dec 06 00:55:17.732 INFO running `"docker" "start" "-a" "42dca8cb562c7195f0e960bda072cf235e62edd4ce8b202a3c3931888fac178a"`
Dec 06 00:55:18.128 INFO [stderr] usermod: no changes
Dec 06 00:55:18.212 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 06 00:55:18.215 INFO [stderr]      Running /target/debug/deps/acestream_player-7f8aad4924861b11
Dec 06 00:55:18.217 INFO [stdout] 
Dec 06 00:55:18.217 INFO [stdout] running 0 tests
Dec 06 00:55:18.217 INFO [stdout] 
Dec 06 00:55:18.217 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 00:55:18.217 INFO [stdout] 
Dec 06 00:55:18.220 INFO [stderr] su: No module specific data is present
Dec 06 00:55:18.516 INFO running `"docker" "inspect" "42dca8cb562c7195f0e960bda072cf235e62edd4ce8b202a3c3931888fac178a"`
Dec 06 00:55:18.627 INFO running `"docker" "rm" "-f" "42dca8cb562c7195f0e960bda072cf235e62edd4ce8b202a3c3931888fac178a"`
Dec 06 00:55:18.726 INFO [stdout] 42dca8cb562c7195f0e960bda072cf235e62edd4ce8b202a3c3931888fac178a