Nov 17 04:01:34.675 INFO testing ecsumed/Raceplayer against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 04:01:34.675 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 17 04:01:34.861 INFO [stdout] 18502b30d6c4f8797cf99a84137f99ce0cc6c37550e3286ce973a75aab9e3ea0
Nov 17 04:01:34.863 INFO running `"docker" "start" "-a" "18502b30d6c4f8797cf99a84137f99ce0cc6c37550e3286ce973a75aab9e3ea0"`
Nov 17 04:01:35.308 INFO [stderr] usermod: no changes
Nov 17 04:01:35.472 INFO [stderr]    Compiling nix v0.9.0
Nov 17 04:01:35.473 INFO [stderr]    Compiling tempfile v2.2.0
Nov 17 04:01:35.473 INFO [stderr]    Compiling regex v0.2.11
Nov 17 04:01:43.278 INFO [stderr]    Compiling rexpect v0.3.0
Nov 17 04:01:43.278 INFO [stderr]    Compiling ctrlc v3.1.0
Nov 17 04:01:45.835 INFO [stderr]    Compiling acestream_player v0.1.0 (/source)
Nov 17 04:01:47.116 INFO [stderr] warning: unused import: `SubCommand`
Nov 17 04:01:47.116 INFO [stderr]  --> src/main.rs:6:22
Nov 17 04:01:47.117 INFO [stderr]   |
Nov 17 04:01:47.117 INFO [stderr] 6 | use clap::{Arg, App, SubCommand};
Nov 17 04:01:47.117 INFO [stderr]   |                      ^^^^^^^^^^
Nov 17 04:01:47.118 INFO [stderr]   |
Nov 17 04:01:47.118 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 17 04:01:47.118 INFO [stderr] 
Nov 17 04:01:47.119 INFO [stderr] warning: unused import: `rexpect::errors::*`
Nov 17 04:01:47.119 INFO [stderr]  --> src/main.rs:9:5
Nov 17 04:01:47.119 INFO [stderr]   |
Nov 17 04:01:47.120 INFO [stderr] 9 | use rexpect::errors::*;
Nov 17 04:01:47.120 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^
Nov 17 04:01:47.120 INFO [stderr] 
Nov 17 04:01:47.227 INFO [stderr] warning: unused variable: `player_process`
Nov 17 04:01:47.227 INFO [stderr]   --> src/main.rs:90:7
Nov 17 04:01:47.227 INFO [stderr]    |
Nov 17 04:01:47.227 INFO [stderr] 90 |         let player_process = run_cmd(&format!("{}", player), args).wait()
Nov 17 04:01:47.227 INFO [stderr]    |             ^^^^^^^^^^^^^^ help: consider using `_player_process` instead
Nov 17 04:01:47.227 INFO [stderr]    |
Nov 17 04:01:47.227 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Nov 17 04:01:47.227 INFO [stderr] 
Nov 17 04:01:47.251 INFO [stderr] warning: field is never used: `public_key`
Nov 17 04:01:47.251 INFO [stderr]   --> src/main.rs:30:5
Nov 17 04:01:47.251 INFO [stderr]    |
Nov 17 04:01:47.251 INFO [stderr] 30 |     public_key: str
Nov 17 04:01:47.251 INFO [stderr]    |     ^^^^^^^^^^^^^^^
Nov 17 04:01:47.251 INFO [stderr]    |
Nov 17 04:01:47.251 INFO [stderr]    = note: #[warn(dead_code)] on by default
Nov 17 04:01:47.251 INFO [stderr] 
Nov 17 04:01:47.251 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:47.251 INFO [stderr]   --> src/main.rs:65:3
Nov 17 04:01:47.251 INFO [stderr]    |
Nov 17 04:01:47.251 INFO [stderr] 65 |         p.send_line("HELLOBG version=3");
Nov 17 04:01:47.251 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:47.251 INFO [stderr]    |
Nov 17 04:01:47.251 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Nov 17 04:01:47.251 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:47.251 INFO [stderr] 
Nov 17 04:01:47.251 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:47.252 INFO [stderr]   --> src/main.rs:79:3
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr] 79 |         p.send_line(&format!("READY key={}", resp_key));
Nov 17 04:01:47.252 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:47.252 INFO [stderr] 
Nov 17 04:01:47.252 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:47.252 INFO [stderr]   --> src/main.rs:80:3
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr] 80 |         p.exp_regex("AUTH.*");
Nov 17 04:01:47.252 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:47.252 INFO [stderr] 
Nov 17 04:01:47.252 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:47.252 INFO [stderr]   --> src/main.rs:81:3
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr] 81 |         p.send_line("USERDATA [{\"gender\": \"1\"}, {\"age\": \"3\"}]");
Nov 17 04:01:47.252 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:47.252 INFO [stderr] 
Nov 17 04:01:47.252 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:47.252 INFO [stderr]   --> src/main.rs:83:3
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr] 83 |         p.send_line(&format!("START PID {} 0", stream_id));
Nov 17 04:01:47.252 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:47.252 INFO [stderr]    |
Nov 17 04:01:47.252 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:47.252 INFO [stderr] 
Nov 17 04:01:49.790 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.46s
Nov 17 04:01:49.803 INFO [stderr] su: No module specific data is present
Nov 17 04:01:50.504 INFO running `"docker" "inspect" "18502b30d6c4f8797cf99a84137f99ce0cc6c37550e3286ce973a75aab9e3ea0"`
Nov 17 04:01:50.804 INFO running `"docker" "rm" "-f" "18502b30d6c4f8797cf99a84137f99ce0cc6c37550e3286ce973a75aab9e3ea0"`
Nov 17 04:01:51.076 INFO [stdout] 18502b30d6c4f8797cf99a84137f99ce0cc6c37550e3286ce973a75aab9e3ea0
Nov 17 04:01:51.080 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 17 04:01:51.533 INFO [stdout] e612f11c516314c67413fbc35fd11c33858fd1778d5b52aa168e95ffc4ba75fe
Nov 17 04:01:51.539 INFO running `"docker" "start" "-a" "e612f11c516314c67413fbc35fd11c33858fd1778d5b52aa168e95ffc4ba75fe"`
Nov 17 04:01:52.339 INFO [stderr] usermod: no changes
Nov 17 04:01:52.412 INFO [stderr]    Compiling acestream_player v0.1.0 (/source)
Nov 17 04:01:53.487 INFO [stderr] warning: unused import: `SubCommand`
Nov 17 04:01:53.487 INFO [stderr]  --> src/main.rs:6:22
Nov 17 04:01:53.487 INFO [stderr]   |
Nov 17 04:01:53.487 INFO [stderr] 6 | use clap::{Arg, App, SubCommand};
Nov 17 04:01:53.487 INFO [stderr]   |                      ^^^^^^^^^^
Nov 17 04:01:53.487 INFO [stderr]   |
Nov 17 04:01:53.487 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Nov 17 04:01:53.487 INFO [stderr] 
Nov 17 04:01:53.487 INFO [stderr] warning: unused import: `rexpect::errors::*`
Nov 17 04:01:53.487 INFO [stderr]  --> src/main.rs:9:5
Nov 17 04:01:53.487 INFO [stderr]   |
Nov 17 04:01:53.487 INFO [stderr] 9 | use rexpect::errors::*;
Nov 17 04:01:53.487 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^
Nov 17 04:01:53.487 INFO [stderr] 
Nov 17 04:01:53.555 INFO [stderr] warning: unused variable: `player_process`
Nov 17 04:01:53.555 INFO [stderr]   --> src/main.rs:90:7
Nov 17 04:01:53.555 INFO [stderr]    |
Nov 17 04:01:53.555 INFO [stderr] 90 |         let player_process = run_cmd(&format!("{}", player), args).wait()
Nov 17 04:01:53.555 INFO [stderr]    |             ^^^^^^^^^^^^^^ help: consider using `_player_process` instead
Nov 17 04:01:53.555 INFO [stderr]    |
Nov 17 04:01:53.555 INFO [stderr]    = note: #[warn(unused_variables)] on by default
Nov 17 04:01:53.555 INFO [stderr] 
Nov 17 04:01:53.577 INFO [stderr] warning: field is never used: `public_key`
Nov 17 04:01:53.577 INFO [stderr]   --> src/main.rs:30:5
Nov 17 04:01:53.577 INFO [stderr]    |
Nov 17 04:01:53.578 INFO [stderr] 30 |     public_key: str
Nov 17 04:01:53.578 INFO [stderr]    |     ^^^^^^^^^^^^^^^
Nov 17 04:01:53.578 INFO [stderr]    |
Nov 17 04:01:53.578 INFO [stderr]    = note: #[warn(dead_code)] on by default
Nov 17 04:01:53.578 INFO [stderr] 
Nov 17 04:01:53.578 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:53.578 INFO [stderr]   --> src/main.rs:65:3
Nov 17 04:01:53.578 INFO [stderr]    |
Nov 17 04:01:53.578 INFO [stderr] 65 |         p.send_line("HELLOBG version=3");
Nov 17 04:01:53.578 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:53.578 INFO [stderr]    |
Nov 17 04:01:53.578 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Nov 17 04:01:53.578 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:53.578 INFO [stderr] 
Nov 17 04:01:53.578 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:53.578 INFO [stderr]   --> src/main.rs:79:3
Nov 17 04:01:53.578 INFO [stderr]    |
Nov 17 04:01:53.578 INFO [stderr] 79 |         p.send_line(&format!("READY key={}", resp_key));
Nov 17 04:01:53.578 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:53.578 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:53.579 INFO [stderr] 
Nov 17 04:01:53.579 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:53.579 INFO [stderr]   --> src/main.rs:80:3
Nov 17 04:01:53.579 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr] 80 |         p.exp_regex("AUTH.*");
Nov 17 04:01:53.579 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:53.579 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:53.579 INFO [stderr] 
Nov 17 04:01:53.579 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:53.579 INFO [stderr]   --> src/main.rs:81:3
Nov 17 04:01:53.579 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr] 81 |         p.send_line("USERDATA [{\"gender\": \"1\"}, {\"age\": \"3\"}]");
Nov 17 04:01:53.579 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:53.579 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:53.579 INFO [stderr] 
Nov 17 04:01:53.579 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 17 04:01:53.579 INFO [stderr]   --> src/main.rs:83:3
Nov 17 04:01:53.579 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr] 83 |         p.send_line(&format!("START PID {} 0", stream_id));
Nov 17 04:01:53.579 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 17 04:01:53.579 INFO [stderr]    |
Nov 17 04:01:53.579 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 17 04:01:53.579 INFO [stderr] 
Nov 17 04:01:53.923 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.57s
Nov 17 04:01:53.932 INFO [stderr] su: No module specific data is present
Nov 17 04:01:54.604 INFO running `"docker" "inspect" "e612f11c516314c67413fbc35fd11c33858fd1778d5b52aa168e95ffc4ba75fe"`
Nov 17 04:01:54.851 INFO running `"docker" "rm" "-f" "e612f11c516314c67413fbc35fd11c33858fd1778d5b52aa168e95ffc4ba75fe"`
Nov 17 04:01:55.023 INFO [stdout] e612f11c516314c67413fbc35fd11c33858fd1778d5b52aa168e95ffc4ba75fe
Nov 17 04:01:55.031 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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 17 04:01:55.484 INFO [stdout] 9f3d16e78b5c6f43b3d2c9afd64892bd729caca08dc8f97a732adebac9e069cf
Nov 17 04:01:55.486 INFO running `"docker" "start" "-a" "9f3d16e78b5c6f43b3d2c9afd64892bd729caca08dc8f97a732adebac9e069cf"`
Nov 17 04:01:55.991 INFO [stderr] usermod: no changes
Nov 17 04:01:56.077 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Nov 17 04:01:56.080 INFO [stderr]      Running /target/debug/deps/acestream_player-b00eab234a3ae741
Nov 17 04:01:56.082 INFO [stdout] 
Nov 17 04:01:56.082 INFO [stdout] running 0 tests
Nov 17 04:01:56.082 INFO [stdout] 
Nov 17 04:01:56.082 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 04:01:56.082 INFO [stdout] 
Nov 17 04:01:56.084 INFO [stderr] su: No module specific data is present
Nov 17 04:01:56.408 INFO running `"docker" "inspect" "9f3d16e78b5c6f43b3d2c9afd64892bd729caca08dc8f97a732adebac9e069cf"`
Nov 17 04:01:56.489 INFO running `"docker" "rm" "-f" "9f3d16e78b5c6f43b3d2c9afd64892bd729caca08dc8f97a732adebac9e069cf"`
Nov 17 04:01:56.583 INFO [stdout] 9f3d16e78b5c6f43b3d2c9afd64892bd729caca08dc8f97a732adebac9e069cf