[INFO] cloning repository https://github.com/vincentdephily/emlop
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/vincentdephily/emlop" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fvincentdephily%2Femlop", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fvincentdephily%2Femlop'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 9b5a0c3ccc47eb05be7eaf1902540e590e05dc9e
[INFO] testing vincentdephily/emlop against 1.47.0 for beta-1.48-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fvincentdephily%2Femlop" "/workspace/builds/worker-7/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/vincentdephily/emlop on toolchain 1.47.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.47.0" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/vincentdephily/emlop
[INFO] finished tweaking git repo https://github.com/vincentdephily/emlop
[INFO] tweaked toml for git repo https://github.com/vincentdephily/emlop written to /workspace/builds/worker-7/source/Cargo.toml
[INFO] crate git repo https://github.com/vincentdephily/emlop already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.47.0" "fetch" "--locked" "--manifest-path" "Cargo.toml", 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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+1.47.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] fad8c5ca794c53a6f59b62c7dccf5567e1f413af449c87185a5318864e51d6ac
[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" "fad8c5ca794c53a6f59b62c7dccf5567e1f413af449c87185a5318864e51d6ac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "fad8c5ca794c53a6f59b62c7dccf5567e1f413af449c87185a5318864e51d6ac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fad8c5ca794c53a6f59b62c7dccf5567e1f413af449c87185a5318864e51d6ac", kill_on_drop: false }`
[INFO] [stdout] fad8c5ca794c53a6f59b62c7dccf5567e1f413af449c87185a5318864e51d6ac
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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=warn" "-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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+1.47.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ce220f70278fa33f249397c2338ba1da8cc910ee7224f1da511041ad4fc1005a
[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" "ce220f70278fa33f249397c2338ba1da8cc910ee7224f1da511041ad4fc1005a", kill_on_drop: false }`
[INFO] [stderr]    Compiling scanlex v0.1.2
[INFO] [stderr]    Compiling errno v0.2.6
[INFO] [stderr]    Compiling tabwriter v1.2.1
[INFO] [stderr]    Compiling stderrlog v0.5.0
[INFO] [stderr]    Compiling sysconf v0.3.4
[INFO] [stderr]    Compiling chrono-english v0.1.4
[INFO] [stderr]    Compiling emlop v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 4.55s
[INFO] running `Command { std: "docker" "inspect" "ce220f70278fa33f249397c2338ba1da8cc910ee7224f1da511041ad4fc1005a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ce220f70278fa33f249397c2338ba1da8cc910ee7224f1da511041ad4fc1005a", kill_on_drop: false }`
[INFO] [stdout] ce220f70278fa33f249397c2338ba1da8cc910ee7224f1da511041ad4fc1005a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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=warn" "-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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+1.47.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7a3d56a163ebb36cc68c7253e8a0e0cf0070e13c6c8666b614a3b2a8ac8c9c43
[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" "7a3d56a163ebb36cc68c7253e8a0e0cf0070e13c6c8666b614a3b2a8ac8c9c43", kill_on_drop: false }`
[INFO] [stderr]    Compiling escargot v0.5.0
[INFO] [stderr]    Compiling wait-timeout v0.2.0
[INFO] [stderr]    Compiling serde_json v1.0.58
[INFO] [stderr]    Compiling assert_cmd v1.0.1
[INFO] [stderr]    Compiling emlop v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 15.22s
[INFO] running `Command { std: "docker" "inspect" "7a3d56a163ebb36cc68c7253e8a0e0cf0070e13c6c8666b614a3b2a8ac8c9c43", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7a3d56a163ebb36cc68c7253e8a0e0cf0070e13c6c8666b614a3b2a8ac8c9c43", kill_on_drop: false }`
[INFO] [stdout] 7a3d56a163ebb36cc68c7253e8a0e0cf0070e13c6c8666b614a3b2a8ac8c9c43
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7/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=warn" "-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" "rustops/crates-build-env@sha256:d92eaeed33fa75130ca1ee6c8a01d1ffb62bac859dfdfb3450e3e5d1c0146529" "/opt/rustwide/cargo-home/bin/cargo" "+1.47.0" "test" "--frozen", 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] 4829cedabba0b02facd6869aeb994795d2319340d64fe2ae99f11eb2121f69c5
[INFO] running `Command { std: "docker" "start" "-a" "4829cedabba0b02facd6869aeb994795d2319340d64fe2ae99f11eb2121f69c5", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/emlop-dcf70b0feccb5869
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test commands::tests::timespan_next_ ... ok
[INFO] [stdout] test parser::tests::parse_hist_shortline ... ok
[INFO] [stdout] test parser::tests::parse_pretend_blocker ... ok
[INFO] [stdout] test parser::tests::split_atom_ ... ok
[INFO] [stdout] test parser::tests::parse_pretend_basic ... ok
[INFO] [stdout] test parser::tests::parse_hist_badtimestamp ... ok
[INFO] [stdout] test parser::tests::parse_hist_badversion ... ok
[INFO] [stdout] test tests::duration ... ok
[INFO] [stdout] test parser::tests::parse_hist_nullbytes ... ok
[INFO] [stdout] test tests::date ... FAILED
[INFO] [stdout] test proces::tests::start_time ... FAILED
[INFO] [stdout] test parser::tests::parse_hist_nofilter ... ok
[INFO] [stdout] test parser::tests::parse_hist_filter_ts ... ok
[INFO] [stdout] test parser::tests::parse_hist_filter_pkg ... ok
[INFO] [stdout] test parser::tests::parse_hist_all ... ok
[INFO] [stdout] test commands::tests::predict_tty ... FAILED
[INFO] [stdout] test commands::tests::negative_merge_time ... FAILED
[INFO] [stdout] test commands::tests::log ... ok
[INFO] [stdout] test commands::tests::predict_emerge_p ... ok
[INFO] [stdout] test commands::tests::stats ... ok
[INFO] [stdout] test commands::tests::exit_status ... ok
[INFO] [stderr] error: test failed, to rerun pass '--bin emlop'
[INFO] [stdout] test commands::tests::stats_grouped ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::date stdout ----
[INFO] [stdout] thread 'tests::date' panicked at 'assertion failed: `(left == right)`
[INFO] [stdout]   left: `Ok(1522713661)`,
[INFO] [stdout]  right: `Ok(1522717261)`', src/main.rs:237:9
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::libunwind::trace::h577ea05e9ca4629a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/libunwind.rs:96
[INFO] [stdout]    1:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::trace_unsynchronized::h50b9b72b84c7dd56
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/mod.rs:66
[INFO] [stdout]    2:     0x55f45d5e1a20 - std::sys_common::backtrace::_print_fmt::h6541cf9823837fac
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:79
[INFO] [stdout]    3:     0x55f45d5e1a20 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf64fbff071026df5
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:58
[INFO] [stdout]    4:     0x55f45d6078cc - core::fmt::write::h9ddafa4860d8adff
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/fmt/mod.rs:1082
[INFO] [stdout]    5:     0x55f45d26e155 - std::io::Write::write_fmt::h50a71e2460ce07da
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/mod.rs:1514
[INFO] [stdout]    6:     0x55f45d5dc2e1 - std::io::impls::<impl std::io::Write for alloc::boxed::Box<W>>::write_fmt::h9bbd3fed8160d0d2
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/impls.rs:176
[INFO] [stdout]    7:     0x55f45d5e3ea0 - std::sys_common::backtrace::_print::ha25f9ff5080d886d
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:61
[INFO] [stdout]    8:     0x55f45d5e3ea0 - std::sys_common::backtrace::print::h213e8aa8dc5405c0
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:48
[INFO] [stdout]    9:     0x55f45d5e3ea0 - std::panicking::default_hook::{{closure}}::h6482fae49ef9d963
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:200
[INFO] [stdout]   10:     0x55f45d5e3b96 - std::panicking::default_hook::he30ad7589e0970f9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:216
[INFO] [stdout]   11:     0x55f45d5e4503 - std::panicking::rust_panic_with_hook::haa1ed36ada4ffb03
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:569
[INFO] [stdout]   12:     0x55f45d5e40d9 - std::panicking::begin_panic_handler::{{closure}}::h7001af1bb21aeaeb
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:476
[INFO] [stdout]   13:     0x55f45d5e1eac - std::sys_common::backtrace::__rust_end_short_backtrace::h39910f557f5f2367
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:153
[INFO] [stdout]   14:     0x55f45d5e4099 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:475
[INFO] [stdout]   15:     0x55f45d5e404b - std::panicking::begin_panic_fmt::hc1b1320af38b7e12
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:429
[INFO] [stdout]   16:     0x55f45d256e86 - emlop::tests::date::h3670ebbf18505642
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:237
[INFO] [stdout]   17:     0x55f45d2567ca - emlop::tests::date::{{closure}}::h5da93989c5f15f90
[INFO] [stdout]                                at /opt/rustwide/workdir/src/main.rs:232
[INFO] [stdout]   18:     0x55f45d207cde - core::ops::function::FnOnce::call_once::h40a7a154ac3e52e9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   19:     0x55f45d296a33 - core::ops::function::FnOnce::call_once::hd04d57b5cf16f85a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   20:     0x55f45d296a33 - test::__rust_begin_short_backtrace::hd8e9a8c5a2e43388
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:517
[INFO] [stdout]   21:     0x55f45d294f03 - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h3c3db8a500c1a19a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   22:     0x55f45d294f03 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h043e9ebcbe360c80
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   23:     0x55f45d294f03 - std::panicking::try::do_call::h47c2fb275506c2e8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   24:     0x55f45d294f03 - std::panicking::try::h569c20f0e99453b4
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   25:     0x55f45d294f03 - std::panic::catch_unwind::hb33c6d0f01540d23
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   26:     0x55f45d294f03 - test::run_test_in_process::hc0ce562d167e51cf
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:544
[INFO] [stdout]   27:     0x55f45d294f03 - test::run_test::run_test_inner::{{closure}}::hd3120982fcd71544
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:450
[INFO] [stdout]   28:     0x55f45d26d586 - std::sys_common::backtrace::__rust_begin_short_backtrace::h84f8bc12cc4cdac6
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:137
[INFO] [stdout]   29:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb8b5fa0ba425d4db
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:458
[INFO] [stdout]   30:     0x55f45d2727b5 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc4b84b244cf55f75
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   31:     0x55f45d2727b5 - std::panicking::try::do_call::ha31922ad8fa17753
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   32:     0x55f45d2727b5 - std::panicking::try::h38a6da203722ae6e
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   33:     0x55f45d2727b5 - std::panic::catch_unwind::h83afce167c9062b9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   34:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h160689155590a70c
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:457
[INFO] [stdout]   35:     0x55f45d2727b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7dbf17a455132a46
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   36:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h670c50864ac2cb92
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   37:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h2511952749086d81
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   38:     0x55f45d5eb5aa - std::sys::unix::thread::Thread::new::thread_start::h5ad4ddffe24373a8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys/unix/thread.rs:87
[INFO] [stdout]   39:     0x7fc1d6227609 - start_thread
[INFO] [stdout]   40:     0x7fc1d6133103 - clone
[INFO] [stdout]   41:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- proces::tests::start_time stdout ----
[INFO] [stdout] thread 'proces::tests::start_time' panicked at 'Only 5 processes found', src/proces.rs:121:9
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::libunwind::trace::h577ea05e9ca4629a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/libunwind.rs:96
[INFO] [stdout]    1:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::trace_unsynchronized::h50b9b72b84c7dd56
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/mod.rs:66
[INFO] [stdout]    2:     0x55f45d5e1a20 - std::sys_common::backtrace::_print_fmt::h6541cf9823837fac
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:79
[INFO] [stdout]    3:     0x55f45d5e1a20 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf64fbff071026df5
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:58
[INFO] [stdout]    4:     0x55f45d6078cc - core::fmt::write::h9ddafa4860d8adff
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/fmt/mod.rs:1082
[INFO] [stdout]    5:     0x55f45d26e155 - std::io::Write::write_fmt::h50a71e2460ce07da
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/mod.rs:1514
[INFO] [stdout]    6:     0x55f45d5dc2e1 - std::io::impls::<impl std::io::Write for alloc::boxed::Box<W>>::write_fmt::h9bbd3fed8160d0d2
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/impls.rs:176
[INFO] [stdout]    7:     0x55f45d5e3ea0 - std::sys_common::backtrace::_print::ha25f9ff5080d886d
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:61
[INFO] [stdout]    8:     0x55f45d5e3ea0 - std::sys_common::backtrace::print::h213e8aa8dc5405c0
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:48
[INFO] [stdout]    9:     0x55f45d5e3ea0 - std::panicking::default_hook::{{closure}}::h6482fae49ef9d963
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:200
[INFO] [stdout]   10:     0x55f45d5e3b96 - std::panicking::default_hook::he30ad7589e0970f9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:216
[INFO] [stdout]   11:     0x55f45d5e4503 - std::panicking::rust_panic_with_hook::haa1ed36ada4ffb03
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:569
[INFO] [stdout]   12:     0x55f45d5e40d9 - std::panicking::begin_panic_handler::{{closure}}::h7001af1bb21aeaeb
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:476
[INFO] [stdout]   13:     0x55f45d5e1eac - std::sys_common::backtrace::__rust_end_short_backtrace::h39910f557f5f2367
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:153
[INFO] [stdout]   14:     0x55f45d5e4099 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:475
[INFO] [stdout]   15:     0x55f45d5e404b - std::panicking::begin_panic_fmt::hc1b1320af38b7e12
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:429
[INFO] [stdout]   16:     0x55f45d20cfff - emlop::proces::tests::start_time::hb7c187ffb81a6aa4
[INFO] [stdout]                                at /opt/rustwide/workdir/src/proces.rs:121
[INFO] [stdout]   17:     0x55f45d20c42a - emlop::proces::tests::start_time::{{closure}}::h66df807d8c537269
[INFO] [stdout]                                at /opt/rustwide/workdir/src/proces.rs:84
[INFO] [stdout]   18:     0x55f45d207e9e - core::ops::function::FnOnce::call_once::hbcebd43110f2131c
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   19:     0x55f45d296a33 - core::ops::function::FnOnce::call_once::hd04d57b5cf16f85a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   20:     0x55f45d296a33 - test::__rust_begin_short_backtrace::hd8e9a8c5a2e43388
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:517
[INFO] [stdout]   21:     0x55f45d294f03 - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h3c3db8a500c1a19a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   22:     0x55f45d294f03 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h043e9ebcbe360c80
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   23:     0x55f45d294f03 - std::panicking::try::do_call::h47c2fb275506c2e8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   24:     0x55f45d294f03 - std::panicking::try::h569c20f0e99453b4
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   25:     0x55f45d294f03 - std::panic::catch_unwind::hb33c6d0f01540d23
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   26:     0x55f45d294f03 - test::run_test_in_process::hc0ce562d167e51cf
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:544
[INFO] [stdout]   27:     0x55f45d294f03 - test::run_test::run_test_inner::{{closure}}::hd3120982fcd71544
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:450
[INFO] [stdout]   28:     0x55f45d26d586 - std::sys_common::backtrace::__rust_begin_short_backtrace::h84f8bc12cc4cdac6
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:137
[INFO] [stdout]   29:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb8b5fa0ba425d4db
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:458
[INFO] [stdout]   30:     0x55f45d2727b5 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc4b84b244cf55f75
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   31:     0x55f45d2727b5 - std::panicking::try::do_call::ha31922ad8fa17753
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   32:     0x55f45d2727b5 - std::panicking::try::h38a6da203722ae6e
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   33:     0x55f45d2727b5 - std::panic::catch_unwind::h83afce167c9062b9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   34:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h160689155590a70c
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:457
[INFO] [stdout]   35:     0x55f45d2727b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7dbf17a455132a46
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   36:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h670c50864ac2cb92
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   37:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h2511952749086d81
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   38:     0x55f45d5eb5aa - std::sys::unix::thread::Thread::new::thread_start::h5ad4ddffe24373a8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys/unix/thread.rs:87
[INFO] [stdout]   39:     0x7fc1d6227609 - start_thread
[INFO] [stdout]   40:     0x7fc1d6133103 - clone
[INFO] [stdout]   41:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- commands::tests::predict_tty stdout ----
[INFO] [stdout] thread 'commands::tests::predict_tty' panicked at 'Unexpected return code, failed var == 2
[INFO] [stdout] 
[INFO] [stdout] command=`"/opt/rustwide/target/x86_64-unknown-linux-gnu/debug/emlop" "p"`
[INFO] [stdout] code=1
[INFO] [stdout] stdout=``````
[INFO] [stdout] stderr=```ERROR - Cannot open "/var/log/emerge.log": No such file or directory (os error 2)
[INFO] [stdout] ```
[INFO] [stdout] ', /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/assert_cmd-1.0.1/src/assert.rs:257:13
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::libunwind::trace::h577ea05e9ca4629a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/libunwind.rs:96
[INFO] [stdout]    1:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::trace_unsynchronized::h50b9b72b84c7dd56
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/mod.rs:66
[INFO] [stdout]    2:     0x55f45d5e1a20 - std::sys_common::backtrace::_print_fmt::h6541cf9823837fac
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:79
[INFO] [stdout]    3:     0x55f45d5e1a20 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf64fbff071026df5
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:58
[INFO] [stdout]    4:     0x55f45d6078cc - core::fmt::write::h9ddafa4860d8adff
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/fmt/mod.rs:1082
[INFO] [stdout]    5:     0x55f45d26e155 - std::io::Write::write_fmt::h50a71e2460ce07da
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/mod.rs:1514
[INFO] [stdout]    6:     0x55f45d5dc2e1 - std::io::impls::<impl std::io::Write for alloc::boxed::Box<W>>::write_fmt::h9bbd3fed8160d0d2
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/impls.rs:176
[INFO] [stdout]    7:     0x55f45d5e3ea0 - std::sys_common::backtrace::_print::ha25f9ff5080d886d
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:61
[INFO] [stdout]    8:     0x55f45d5e3ea0 - std::sys_common::backtrace::print::h213e8aa8dc5405c0
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:48
[INFO] [stdout]    9:     0x55f45d5e3ea0 - std::panicking::default_hook::{{closure}}::h6482fae49ef9d963
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:200
[INFO] [stdout]   10:     0x55f45d5e3b96 - std::panicking::default_hook::he30ad7589e0970f9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:216
[INFO] [stdout]   11:     0x55f45d5e4503 - std::panicking::rust_panic_with_hook::haa1ed36ada4ffb03
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:569
[INFO] [stdout]   12:     0x55f45d5e40d9 - std::panicking::begin_panic_handler::{{closure}}::h7001af1bb21aeaeb
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:476
[INFO] [stdout]   13:     0x55f45d5e1eac - std::sys_common::backtrace::__rust_end_short_backtrace::h39910f557f5f2367
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:153
[INFO] [stdout]   14:     0x55f45d5e4099 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:475
[INFO] [stdout]   15:     0x55f45d5e404b - std::panicking::begin_panic_fmt::hc1b1320af38b7e12
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:429
[INFO] [stdout]   16:     0x55f45d53d029 - assert_cmd::assert::Assert::code_impl::hf01d280203512f12
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/assert_cmd-1.0.1/src/assert.rs:257
[INFO] [stdout]   17:     0x55f45d257a6f - assert_cmd::assert::Assert::code::h2bf762b3a8559d25
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/assert_cmd-1.0.1/src/assert.rs:247
[INFO] [stdout]   18:     0x55f45d21948e - emlop::commands::tests::predict_tty::h933eb24c2f3ef605
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands.rs:520
[INFO] [stdout]   19:     0x55f45d2193fa - emlop::commands::tests::predict_tty::{{closure}}::h505ad8f938c5b6ff
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands.rs:517
[INFO] [stdout]   20:     0x55f45d20815e - core::ops::function::FnOnce::call_once::hfd5329c5959f6f75
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   21:     0x55f45d296a33 - core::ops::function::FnOnce::call_once::hd04d57b5cf16f85a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   22:     0x55f45d296a33 - test::__rust_begin_short_backtrace::hd8e9a8c5a2e43388
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:517
[INFO] [stdout]   23:     0x55f45d294f03 - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h3c3db8a500c1a19a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   24:     0x55f45d294f03 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h043e9ebcbe360c80
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   25:     0x55f45d294f03 - std::panicking::try::do_call::h47c2fb275506c2e8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   26:     0x55f45d294f03 - std::panicking::try::h569c20f0e99453b4
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   27:     0x55f45d294f03 - std::panic::catch_unwind::hb33c6d0f01540d23
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   28:     0x55f45d294f03 - test::run_test_in_process::hc0ce562d167e51cf
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:544
[INFO] [stdout]   29:     0x55f45d294f03 - test::run_test::run_test_inner::{{closure}}::hd3120982fcd71544
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:450
[INFO] [stdout]   30:     0x55f45d26d586 - std::sys_common::backtrace::__rust_begin_short_backtrace::h84f8bc12cc4cdac6
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:137
[INFO] [stdout]   31:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb8b5fa0ba425d4db
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:458
[INFO] [stdout]   32:     0x55f45d2727b5 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc4b84b244cf55f75
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   33:     0x55f45d2727b5 - std::panicking::try::do_call::ha31922ad8fa17753
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   34:     0x55f45d2727b5 - std::panicking::try::h38a6da203722ae6e
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   35:     0x55f45d2727b5 - std::panic::catch_unwind::h83afce167c9062b9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   36:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h160689155590a70c
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:457
[INFO] [stdout]   37:     0x55f45d2727b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7dbf17a455132a46
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   38:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h670c50864ac2cb92
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   39:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h2511952749086d81
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   40:     0x55f45d5eb5aa - std::sys::unix::thread::Thread::new::thread_start::h5ad4ddffe24373a8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys/unix/thread.rs:87
[INFO] [stdout]   41:     0x7fc1d6227609 - start_thread
[INFO] [stdout]   42:     0x7fc1d6133103 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- commands::tests::negative_merge_time stdout ----
[INFO] [stdout] thread 'commands::tests::negative_merge_time' panicked at 'Unexpected stdout, failed var - original <= 0
[INFO] [stdout] ├── original: 2019-06-05 09:32:10 +01:00      1:09 Sync
[INFO] [stdout] 2019-06-05 12:26:54 +01:00      5:56 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-06 03:11:48 +01:00        26 >>> kde-apps/libktnef-19.04.1
[INFO] [stdout] 2019-06-06 03:16:01 +01:00        34 >>> net-misc/chrony-3.3
[INFO] [stdout] 2019-06-05 11:18:28 +01:00         ? Sync
[INFO] [stdout] 2019-06-05 11:21:02 +01:00         ? >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-08 22:33:36 +01:00      3:10 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 
[INFO] [stdout] ├── actual distance: 14
[INFO] [stdout] ├── diff: [91m2019-06-05 09:32:10 +01:00      1:09 Sync
[INFO] [stdout] 2019-06-05 12:26:54 +01:00      5:56 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-06 03:11:48 +01:00        26 >>> kde-apps/libktnef-19.04.1
[INFO] [stdout] 2019-06-06 03:16:01 +01:00        34 >>> net-misc/chrony-3.3
[INFO] [stdout] 2019-06-05 11:18:28 +01:00         ? Sync
[INFO] [stdout] 2019-06-05 11:21:02 +01:00         ? >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-08 22:33:36 +01:00      3:10 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] [0m
[INFO] [stdout] [92m2019-06-05 08:32:10 +00:00      1:09 Sync
[INFO] [stdout] 2019-06-05 11:26:54 +00:00      5:56 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-06 02:11:48 +00:00        26 >>> kde-apps/libktnef-19.04.1
[INFO] [stdout] 2019-06-06 02:16:01 +00:00        34 >>> net-misc/chrony-3.3
[INFO] [stdout] 2019-06-05 10:18:28 +00:00         ? Sync
[INFO] [stdout] 2019-06-05 10:21:02 +00:00         ? >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-08 21:33:36 +00:00      3:10 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] [0m
[INFO] [stdout] 
[INFO] [stdout] └── var as str: 2019-06-05 08:32:10 +00:00      1:09 Sync
[INFO] [stdout] 2019-06-05 11:26:54 +00:00      5:56 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-06 02:11:48 +00:00        26 >>> kde-apps/libktnef-19.04.1
[INFO] [stdout] 2019-06-06 02:16:01 +00:00        34 >>> net-misc/chrony-3.3
[INFO] [stdout] 2019-06-05 10:18:28 +00:00         ? Sync
[INFO] [stdout] 2019-06-05 10:21:02 +00:00         ? >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-08 21:33:36 +00:00      3:10 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] command=`"/opt/rustwide/target/x86_64-unknown-linux-gnu/debug/emlop" "-F" "test/emerge.negtime.log" "l" "-sms"`
[INFO] [stdout] stdin=``
[INFO] [stdout] code=0
[INFO] [stdout] stdout=```2019-06-05 08:32:10 +00:00      1:09 Sync
[INFO] [stdout] 2019-06-05 11:26:54 +00:00      5:56 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-06 02:11:48 +00:00        26 >>> kde-apps/libktnef-19.04.1
[INFO] [stdout] 2019-06-06 02:16:01 +00:00        34 >>> net-misc/chrony-3.3
[INFO] [stdout] 2019-06-05 10:18:28 +00:00         ? Sync
[INFO] [stdout] 2019-06-05 10:21:02 +00:00         ? >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] 2019-06-08 21:33:36 +00:00      3:10 >>> kde-plasma/kwin-5.15.5
[INFO] [stdout] ```
[INFO] [stdout] stderr=``````
[INFO] [stdout] ', /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/assert_cmd-1.0.1/src/assert.rs:342:17
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::libunwind::trace::h577ea05e9ca4629a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/libunwind.rs:96
[INFO] [stdout]    1:     0x55f45d5e1a20 - std::backtrace_rs::backtrace::trace_unsynchronized::h50b9b72b84c7dd56
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/../../backtrace/src/backtrace/mod.rs:66
[INFO] [stdout]    2:     0x55f45d5e1a20 - std::sys_common::backtrace::_print_fmt::h6541cf9823837fac
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:79
[INFO] [stdout]    3:     0x55f45d5e1a20 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hf64fbff071026df5
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:58
[INFO] [stdout]    4:     0x55f45d6078cc - core::fmt::write::h9ddafa4860d8adff
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/fmt/mod.rs:1082
[INFO] [stdout]    5:     0x55f45d26e155 - std::io::Write::write_fmt::h50a71e2460ce07da
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/mod.rs:1514
[INFO] [stdout]    6:     0x55f45d5dc2e1 - std::io::impls::<impl std::io::Write for alloc::boxed::Box<W>>::write_fmt::h9bbd3fed8160d0d2
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/io/impls.rs:176
[INFO] [stdout]    7:     0x55f45d5e3ea0 - std::sys_common::backtrace::_print::ha25f9ff5080d886d
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:61
[INFO] [stdout]    8:     0x55f45d5e3ea0 - std::sys_common::backtrace::print::h213e8aa8dc5405c0
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:48
[INFO] [stdout]    9:     0x55f45d5e3ea0 - std::panicking::default_hook::{{closure}}::h6482fae49ef9d963
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:200
[INFO] [stdout]   10:     0x55f45d5e3b96 - std::panicking::default_hook::he30ad7589e0970f9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:216
[INFO] [stdout]   11:     0x55f45d5e4503 - std::panicking::rust_panic_with_hook::haa1ed36ada4ffb03
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:569
[INFO] [stdout]   12:     0x55f45d5e40d9 - std::panicking::begin_panic_handler::{{closure}}::h7001af1bb21aeaeb
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:476
[INFO] [stdout]   13:     0x55f45d5e1eac - std::sys_common::backtrace::__rust_end_short_backtrace::h39910f557f5f2367
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:153
[INFO] [stdout]   14:     0x55f45d5e4099 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:475
[INFO] [stdout]   15:     0x55f45d5e404b - std::panicking::begin_panic_fmt::hc1b1320af38b7e12
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:429
[INFO] [stdout]   16:     0x55f45d53d3cb - assert_cmd::assert::Assert::stdout_impl::h9564e0200ccb7fcc
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/assert_cmd-1.0.1/src/assert.rs:342
[INFO] [stdout]   17:     0x55f45d257c50 - assert_cmd::assert::Assert::stdout::h860600a1c00183f6
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/assert_cmd-1.0.1/src/assert.rs:335
[INFO] [stdout]   18:     0x55f45d21b9ed - emlop::commands::tests::negative_merge_time::hde70f1972a75ddb3
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands.rs:772
[INFO] [stdout]   19:     0x55f45d21b13a - emlop::commands::tests::negative_merge_time::{{closure}}::h9647d167872d16ca
[INFO] [stdout]                                at /opt/rustwide/workdir/src/commands.rs:745
[INFO] [stdout]   20:     0x55f45d207bae - core::ops::function::FnOnce::call_once::h0ecdeecf3496809e
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   21:     0x55f45d296a33 - core::ops::function::FnOnce::call_once::hd04d57b5cf16f85a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   22:     0x55f45d296a33 - test::__rust_begin_short_backtrace::hd8e9a8c5a2e43388
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:517
[INFO] [stdout]   23:     0x55f45d294f03 - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h3c3db8a500c1a19a
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   24:     0x55f45d294f03 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h043e9ebcbe360c80
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   25:     0x55f45d294f03 - std::panicking::try::do_call::h47c2fb275506c2e8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   26:     0x55f45d294f03 - std::panicking::try::h569c20f0e99453b4
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   27:     0x55f45d294f03 - std::panic::catch_unwind::hb33c6d0f01540d23
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   28:     0x55f45d294f03 - test::run_test_in_process::hc0ce562d167e51cf
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:544
[INFO] [stdout]   29:     0x55f45d294f03 - test::run_test::run_test_inner::{{closure}}::hd3120982fcd71544
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/test/src/lib.rs:450
[INFO] [stdout]   30:     0x55f45d26d586 - std::sys_common::backtrace::__rust_begin_short_backtrace::h84f8bc12cc4cdac6
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys_common/backtrace.rs:137
[INFO] [stdout]   31:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hb8b5fa0ba425d4db
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:458
[INFO] [stdout]   32:     0x55f45d2727b5 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hc4b84b244cf55f75
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:308
[INFO] [stdout]   33:     0x55f45d2727b5 - std::panicking::try::do_call::ha31922ad8fa17753
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:373
[INFO] [stdout]   34:     0x55f45d2727b5 - std::panicking::try::h38a6da203722ae6e
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panicking.rs:337
[INFO] [stdout]   35:     0x55f45d2727b5 - std::panic::catch_unwind::h83afce167c9062b9
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/panic.rs:379
[INFO] [stdout]   36:     0x55f45d2727b5 - std::thread::Builder::spawn_unchecked::{{closure}}::h160689155590a70c
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/thread/mod.rs:457
[INFO] [stdout]   37:     0x55f45d2727b5 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7dbf17a455132a46
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/core/src/ops/function.rs:227
[INFO] [stdout]   38:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h670c50864ac2cb92
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   39:     0x55f45d5eb5aa - <alloc::boxed::Box<F> as core::ops::function::FnOnce<A>>::call_once::h2511952749086d81
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/alloc/src/boxed.rs:1042
[INFO] [stdout]   40:     0x55f45d5eb5aa - std::sys::unix::thread::Thread::new::thread_start::h5ad4ddffe24373a8
[INFO] [stdout]                                at /rustc/18bf6b4f01a6feaf7259ba7cdae58031af1b7b39/library/std/src/sys/unix/thread.rs:87
[INFO] [stdout]   41:     0x7fc1d6227609 - start_thread
[INFO] [stdout]   42:     0x7fc1d6133103 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     commands::tests::negative_merge_time
[INFO] [stdout]     commands::tests::predict_tty
[INFO] [stdout]     proces::tests::start_time
[INFO] [stdout]     tests::date
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 18 passed; 4 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4829cedabba0b02facd6869aeb994795d2319340d64fe2ae99f11eb2121f69c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4829cedabba0b02facd6869aeb994795d2319340d64fe2ae99f11eb2121f69c5", kill_on_drop: false }`
[INFO] [stdout] 4829cedabba0b02facd6869aeb994795d2319340d64fe2ae99f11eb2121f69c5
