[INFO] fetching crate exclave 0.2.6... [INFO] testing exclave-0.2.6 against master#d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d for pr-83796 [INFO] extracting crate exclave 0.2.6 into /workspace/builds/worker-2/source [INFO] validating manifest of crates.io crate exclave 0.2.6 on toolchain d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] removed /workspace/builds/worker-2/source/.cargo/config [INFO] started tweaking crates.io crate exclave 0.2.6 [INFO] finished tweaking crates.io crate exclave 0.2.6 [INFO] tweaked toml for crates.io crate exclave 0.2.6 written to /workspace/builds/worker-2/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] cad8304e647751fba275f98825c62b7afc721721005f510e242df27f00463d72 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "cad8304e647751fba275f98825c62b7afc721721005f510e242df27f00463d72", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "cad8304e647751fba275f98825c62b7afc721721005f510e242df27f00463d72", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cad8304e647751fba275f98825c62b7afc721721005f510e242df27f00463d72", kill_on_drop: false }` [INFO] [stdout] cad8304e647751fba275f98825c62b7afc721721005f510e242df27f00463d72 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] de66e784c1623af935fbefbd7f7e1c91bb6b0d0758731b18bca83e37ae99adc1 [INFO] running `Command { std: "docker" "start" "-a" "de66e784c1623af935fbefbd7f7e1c91bb6b0d0758731b18bca83e37ae99adc1", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling memchr v2.4.0 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling proc-macro2 v1.0.26 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling slab v0.4.3 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling ordermap v0.3.5 [INFO] [stderr] Compiling fixedbitset v0.1.9 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling regex v0.2.11 [INFO] [stderr] Compiling ucd-util v0.1.8 [INFO] [stderr] Compiling either v1.6.1 [INFO] [stderr] Compiling lazycell v1.3.0 [INFO] [stderr] Compiling nom v2.2.1 [INFO] [stderr] Compiling utf8-ranges v1.0.4 [INFO] [stderr] Compiling exclave v0.2.6 (/opt/rustwide/workdir) [INFO] [stderr] Compiling user32-sys v0.2.0 [INFO] [stderr] Compiling instant v0.1.9 [INFO] [stderr] Compiling itertools v0.5.10 [INFO] [stderr] Compiling thread_local v0.3.6 [INFO] [stderr] Compiling walkdir v2.3.2 [INFO] [stderr] Compiling regex-syntax v0.5.6 [INFO] [stderr] Compiling net2 v0.2.37 [INFO] [stderr] Compiling parking_lot_core v0.8.3 [INFO] [stderr] Compiling inotify-sys v0.1.5 [INFO] [stderr] Compiling clicolors-control v0.2.0 [INFO] [stderr] Compiling nix v0.9.0 [INFO] [stderr] Compiling filetime v0.2.14 [INFO] [stderr] Compiling termios v0.3.3 [INFO] [stderr] Compiling nix v0.20.0 [INFO] [stderr] Compiling petgraph v0.4.13 [INFO] [stderr] Compiling clap v2.33.3 [INFO] [stderr] Compiling inotify v0.7.1 [INFO] [stderr] Compiling parking_lot v0.11.1 [INFO] [stderr] Compiling mio v0.6.23 [INFO] [stderr] Compiling systemd-parser v0.1.3 [INFO] [stderr] Compiling aho-corasick v0.7.18 [INFO] [stderr] Compiling aho-corasick v0.6.10 [INFO] [stderr] Compiling quote v1.0.9 [INFO] [stderr] Compiling syn v1.0.71 [INFO] [stderr] Compiling mio-extras v2.0.6 [INFO] [stderr] Compiling daggy v0.6.0 [INFO] [stderr] Compiling notify v4.0.16 [INFO] [stderr] Compiling dependy v0.4.1 [INFO] [stderr] Compiling regex v1.5.3 [INFO] [stderr] Compiling runny v1.2.5 [INFO] [stderr] Compiling console v0.6.2 [INFO] [stderr] Compiling ctrlc v3.1.9 [INFO] [stderr] Compiling serde_derive v1.0.125 [INFO] [stdout] warning: the item `RunnyError` is imported redundantly [INFO] [stdout] --> src/unit.rs:262:13 [INFO] [stdout] | [INFO] [stdout] 13 | use self::runny::RunnyError; [INFO] [stdout] | ----------------------- the item `RunnyError` is already imported here [INFO] [stdout] ... [INFO] [stdout] 262 | use self::runny::RunnyError; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:267:64 [INFO] [stdout] | [INFO] [stdout] 267 | write!(f, "Unable to activate unit: {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(deprecated)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:368:58 [INFO] [stdout] | [INFO] [stdout] 368 | write!(f, "unable to open file -- {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:371:49 [INFO] [stdout] | [INFO] [stdout] 371 | write!(f, "syntax error: {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:374:53 [INFO] [stdout] | [INFO] [stdout] 374 | write!(f, "time parse error: {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/units/test.rs:386:171 [INFO] [stdout] | [INFO] [stdout] 386 | ...tents::LogError(format!("test daemon raised an error: {}", e.description())))).unwrap(); [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `test_max_time` [INFO] [stdout] --> src/units/scenario.rs:611:21 [INFO] [stdout] | [INFO] [stdout] 611 | let test_max_time = self.make_timeout(test_timeout); [INFO] [stdout] | ^^^^^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_test_max_time` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_variables)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: associated function is never used: `get_manager` [INFO] [stdout] --> src/unitlibrary.rs:422:12 [INFO] [stdout] | [INFO] [stdout] 422 | pub fn get_manager(&self) -> &RefCell { [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 8 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 51.38s [INFO] running `Command { std: "docker" "inspect" "de66e784c1623af935fbefbd7f7e1c91bb6b0d0758731b18bca83e37ae99adc1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "de66e784c1623af935fbefbd7f7e1c91bb6b0d0758731b18bca83e37ae99adc1", kill_on_drop: false }` [INFO] [stdout] de66e784c1623af935fbefbd7f7e1c91bb6b0d0758731b18bca83e37ae99adc1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f6f932f1144487c8d05523d80aa34e4e3c484a869fa373e53c9b5233dbab2a4c [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "f6f932f1144487c8d05523d80aa34e4e3c484a869fa373e53c9b5233dbab2a4c", kill_on_drop: false }` [INFO] [stderr] Compiling exclave v0.2.6 (/opt/rustwide/workdir) [INFO] [stdout] warning: the item `RunnyError` is imported redundantly [INFO] [stdout] --> src/unit.rs:262:13 [INFO] [stdout] | [INFO] [stdout] 13 | use self::runny::RunnyError; [INFO] [stdout] | ----------------------- the item `RunnyError` is already imported here [INFO] [stdout] ... [INFO] [stdout] 262 | use self::runny::RunnyError; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused import: `units::interface::InterfaceDescription` [INFO] [stdout] --> src/test.rs:14:5 [INFO] [stdout] | [INFO] [stdout] 14 | use units::interface::InterfaceDescription; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused import: `units::logger::LoggerDescription` [INFO] [stdout] --> src/test.rs:16:5 [INFO] [stdout] | [INFO] [stdout] 16 | use units::logger::LoggerDescription; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused import: `units::trigger::TriggerDescription` [INFO] [stdout] --> src/test.rs:19:5 [INFO] [stdout] | [INFO] [stdout] 19 | use units::trigger::TriggerDescription; [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:267:64 [INFO] [stdout] | [INFO] [stdout] 267 | write!(f, "Unable to activate unit: {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(deprecated)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:368:58 [INFO] [stdout] | [INFO] [stdout] 368 | write!(f, "unable to open file -- {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:371:49 [INFO] [stdout] | [INFO] [stdout] 371 | write!(f, "syntax error: {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/unit.rs:374:53 [INFO] [stdout] | [INFO] [stdout] 374 | write!(f, "time parse error: {}", e.description()) [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stdout] --> src/units/test.rs:386:171 [INFO] [stdout] | [INFO] [stdout] 386 | ...tents::LogError(format!("test daemon raised an error: {}", e.description())))).unwrap(); [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `test_max_time` [INFO] [stdout] --> src/units/scenario.rs:611:21 [INFO] [stdout] | [INFO] [stdout] 611 | let test_max_time = self.make_timeout(test_timeout); [INFO] [stdout] | ^^^^^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_test_max_time` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_variables)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: value assigned to `master_seen` is never read [INFO] [stdout] --> src/test.rs:502:29 [INFO] [stdout] | [INFO] [stdout] 502 | ... master_seen = true; [INFO] [stdout] | ^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_assignments)]` on by default [INFO] [stdout] = help: maybe it is overwritten before being read? [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 11 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 11.12s [INFO] running `Command { std: "docker" "inspect" "f6f932f1144487c8d05523d80aa34e4e3c484a869fa373e53c9b5233dbab2a4c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f6f932f1144487c8d05523d80aa34e4e3c484a869fa373e53c9b5233dbab2a4c", kill_on_drop: false }` [INFO] [stdout] f6f932f1144487c8d05523d80aa34e4e3c484a869fa373e53c9b5233dbab2a4c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 7dde77ee2bb94deebbc73f86d9e6c651919cde3ba8eab5cb2c397b9c71f5dac6 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "7dde77ee2bb94deebbc73f86d9e6c651919cde3ba8eab5cb2c397b9c71f5dac6", kill_on_drop: false }` [INFO] [stderr] Compiling exclave v0.2.6 (/opt/rustwide/workdir) [INFO] [stderr] warning: the item `RunnyError` is imported redundantly [INFO] [stderr] --> src/unit.rs:262:13 [INFO] [stderr] | [INFO] [stderr] 13 | use self::runny::RunnyError; [INFO] [stderr] | ----------------------- the item `RunnyError` is already imported here [INFO] [stderr] ... [INFO] [stderr] 262 | use self::runny::RunnyError; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` on by default [INFO] [stderr] [INFO] [stderr] warning: unused import: `units::interface::InterfaceDescription` [INFO] [stderr] --> src/test.rs:14:5 [INFO] [stderr] | [INFO] [stderr] 14 | use units::interface::InterfaceDescription; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: unused import: `units::logger::LoggerDescription` [INFO] [stderr] --> src/test.rs:16:5 [INFO] [stderr] | [INFO] [stderr] 16 | use units::logger::LoggerDescription; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: unused import: `units::trigger::TriggerDescription` [INFO] [stderr] --> src/test.rs:19:5 [INFO] [stderr] | [INFO] [stderr] 19 | use units::trigger::TriggerDescription; [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/unit.rs:267:64 [INFO] [stderr] | [INFO] [stderr] 267 | write!(f, "Unable to activate unit: {}", e.description()) [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/unit.rs:368:58 [INFO] [stderr] | [INFO] [stderr] 368 | write!(f, "unable to open file -- {}", e.description()) [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/unit.rs:371:49 [INFO] [stderr] | [INFO] [stderr] 371 | write!(f, "syntax error: {}", e.description()) [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/unit.rs:374:53 [INFO] [stderr] | [INFO] [stderr] 374 | write!(f, "time parse error: {}", e.description()) [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::error::Error::description`: use the Display impl or to_string() [INFO] [stderr] --> src/units/test.rs:386:171 [INFO] [stderr] | [INFO] [stderr] 386 | ...tents::LogError(format!("test daemon raised an error: {}", e.description())))).unwrap(); [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: unused variable: `test_max_time` [INFO] [stderr] --> src/units/scenario.rs:611:21 [INFO] [stderr] | [INFO] [stderr] 611 | let test_max_time = self.make_timeout(test_timeout); [INFO] [stderr] | ^^^^^^^^^^^^^ help: if this is intentional, prefix it with an underscore: `_test_max_time` [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_variables)]` on by default [INFO] [stderr] [INFO] [stderr] warning: value assigned to `master_seen` is never read [INFO] [stderr] --> src/test.rs:502:29 [INFO] [stderr] | [INFO] [stderr] 502 | ... master_seen = true; [INFO] [stderr] | ^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_assignments)]` on by default [INFO] [stderr] = help: maybe it is overwritten before being read? [INFO] [stderr] [INFO] [stderr] warning: 11 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 11.76s [INFO] [stdout] [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/exclave-8591a3a6933bf770) [INFO] [stdout] running 6 tests [INFO] [stdout] test test::load_dependency ... ok [INFO] [stdout] test test::basic_scenario ... ok [INFO] [stdout] test test::test_requires ... FAILED [INFO] [stdout] test test::scenario_execstop ... ok [INFO] [stdout] test test::scenario_execstopfailure ... ok [INFO] [stderr] error: test failed, to rerun pass '--bin exclave' [INFO] [stdout] test test::scenario_execstopsuccess ... FAILED [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- test::test_requires stdout ---- [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "dependent", kind: Test }, status: Loaded }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "master", kind: Test }, status: Loaded }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "scenario", kind: Scenario }, status: Loaded }) [INFO] [stdout] Message: RescanRequest [INFO] [stdout] Message: RescanStart [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "dependent", kind: Test }, status: Selected }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "master", kind: Test }, status: Selected }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "scenario", kind: Scenario }, status: Selected }) [INFO] [stdout] Message: RescanFinish [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StartScenario(Some(UnitName { id: "scenario", kind: Scenario })) }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "scenario", kind: Scenario }, log_type: Info, log_message: "scenario starting", unix_time: 1619995759, unix_time_nsecs: 639921636 }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "scenario", kind: Scenario }, status: Active }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: AdvanceScenario(0) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StartTest(UnitName { id: "dependent", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "dependent", kind: Test }, contents: TestStarted }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "dependent", kind: Test }, status: Active }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "dependent", kind: Test }, contents: TestFinished(0, "") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "dependent", kind: Test }, contents: AdvanceScenario(0) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StopTest(UnitName { id: "dependent", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StartTest(UnitName { id: "master", kind: Test }) }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "dependent", kind: Test }, status: DeactivatedSuccessfully("controller requested test stop") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "master", kind: Test }, contents: TestStarted }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "dependent", kind: Test }, contents: Log("begin-dependent") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "dependent", kind: Test }, contents: Log("end-dependent") }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "master", kind: Test }, status: Active }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "dependent", kind: Test }, log_type: Info, log_message: "begin-dependent", unix_time: 1619995759, unix_time_nsecs: 724139626 }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "dependent", kind: Test }, log_type: Info, log_message: "end-dependent", unix_time: 1619995759, unix_time_nsecs: 724148326 }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "master", kind: Test }, contents: TestFinished(0, "") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "master", kind: Test }, contents: AdvanceScenario(0) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StopTest(UnitName { id: "master", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StopTest(UnitName { id: "dependent", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StopTest(UnitName { id: "master", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: StopTest(UnitName { id: "scenario", kind: Scenario }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "scenario", kind: Scenario }, contents: ScenarioFinished(200, "all tests passed") }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "master", kind: Test }, status: DeactivatedSuccessfully("controller requested test stop") }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "scenario", kind: Scenario }, log_type: Info, log_message: "scenario ended", unix_time: 1619995759, unix_time_nsecs: 738486831 }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "scenario", kind: Scenario }, status: DeactivatedSuccessfully("controller requested test stop") }) [INFO] [stdout] thread 'test::test_requires' panicked at 'unit scenario.scenario deactivated before strings were found (success: controller requested test stop)', src/test.rs:513:25 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55ebd0138230 - std::backtrace_rs::backtrace::libunwind::trace::hdcf4f90f85129e83 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x55ebd0138230 - std::backtrace_rs::backtrace::trace_unsynchronized::h2669e30cb82f6732 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55ebd0138230 - std::sys_common::backtrace::_print_fmt::hfbda19e17f6db318 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x55ebd0138230 - ::fmt::h1a8751bf59281272 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x55ebd015c25f - core::fmt::write::h7aa6cd0067dca82a [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/fmt/mod.rs:1094:17 [INFO] [stdout] 5: 0x55ebd0134b45 - std::io::Write::write_fmt::he6661e7347cae026 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/io/mod.rs:1580:15 [INFO] [stdout] 6: 0x55ebd013a31b - std::sys_common::backtrace::_print::h551e9ec8a9fa8106 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x55ebd013a31b - std::sys_common::backtrace::print::ha4b1c5e95fa040b3 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x55ebd013a31b - std::panicking::default_hook::{{closure}}::h0b34c9ab7fb9f857 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x55ebd0139e38 - std::panicking::default_hook::h3067e8318decd17a [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x55ebd013a8cd - std::panicking::rust_panic_with_hook::h81b8facc50f34daa [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:591:17 [INFO] [stdout] 11: 0x55ebd013a4c7 - std::panicking::begin_panic_handler::{{closure}}::ha376ab85d95a000e [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:497:13 [INFO] [stdout] 12: 0x55ebd01386ec - std::sys_common::backtrace::__rust_end_short_backtrace::h6795c8afdd1a77e6 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x55ebd013a429 - rust_begin_unwind [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:493:5 [INFO] [stdout] 14: 0x55ebcfe2ea0b - std::panicking::begin_panic_fmt::hf43a0025042538e2 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:435:5 [INFO] [stdout] 15: 0x55ebcfe7a337 - exclave::test::test_requires::hcb204396af19a732 [INFO] [stdout] at /opt/rustwide/workdir/src/test.rs:513:25 [INFO] [stdout] 16: 0x55ebcfe79bad - exclave::test::test_requires::{{closure}}::h9a91673e76219d83 [INFO] [stdout] at /opt/rustwide/workdir/src/test.rs:453:1 [INFO] [stdout] 17: 0x55ebcfee976e - core::ops::function::FnOnce::call_once::h6acde59440e493ea [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 18: 0x55ebcff5b2b6 - core::ops::function::FnOnce::call_once::h4e9353104004bc3d [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x55ebcff5b2b6 - test::__rust_begin_short_backtrace::hea4fd7fe7fb8303e [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:576:5 [INFO] [stdout] 20: 0x55ebcff59d16 - as core::ops::function::FnOnce>::call_once::hd7fd3e88849fc750 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/alloc/src/boxed.rs:1546:9 [INFO] [stdout] 21: 0x55ebcff59d16 - as core::ops::function::FnOnce<()>>::call_once::h78a6897906b26c6e [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:344:9 [INFO] [stdout] 22: 0x55ebcff59d16 - std::panicking::try::do_call::hbaa9991bef154d76 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:379:40 [INFO] [stdout] 23: 0x55ebcff59d16 - std::panicking::try::h0d1e876a7e6d9927 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:343:19 [INFO] [stdout] 24: 0x55ebcff59d16 - std::panic::catch_unwind::hf9cfc8afbc70b84f [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:431:14 [INFO] [stdout] 25: 0x55ebcff59d16 - test::run_test_in_process::h947bacbaa4554ce4 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:599:18 [INFO] [stdout] 26: 0x55ebcff59d16 - test::run_test::run_test_inner::{{closure}}::he92bab8f95bf0784 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:491:39 [INFO] [stdout] 27: 0x55ebcff3515e - test::run_test::run_test_inner::{{closure}}::hf54209f3d25efeee [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:518:37 [INFO] [stdout] 28: 0x55ebcff3515e - std::sys_common::backtrace::__rust_begin_short_backtrace::hd6d5206cf37fd269 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 29: 0x55ebcff38e78 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h13bbfbf02991444a [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/thread/mod.rs:474:17 [INFO] [stdout] 30: 0x55ebcff38e78 - as core::ops::function::FnOnce<()>>::call_once::h0e73313fa1b917d2 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:344:9 [INFO] [stdout] 31: 0x55ebcff38e78 - std::panicking::try::do_call::h0601c6cdea59af51 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:379:40 [INFO] [stdout] 32: 0x55ebcff38e78 - std::panicking::try::h0d9ec6a2b7f348d3 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:343:19 [INFO] [stdout] 33: 0x55ebcff38e78 - std::panic::catch_unwind::h5b95c4a8cef23017 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:431:14 [INFO] [stdout] 34: 0x55ebcff38e78 - std::thread::Builder::spawn_unchecked::{{closure}}::hbfc50e47df131303 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/thread/mod.rs:473:30 [INFO] [stdout] 35: 0x55ebcff38e78 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h5a3a0711c63b5a79 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 36: 0x55ebd01406a3 - as core::ops::function::FnOnce>::call_once::h3aa31cb6360b59d9 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/alloc/src/boxed.rs:1546:9 [INFO] [stdout] 37: 0x55ebd01406a3 - as core::ops::function::FnOnce>::call_once::h7719d3c7c5841461 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/alloc/src/boxed.rs:1546:9 [INFO] [stdout] 38: 0x55ebd01406a3 - std::sys::unix::thread::Thread::new::thread_start::hfbe13ead469fd0bc [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys/unix/thread.rs:71:17 [INFO] [stdout] 39: 0x7fcf122c0609 - start_thread [INFO] [stdout] 40: 0x7fcf12427293 - clone [INFO] [stdout] 41: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- test::scenario_execstopsuccess stdout ---- [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "simpletest", kind: Test }, status: Loaded }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "execstopsuccess", kind: Scenario }, status: Loaded }) [INFO] [stdout] Message: RescanRequest [INFO] [stdout] Message: RescanStart [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "simpletest", kind: Test }, status: Selected }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "execstopsuccess", kind: Scenario }, status: Selected }) [INFO] [stdout] Message: RescanFinish [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: StartScenario(Some(UnitName { id: "execstopsuccess", kind: Scenario })) }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "execstopsuccess", kind: Scenario }, log_type: Info, log_message: "scenario starting", unix_time: 1619995759, unix_time_nsecs: 637676488 }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "execstopsuccess", kind: Scenario }, status: Active }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: AdvanceScenario(0) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: StartTest(UnitName { id: "simpletest", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "simpletest", kind: Test }, contents: TestStarted }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "simpletest", kind: Test }, status: Active }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "simpletest", kind: Test }, contents: TestFinished(0, "") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "simpletest", kind: Test }, contents: AdvanceScenario(0) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "simpletest", kind: Test }, contents: Log("begin") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "simpletest", kind: Test }, contents: Log("end") }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: StopTest(UnitName { id: "simpletest", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: Log("execstopsuccess: starting [/bin/sh -c \"echo 'cmd-starting-success'; sleep 1; echo 'cmd-ending-success'; exit 0\"]") }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "simpletest", kind: Test }, log_type: Info, log_message: "begin", unix_time: 1619995759, unix_time_nsecs: 769959699 }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "simpletest", kind: Test }, log_type: Info, log_message: "end", unix_time: 1619995759, unix_time_nsecs: 769970600 }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "simpletest", kind: Test }, status: DeactivatedSuccessfully("controller requested test stop") }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "execstopsuccess", kind: Scenario }, log_type: Info, log_message: "execstopsuccess: starting [/bin/sh -c \"echo 'cmd-starting-success'; sleep 1; echo 'cmd-ending-success'; exit 0\"]", unix_time: 1619995759, unix_time_nsecs: 770007700 }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: Log("cmd-starting-success") }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "execstopsuccess", kind: Scenario }, log_type: Info, log_message: "cmd-starting-success", unix_time: 1619995759, unix_time_nsecs: 770751416 }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: AdvanceScenario(0) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: Log("execstopsuccess: finished [/bin/sh -c \"echo 'cmd-starting-success'; sleep 1; echo 'cmd-ending-success'; exit 0\"]") }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "execstopsuccess", kind: Scenario }, log_type: Info, log_message: "execstopsuccess: finished [/bin/sh -c \"echo 'cmd-starting-success'; sleep 1; echo 'cmd-ending-success'; exit 0\"]", unix_time: 1619995760, unix_time_nsecs: 775390565 }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: StopTest(UnitName { id: "simpletest", kind: Test }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: StopTest(UnitName { id: "execstopsuccess", kind: Scenario }) }) [INFO] [stdout] Message: ManagerRequest(ManagerControlMessage { sender: UnitName { id: "execstopsuccess", kind: Scenario }, contents: ScenarioFinished(200, "all tests passed") }) [INFO] [stdout] Message: Log(LogEntry { unit: UnitName { id: "execstopsuccess", kind: Scenario }, log_type: Info, log_message: "scenario ended", unix_time: 1619995760, unix_time_nsecs: 775466267 }) [INFO] [stdout] Message: Status(UnitStatusEvent { name: UnitName { id: "execstopsuccess", kind: Scenario }, status: DeactivatedSuccessfully("controller requested test stop") }) [INFO] [stdout] thread 'test::scenario_execstopsuccess' panicked at 'unit execstopsuccess.scenario deactivated before success script was run (success: controller requested test stop)', src/test.rs:371:25 [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x55ebd0138230 - std::backtrace_rs::backtrace::libunwind::trace::hdcf4f90f85129e83 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 [INFO] [stdout] 1: 0x55ebd0138230 - std::backtrace_rs::backtrace::trace_unsynchronized::h2669e30cb82f6732 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 [INFO] [stdout] 2: 0x55ebd0138230 - std::sys_common::backtrace::_print_fmt::hfbda19e17f6db318 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:67:5 [INFO] [stdout] 3: 0x55ebd0138230 - ::fmt::h1a8751bf59281272 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:46:22 [INFO] [stdout] 4: 0x55ebd015c25f - core::fmt::write::h7aa6cd0067dca82a [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/fmt/mod.rs:1094:17 [INFO] [stdout] 5: 0x55ebd0134b45 - std::io::Write::write_fmt::he6661e7347cae026 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/io/mod.rs:1580:15 [INFO] [stdout] 6: 0x55ebd013a31b - std::sys_common::backtrace::_print::h551e9ec8a9fa8106 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:49:5 [INFO] [stdout] 7: 0x55ebd013a31b - std::sys_common::backtrace::print::ha4b1c5e95fa040b3 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:36:9 [INFO] [stdout] 8: 0x55ebd013a31b - std::panicking::default_hook::{{closure}}::h0b34c9ab7fb9f857 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:208:50 [INFO] [stdout] 9: 0x55ebd0139e38 - std::panicking::default_hook::h3067e8318decd17a [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:222:9 [INFO] [stdout] 10: 0x55ebd013a8cd - std::panicking::rust_panic_with_hook::h81b8facc50f34daa [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:591:17 [INFO] [stdout] 11: 0x55ebd013a4c7 - std::panicking::begin_panic_handler::{{closure}}::ha376ab85d95a000e [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:497:13 [INFO] [stdout] 12: 0x55ebd01386ec - std::sys_common::backtrace::__rust_end_short_backtrace::h6795c8afdd1a77e6 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:141:18 [INFO] [stdout] 13: 0x55ebd013a429 - rust_begin_unwind [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:493:5 [INFO] [stdout] 14: 0x55ebcfe2ea0b - std::panicking::begin_panic_fmt::hf43a0025042538e2 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:435:5 [INFO] [stdout] 15: 0x55ebcfe78feb - exclave::test::scenario_execstopsuccess::h238d7596cf7f21ad [INFO] [stdout] at /opt/rustwide/workdir/src/test.rs:371:25 [INFO] [stdout] 16: 0x55ebcfe787fd - exclave::test::scenario_execstopsuccess::{{closure}}::hcd8912585b902315 [INFO] [stdout] at /opt/rustwide/workdir/src/test.rs:311:1 [INFO] [stdout] 17: 0x55ebcfee98be - core::ops::function::FnOnce::call_once::hbccf5eb27530e2b3 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 18: 0x55ebcff5b2b6 - core::ops::function::FnOnce::call_once::h4e9353104004bc3d [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 19: 0x55ebcff5b2b6 - test::__rust_begin_short_backtrace::hea4fd7fe7fb8303e [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:576:5 [INFO] [stdout] 20: 0x55ebcff59d16 - as core::ops::function::FnOnce>::call_once::hd7fd3e88849fc750 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/alloc/src/boxed.rs:1546:9 [INFO] [stdout] 21: 0x55ebcff59d16 - as core::ops::function::FnOnce<()>>::call_once::h78a6897906b26c6e [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:344:9 [INFO] [stdout] 22: 0x55ebcff59d16 - std::panicking::try::do_call::hbaa9991bef154d76 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:379:40 [INFO] [stdout] 23: 0x55ebcff59d16 - std::panicking::try::h0d1e876a7e6d9927 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:343:19 [INFO] [stdout] 24: 0x55ebcff59d16 - std::panic::catch_unwind::hf9cfc8afbc70b84f [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:431:14 [INFO] [stdout] 25: 0x55ebcff59d16 - test::run_test_in_process::h947bacbaa4554ce4 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:599:18 [INFO] [stdout] 26: 0x55ebcff59d16 - test::run_test::run_test_inner::{{closure}}::he92bab8f95bf0784 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:491:39 [INFO] [stdout] 27: 0x55ebcff3515e - test::run_test::run_test_inner::{{closure}}::hf54209f3d25efeee [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/test/src/lib.rs:518:37 [INFO] [stdout] 28: 0x55ebcff3515e - std::sys_common::backtrace::__rust_begin_short_backtrace::hd6d5206cf37fd269 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys_common/backtrace.rs:125:18 [INFO] [stdout] 29: 0x55ebcff38e78 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::h13bbfbf02991444a [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/thread/mod.rs:474:17 [INFO] [stdout] 30: 0x55ebcff38e78 - as core::ops::function::FnOnce<()>>::call_once::h0e73313fa1b917d2 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:344:9 [INFO] [stdout] 31: 0x55ebcff38e78 - std::panicking::try::do_call::h0601c6cdea59af51 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:379:40 [INFO] [stdout] 32: 0x55ebcff38e78 - std::panicking::try::h0d9ec6a2b7f348d3 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panicking.rs:343:19 [INFO] [stdout] 33: 0x55ebcff38e78 - std::panic::catch_unwind::h5b95c4a8cef23017 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/panic.rs:431:14 [INFO] [stdout] 34: 0x55ebcff38e78 - std::thread::Builder::spawn_unchecked::{{closure}}::hbfc50e47df131303 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/thread/mod.rs:473:30 [INFO] [stdout] 35: 0x55ebcff38e78 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h5a3a0711c63b5a79 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/core/src/ops/function.rs:227:5 [INFO] [stdout] 36: 0x55ebd01406a3 - as core::ops::function::FnOnce>::call_once::h3aa31cb6360b59d9 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/alloc/src/boxed.rs:1546:9 [INFO] [stdout] 37: 0x55ebd01406a3 - as core::ops::function::FnOnce>::call_once::h7719d3c7c5841461 [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/alloc/src/boxed.rs:1546:9 [INFO] [stdout] 38: 0x55ebd01406a3 - std::sys::unix::thread::Thread::new::thread_start::hfbe13ead469fd0bc [INFO] [stdout] at /rustc/d408fdd4a82bc3e7ea61dd81bc9a8781b2bf939d/library/std/src/sys/unix/thread.rs:71:17 [INFO] [stdout] 39: 0x7fcf122c0609 - start_thread [INFO] [stdout] 40: 0x7fcf12427293 - clone [INFO] [stdout] 41: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] test::scenario_execstopsuccess [INFO] [stdout] test::test_requires [INFO] [stdout] [INFO] [stdout] test result: FAILED. 4 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.15s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "7dde77ee2bb94deebbc73f86d9e6c651919cde3ba8eab5cb2c397b9c71f5dac6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7dde77ee2bb94deebbc73f86d9e6c651919cde3ba8eab5cb2c397b9c71f5dac6", kill_on_drop: false }` [INFO] [stdout] 7dde77ee2bb94deebbc73f86d9e6c651919cde3ba8eab5cb2c397b9c71f5dac6