[INFO] fetching crate forester-rs 0.4.1...
[INFO] testing forester-rs-0.4.1 against nightly-2024-10-28+rustflags=-Zrandomize-layout for randomize-layout
[INFO] extracting crate forester-rs 0.4.1 into /workspace/builds/worker-0-tc2/source
[INFO] validating manifest of crates.io crate forester-rs 0.4.1 on toolchain nightly-2024-10-28
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+nightly-2024-10-28" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate forester-rs 0.4.1
[INFO] finished tweaking crates.io crate forester-rs 0.4.1
[INFO] tweaked toml for crates.io crate forester-rs 0.4.1 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+nightly-2024-10-28" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]      Locking 231 packages to latest compatible versions
[INFO] [stderr]       Adding axum v0.6.20 (available: v0.7.7)
[INFO] [stderr]       Adding hyper v0.14.31 (available: v1.5.0)
[INFO] [stderr]       Adding itertools v0.12.1 (available: v0.13.0)
[INFO] [stderr]       Adding logos v0.13.0 (available: v0.14.2)
[INFO] [stderr]       Adding quick-xml v0.31.0 (available: v0.37.0)
[INFO] [stderr]       Adding reqwest v0.11.27 (available: v0.12.9)
[INFO] [stderr]       Adding tungstenite v0.21.0 (available: v0.24.0)
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+nightly-2024-10-28" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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:4a844ea9eb2546a2d2c7022eacef16ef2e8229c7fbb2c7d4d55a9ceca922f72d" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2024-10-28" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c60525c5e86053e580ef87d6b65b5a22d3b4987cba45a31779f5bd27b25b1e24
[INFO] running `Command { std: "docker" "start" "-a" "c60525c5e86053e580ef87d6b65b5a22d3b4987cba45a31779f5bd27b25b1e24", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c60525c5e86053e580ef87d6b65b5a22d3b4987cba45a31779f5bd27b25b1e24", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c60525c5e86053e580ef87d6b65b5a22d3b4987cba45a31779f5bd27b25b1e24", kill_on_drop: false }`
[INFO] [stdout] c60525c5e86053e580ef87d6b65b5a22d3b4987cba45a31779f5bd27b25b1e24
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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 -Zrandomize-layout" "-e" "RUSTDOCFLAGS=--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:4a844ea9eb2546a2d2c7022eacef16ef2e8229c7fbb2c7d4d55a9ceca922f72d" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2024-10-28" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 6295f237a7424bc594476b6c4308e22d26db2af1b4102d32e3368bc2fa785c43
[INFO] running `Command { std: "docker" "start" "-a" "6295f237a7424bc594476b6c4308e22d26db2af1b4102d32e3368bc2fa785c43", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.161
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling bytes v1.8.0
[INFO] [stderr]    Compiling itoa v1.0.11
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling vcpkg v0.2.15
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling tokio-macros v2.4.0
[INFO] [stderr]    Compiling rustversion v1.0.18
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling serde v1.0.214
[INFO] [stderr]    Compiling bitflags v2.6.0
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling http v0.2.12
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling tracing-attributes v0.1.27
[INFO] [stderr]    Compiling openssl-sys v0.9.104
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling httparse v1.9.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling mio v1.0.2
[INFO] [stderr]    Compiling signal-hook-registry v1.4.2
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling socket2 v0.5.7
[INFO] [stderr]    Compiling hashbrown v0.15.0
[INFO] [stderr]    Compiling zerocopy-derive v0.7.35
[INFO] [stderr]    Compiling tokio v1.41.0
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling tracing v0.1.40
[INFO] [stderr]    Compiling foreign-types-shared v0.1.1
[INFO] [stderr]    Compiling tower-service v0.3.3
[INFO] [stderr]    Compiling openssl v0.10.68
[INFO] [stderr]    Compiling indexmap v2.6.0
[INFO] [stderr]    Compiling foreign-types v0.3.2
[INFO] [stderr]    Compiling http-body v0.4.6
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling native-tls v0.2.12
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling try-lock v0.2.5
[INFO] [stderr]    Compiling ryu v1.0.18
[INFO] [stderr]    Compiling want v0.3.1
[INFO] [stderr]    Compiling pest v2.7.14
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling beef v0.5.2
[INFO] [stderr]    Compiling rustix v0.38.38
[INFO] [stderr]    Compiling openssl-probe v0.1.5
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling regex-syntax v0.6.29
[INFO] [stderr]    Compiling dot-structures v0.1.1
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling serde_json v1.0.132
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling regex-automata v0.4.8
[INFO] [stderr]    Compiling pest_meta v2.7.14
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling axum-core v0.3.4
[INFO] [stderr]    Compiling pin-project-internal v1.1.7
[INFO] [stderr]    Compiling logos-codegen v0.13.0
[INFO] [stderr]    Compiling tokio-util v0.7.12
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling linux-raw-sys v0.4.14
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling mime v0.3.17
[INFO] [stderr]    Compiling h2 v0.3.26
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling pin-project v1.1.7
[INFO] [stderr]    Compiling logos-derive v0.13.0
[INFO] [stderr]    Compiling pest_generator v2.7.14
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling into-attr v0.1.1
[INFO] [stderr]    Compiling dot-generator v0.2.0
[INFO] [stderr]    Compiling axum v0.6.20
[INFO] [stderr]    Compiling async-trait v0.1.83
[INFO] [stderr]    Compiling fastrand v2.1.1
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling sync_wrapper v0.1.2
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling cpufeatures v0.2.14
[INFO] [stderr]    Compiling anstyle v1.0.9
[INFO] [stderr]    Compiling anstyle-query v1.1.2
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling anstream v0.6.17
[INFO] [stderr]    Compiling rustls-pemfile v1.0.4
[INFO] [stderr]    Compiling tempfile v3.13.0
[INFO] [stderr]    Compiling pest_derive v2.7.14
[INFO] [stderr]    Compiling logos v0.13.0
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling env_filter v0.1.2
[INFO] [stderr]    Compiling serde_path_to_error v0.1.16
[INFO] [stderr]    Compiling http v1.1.0
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling ipnet v2.10.1
[INFO] [stderr]    Compiling either v1.13.0
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling hyper v0.14.31
[INFO] [stderr]    Compiling iana-time-zone v0.1.61
[INFO] [stderr]    Compiling into-attr-derive v0.2.1
[INFO] [stderr]    Compiling matchit v0.7.3
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling utf-8 v0.7.6
[INFO] [stderr]    Compiling graphviz-rust v0.9.3
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling tungstenite v0.21.0
[INFO] [stderr]    Compiling chrono v0.4.38
[INFO] [stderr]    Compiling env_logger v0.11.5
[INFO] [stderr]    Compiling itertools v0.12.1
[INFO] [stderr]    Compiling strum_macros v0.26.4
[INFO] [stderr]    Compiling parsit v0.2.0
[INFO] [stderr]    Compiling quick-xml v0.31.0
[INFO] [stderr]    Compiling strum v0.26.3
[INFO] [stderr]    Compiling hyper-tls v0.5.0
[INFO] [stderr]    Compiling reqwest v0.11.27
[INFO] [stderr]    Compiling forester-rs v0.4.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `format`
[INFO] [stdout]   --> src/runtime/args.rs:17:16
[INFO] [stdout]    |
[INFO] [stdout] 17 | use std::fmt::{format, Display, Formatter};
[INFO] [stdout]    |                ^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `super::rtree::rnode::RNode`
[INFO] [stdout]   --> src/runtime/context.rs:16:5
[INFO] [stdout]    |
[INFO] [stdout] 16 | use super::rtree::rnode::RNode;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `get` is never used
[INFO] [stdout]    --> src/runtime/builder.rs:488:8
[INFO] [stdout]     |
[INFO] [stdout] 481 | impl ServerPort {
[INFO] [stdout]     | --------------- method in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 488 |     fn get(&self) -> u16 {
[INFO] [stdout]     |        ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `client` is never read
[INFO] [stdout]   --> src/runtime/forester/serv.rs:37:5
[INFO] [stdout]    |
[INFO] [stdout] 34 | pub struct HttpServ {
[INFO] [stdout]    |            -------- field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 37 |     client: Client<HttpConnector, Body>,
[INFO] [stdout]    |     ^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `HttpServ` has a derived impl for the trait `Clone`, but this is intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `buf_size` is never read
[INFO] [stdout]    --> src/runtime/ros.rs:142:5
[INFO] [stdout]     |
[INFO] [stdout] 140 | pub struct TargetCfg {
[INFO] [stdout]     |            --------- field in this struct
[INFO] [stdout] 141 |     tp: String,
[INFO] [stdout] 142 |     buf_size: Option<usize>,
[INFO] [stdout]     |     ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `TargetCfg` has a derived impl for the trait `Debug`, but this is intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: fields `tp` and `msg` are never read
[INFO] [stdout]    --> src/runtime/ros.rs:166:5
[INFO] [stdout]     |
[INFO] [stdout] 165 | pub struct ForesterRosMessage {
[INFO] [stdout]     |            ------------------ fields in this struct
[INFO] [stdout] 166 |     tp: String,
[INFO] [stdout]     |     ^^
[INFO] [stdout] 167 |     msg: String,
[INFO] [stdout]     |     ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `ForesterRosMessage` has derived impls for the traits `Debug` and `Clone`, but these are intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 15s
[INFO] running `Command { std: "docker" "inspect" "6295f237a7424bc594476b6c4308e22d26db2af1b4102d32e3368bc2fa785c43", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6295f237a7424bc594476b6c4308e22d26db2af1b4102d32e3368bc2fa785c43", kill_on_drop: false }`
[INFO] [stdout] 6295f237a7424bc594476b6c4308e22d26db2af1b4102d32e3368bc2fa785c43
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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 -Zrandomize-layout" "-e" "RUSTDOCFLAGS=--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:4a844ea9eb2546a2d2c7022eacef16ef2e8229c7fbb2c7d4d55a9ceca922f72d" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2024-10-28" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] be42decb5d9ce78ea25ff6d6677738a934f2dca583b1d8f6c25572d4575264bc
[INFO] running `Command { std: "docker" "start" "-a" "be42decb5d9ce78ea25ff6d6677738a934f2dca583b1d8f6c25572d4575264bc", kill_on_drop: false }`
[INFO] [stderr]    Compiling smallvec v1.13.2
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling num_cpus v1.16.0
[INFO] [stderr]    Compiling deadpool-runtime v0.1.4
[INFO] [stderr]    Compiling assert-json-diff v2.0.2
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling tokio v1.41.0
[INFO] [stderr]    Compiling regex-automata v0.4.8
[INFO] [stderr]    Compiling axum-core v0.3.4
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling http-body-util v0.1.2
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling env_filter v0.1.2
[INFO] [stderr]    Compiling tokio-util v0.7.12
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling deadpool v0.10.0
[INFO] [stderr]    Compiling env_logger v0.11.5
[INFO] [stderr]    Compiling h2 v0.3.26
[INFO] [stderr]    Compiling h2 v0.4.6
[INFO] [stderr]    Compiling hyper v0.14.31
[INFO] [stderr]    Compiling hyper v1.5.0
[INFO] [stderr]    Compiling hyper-util v0.1.10
[INFO] [stderr]    Compiling wiremock v0.6.2
[INFO] [stderr]    Compiling hyper-tls v0.5.0
[INFO] [stderr]    Compiling axum v0.6.20
[INFO] [stderr]    Compiling reqwest v0.11.27
[INFO] [stderr]    Compiling forester-http v0.1.0
[INFO] [stderr]    Compiling forester-rs v0.4.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `format`
[INFO] [stdout]   --> src/runtime/args.rs:17:16
[INFO] [stdout]    |
[INFO] [stdout] 17 | use std::fmt::{format, Display, Formatter};
[INFO] [stdout]    |                ^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `super::rtree::rnode::RNode`
[INFO] [stdout]   --> src/runtime/context.rs:16:5
[INFO] [stdout]    |
[INFO] [stdout] 16 | use super::rtree::rnode::RNode;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `format`
[INFO] [stdout]   --> src/runtime/args.rs:17:16
[INFO] [stdout]    |
[INFO] [stdout] 17 | use std::fmt::{format, Display, Formatter};
[INFO] [stdout]    |                ^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `super::rtree::rnode::RNode`
[INFO] [stdout]   --> src/runtime/context.rs:16:5
[INFO] [stdout]    |
[INFO] [stdout] 16 | use super::rtree::rnode::RNode;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `crate::tests::turn_on_logs`
[INFO] [stdout]    --> src/runtime/rtree.rs:289:9
[INFO] [stdout]     |
[INFO] [stdout] 289 |     use crate::tests::turn_on_logs;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `Impl`
[INFO] [stdout]  --> src/tests/actions_re.rs:2:30
[INFO] [stdout]   |
[INFO] [stdout] 2 | use crate::runtime::action::{Impl, ImplRemote};
[INFO] [stdout]   |                              ^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `Impl`
[INFO] [stdout]  --> src/tests/trimmer.rs:2:30
[INFO] [stdout]   |
[INFO] [stdout] 2 | use crate::runtime::action::{Impl};
[INFO] [stdout]   |                              ^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `ServiceExt`
[INFO] [stdout]   --> src/tests/simulator.rs:14:26
[INFO] [stdout]    |
[INFO] [stdout] 14 | use axum::{Json, Router, ServiceExt};
[INFO] [stdout]    |                          ^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `get` is never used
[INFO] [stdout]    --> src/runtime/builder.rs:488:8
[INFO] [stdout]     |
[INFO] [stdout] 481 | impl ServerPort {
[INFO] [stdout]     | --------------- method in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 488 |     fn get(&self) -> u16 {
[INFO] [stdout]     |        ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `client` is never read
[INFO] [stdout]   --> src/runtime/forester/serv.rs:37:5
[INFO] [stdout]    |
[INFO] [stdout] 34 | pub struct HttpServ {
[INFO] [stdout]    |            -------- field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 37 |     client: Client<HttpConnector, Body>,
[INFO] [stdout]    |     ^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `HttpServ` has a derived impl for the trait `Clone`, but this is intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `buf_size` is never read
[INFO] [stdout]    --> src/runtime/ros.rs:142:5
[INFO] [stdout]     |
[INFO] [stdout] 140 | pub struct TargetCfg {
[INFO] [stdout]     |            --------- field in this struct
[INFO] [stdout] 141 |     tp: String,
[INFO] [stdout] 142 |     buf_size: Option<usize>,
[INFO] [stdout]     |     ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `TargetCfg` has a derived impl for the trait `Debug`, but this is intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: fields `tp` and `msg` are never read
[INFO] [stdout]    --> src/runtime/ros.rs:166:5
[INFO] [stdout]     |
[INFO] [stdout] 165 | pub struct ForesterRosMessage {
[INFO] [stdout]     |            ------------------ fields in this struct
[INFO] [stdout] 166 |     tp: String,
[INFO] [stdout]     |     ^^
[INFO] [stdout] 167 |     msg: String,
[INFO] [stdout]     |     ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `ForesterRosMessage` has derived impls for the traits `Debug` and `Clone`, but these are intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: method `get` is never used
[INFO] [stdout]    --> src/runtime/builder.rs:488:8
[INFO] [stdout]     |
[INFO] [stdout] 481 | impl ServerPort {
[INFO] [stdout]     | --------------- method in this implementation
[INFO] [stdout] ...
[INFO] [stdout] 488 |     fn get(&self) -> u16 {
[INFO] [stdout]     |        ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `client` is never read
[INFO] [stdout]   --> src/runtime/forester/serv.rs:37:5
[INFO] [stdout]    |
[INFO] [stdout] 34 | pub struct HttpServ {
[INFO] [stdout]    |            -------- field in this struct
[INFO] [stdout] ...
[INFO] [stdout] 37 |     client: Client<HttpConnector, Body>,
[INFO] [stdout]    |     ^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `HttpServ` has a derived impl for the trait `Clone`, but this is intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: field `buf_size` is never read
[INFO] [stdout]    --> src/runtime/ros.rs:142:5
[INFO] [stdout]     |
[INFO] [stdout] 140 | pub struct TargetCfg {
[INFO] [stdout]     |            --------- field in this struct
[INFO] [stdout] 141 |     tp: String,
[INFO] [stdout] 142 |     buf_size: Option<usize>,
[INFO] [stdout]     |     ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `TargetCfg` has a derived impl for the trait `Debug`, but this is intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: fields `tp` and `msg` are never read
[INFO] [stdout]    --> src/runtime/ros.rs:166:5
[INFO] [stdout]     |
[INFO] [stdout] 165 | pub struct ForesterRosMessage {
[INFO] [stdout]     |            ------------------ fields in this struct
[INFO] [stdout] 166 |     tp: String,
[INFO] [stdout]     |     ^^
[INFO] [stdout] 167 |     msg: String,
[INFO] [stdout]     |     ^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `ForesterRosMessage` has derived impls for the traits `Debug` and `Clone`, but these are intentionally ignored during dead code analysis
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: struct `SmokeTest` is never constructed
[INFO] [stdout]   --> src/tests/trimmer.rs:92:8
[INFO] [stdout]    |
[INFO] [stdout] 92 | struct SmokeTest;
[INFO] [stdout]    |        ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused `Result` that must be used
[INFO] [stdout]   --> src/tests/trimmer.rs:32:5
[INFO] [stdout]    |
[INFO] [stdout] 32 |     forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stdout]    = note: `#[warn(unused_must_use)]` on by default
[INFO] [stdout] help: use `let _ = ...` to ignore the resulting value
[INFO] [stdout]    |
[INFO] [stdout] 32 |     let _ = forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stdout]    |     +++++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused `Result` that must be used
[INFO] [stdout]   --> src/tests/trimmer.rs:87:5
[INFO] [stdout]    |
[INFO] [stdout] 87 |     forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stdout] help: use `let _ = ...` to ignore the resulting value
[INFO] [stdout]    |
[INFO] [stdout] 87 |     let _ = forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stdout]    |     +++++++
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 08s
[INFO] running `Command { std: "docker" "inspect" "be42decb5d9ce78ea25ff6d6677738a934f2dca583b1d8f6c25572d4575264bc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "be42decb5d9ce78ea25ff6d6677738a934f2dca583b1d8f6c25572d4575264bc", kill_on_drop: false }`
[INFO] [stdout] be42decb5d9ce78ea25ff6d6677738a934f2dca583b1d8f6c25572d4575264bc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/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 -Zrandomize-layout" "-e" "RUSTDOCFLAGS=--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:4a844ea9eb2546a2d2c7022eacef16ef2e8229c7fbb2c7d4d55a9ceca922f72d" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2024-10-28" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1df7ea2b4fbb2f3a0b26d46307cc037adcaad62237924aaa8267c927799272ac
[INFO] running `Command { std: "docker" "start" "-a" "1df7ea2b4fbb2f3a0b26d46307cc037adcaad62237924aaa8267c927799272ac", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `format`
[INFO] [stderr]   --> src/runtime/args.rs:17:16
[INFO] [stderr]    |
[INFO] [stderr] 17 | use std::fmt::{format, Display, Formatter};
[INFO] [stderr]    |                ^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_imports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `super::rtree::rnode::RNode`
[INFO] [stderr]   --> src/runtime/context.rs:16:5
[INFO] [stderr]    |
[INFO] [stderr] 16 | use super::rtree::rnode::RNode;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: method `get` is never used
[INFO] [stderr]    --> src/runtime/builder.rs:488:8
[INFO] [stderr]     |
[INFO] [stderr] 481 | impl ServerPort {
[INFO] [stderr]     | --------------- method in this implementation
[INFO] [stderr] ...
[INFO] [stderr] 488 |     fn get(&self) -> u16 {
[INFO] [stderr]     |        ^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: field `client` is never read
[INFO] [stderr]   --> src/runtime/forester/serv.rs:37:5
[INFO] [stderr]    |
[INFO] [stderr] 34 | pub struct HttpServ {
[INFO] [stderr]    |            -------- field in this struct
[INFO] [stderr] ...
[INFO] [stderr] 37 |     client: Client<HttpConnector, Body>,
[INFO] [stderr]    |     ^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `HttpServ` has a derived impl for the trait `Clone`, but this is intentionally ignored during dead code analysis
[INFO] [stderr] 
[INFO] [stderr] warning: field `buf_size` is never read
[INFO] [stderr]    --> src/runtime/ros.rs:142:5
[INFO] [stderr]     |
[INFO] [stderr] 140 | pub struct TargetCfg {
[INFO] [stderr]     |            --------- field in this struct
[INFO] [stderr] 141 |     tp: String,
[INFO] [stderr] 142 |     buf_size: Option<usize>,
[INFO] [stderr]     |     ^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `TargetCfg` has a derived impl for the trait `Debug`, but this is intentionally ignored during dead code analysis
[INFO] [stderr] 
[INFO] [stderr] warning: fields `tp` and `msg` are never read
[INFO] [stderr]    --> src/runtime/ros.rs:166:5
[INFO] [stderr]     |
[INFO] [stderr] 165 | pub struct ForesterRosMessage {
[INFO] [stderr]     |            ------------------ fields in this struct
[INFO] [stderr] 166 |     tp: String,
[INFO] [stderr]     |     ^^
[INFO] [stderr] 167 |     msg: String,
[INFO] [stderr]     |     ^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `ForesterRosMessage` has derived impls for the traits `Debug` and `Clone`, but these are intentionally ignored during dead code analysis
[INFO] [stderr] 
[INFO] [stderr] warning: `forester-rs` (lib) generated 6 warnings (run `cargo fix --lib -p forester-rs` to apply 2 suggestions)
[INFO] [stderr] warning: unused import: `crate::tests::turn_on_logs`
[INFO] [stderr]    --> src/runtime/rtree.rs:289:9
[INFO] [stderr]     |
[INFO] [stderr] 289 |     use crate::tests::turn_on_logs;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `Impl`
[INFO] [stderr]  --> src/tests/actions_re.rs:2:30
[INFO] [stderr]   |
[INFO] [stderr] 2 | use crate::runtime::action::{Impl, ImplRemote};
[INFO] [stderr]   |                              ^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `Impl`
[INFO] [stderr]  --> src/tests/trimmer.rs:2:30
[INFO] [stderr]   |
[INFO] [stderr] 2 | use crate::runtime::action::{Impl};
[INFO] [stderr]   |                              ^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `ServiceExt`
[INFO] [stderr]   --> src/tests/simulator.rs:14:26
[INFO] [stderr]    |
[INFO] [stderr] 14 | use axum::{Json, Router, ServiceExt};
[INFO] [stderr]    |                          ^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: struct `SmokeTest` is never constructed
[INFO] [stderr]   --> src/tests/trimmer.rs:92:8
[INFO] [stderr]    |
[INFO] [stderr] 92 | struct SmokeTest;
[INFO] [stderr]    |        ^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused `Result` that must be used
[INFO] [stderr]   --> src/tests/trimmer.rs:32:5
[INFO] [stderr]    |
[INFO] [stderr] 32 |     forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stderr]    = note: `#[warn(unused_must_use)]` on by default
[INFO] [stderr] help: use `let _ = ...` to ignore the resulting value
[INFO] [stderr]    |
[INFO] [stderr] 32 |     let _ = forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stderr]    |     +++++++
[INFO] [stderr] 
[INFO] [stderr] warning: unused `Result` that must be used
[INFO] [stderr]   --> src/tests/trimmer.rs:87:5
[INFO] [stderr]    |
[INFO] [stderr] 87 |     forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
[INFO] [stderr] help: use `let _ = ...` to ignore the resulting value
[INFO] [stderr]    |
[INFO] [stderr] 87 |     let _ = forester.add_trim_task(TrimTask::rt_tree(Test));
[INFO] [stderr]    |     +++++++
[INFO] [stderr] 
[INFO] [stderr] warning: `forester-rs` (lib test) generated 13 warnings (6 duplicates) (run `cargo fix --lib -p forester-rs --tests` to apply 1 suggestion)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.44s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/forester_rs-4f3e8be2c175c8d2)
[INFO] [stdout] 
[INFO] [stdout] running 106 tests
[INFO] [stdout] test runtime::action::builtin::http::tests::smoke ... ignored
[INFO] [stdout] test runtime::ros::client::tests::smoke ... ignored
[INFO] [stdout] test runtime::rtree::builder::tests::smoke ... ok
[INFO] [stdout] test runtime::rtree::iter::tests::smoke ... ok
[INFO] [stdout] test runtime::rtree::macros::tests::arg ... ok
[INFO] [stdout] test runtime::rtree::tests::decorator_lambda ... ok
[INFO] [stdout] test runtime::rtree::analyzer::tests::find_and_parent ... ok
[INFO] [stdout] test runtime::rtree::tests::params ... ok
[INFO] [stdout] test runtime::builder::ros_nav::tests::print ... ok
[INFO] [stdout] test tests::actions::builtin_actions ... ok
[INFO] [stdout] test runtime::rtree::tests::params2 ... ok
[INFO] [stdout] test runtime::rtree::tests::smoke ... ok
[INFO] [stdout] test runtime::action::builtin::data::tests::lock_unlock ... ok
[INFO] [stdout] test tests::actions::mix_test ... ignored
[INFO] [stdout] test simulator::tests::smoke_all_success ... ok
[INFO] [stdout] test runtime::action::builtin::data::tests::store_tick ... ok
[INFO] [stdout] test tests::actions::builtin_test ... ok
[INFO] [stdout] test tests::actions::lock_unlock ... ok
[INFO] [stdout] test simulator::actions::tests::smoke ... ok
[INFO] [stdout] test tests::actions::sync_action_halt_ok ... ok
[INFO] [stdout] test tests::actions::sync_action_halt_err ... ok
[INFO] [stdout] test tests::args::inter_args_decorator ... ok
[INFO] [stdout] test tests::args::inter_args_ho ... ok
[INFO] [stdout] test tests::args::inter_args ... ok
[INFO] [stdout] test tests::args::inter_args_lambda ... ok
[INFO] [stdout] test tests::args::inter_args_pointers ... ok
[INFO] [stdout] test tests::args::inter_args_func ... ok
[INFO] [stdout] test tests::actions_re::remote_smoke ... ok
[INFO] [stdout] test tests::actions_re::remote_in_tree ... ok
[INFO] [stdout] test runtime::action::builtin::remote::tests::smoke ... ok
[INFO] [stdout] test tests::decorator::repeat_reactive ... ok
[INFO] [stdout] test tests::args::pointers ... ok
[INFO] [stdout] test tests::decorator::repeat_failure ... ok
[INFO] [stdout] test tests::decorator::repeat_repeat ... ok
[INFO] [stdout] test tests::decorator::retry_retry ... ok
[INFO] [stdout] test tests::decorator::simple_inverter ... ok
[INFO] [stdout] test tests::decorator::simple_retry ... ok
[INFO] [stdout] test tests::decorator::simple_repeat ... ok
[INFO] [stdout] test tests::flow::fallback ... ok
[INFO] [stdout] test tests::flow::fallback_handles_halt ... ok
[INFO] [stdout] test tests::flow::fallback_reset_after_running_failure ... ok
[INFO] [stdout] test tests::flow::fallback_reset_after_running_success ... ok
[INFO] [stdout] test tests::flow::mseq_restart_all_children ... ok
[INFO] [stdout] test tests::flow::fallback_retry ... ok
[INFO] [stdout] test tests::flow::parallel_simple ... ok
[INFO] [stdout] test tests::flow::parallel_simple_w_retry ... ok
[INFO] [stdout] test tests::flow::r_fallback_halt_on_interrupt ... ok
[INFO] [stdout] test tests::actions::builtin_http_get ... FAILED
[INFO] [stdout] test tests::flow::r_fallback_halted_by_running ... ok
[INFO] [stdout] test tests::daemons::built_in ... ok
[INFO] [stdout] test tests::flow::r_sequence_halt_on_interrupt ... ok
[INFO] [stdout] test tests::daemons::built_in_async ... ok
[INFO] [stdout] test tests::flow::sequence_reset_after_running_success ... ok
[INFO] [stdout] test tests::flow::r_sequence_halted_by_running ... ok
[INFO] [stdout] test tests::flow::sequence_restart_all_children ... ok
[INFO] [stdout] test tests::flow::simple_sequence ... ok
[INFO] [stdout] test tests::flow::sequence_handles_halt ... ok
[INFO] [stdout] test tests::nav::import_smoke ... ok
[INFO] [stdout] test tests::import::smoke ... ok
[INFO] [stdout] test tests::params::fail_types ... ok
[INFO] [stdout] test tests::parser::smoke ... ok
[INFO] [stdout] test tests::rtree::tests::ho_op ... ok
[INFO] [stdout] test tests::rtree::tests::ho_tree ... ok
[INFO] [stdout] test tests::params::any_type ... ok
[INFO] [stdout] test tests::rtree::tests::lambda_ho_op ... ok
[INFO] [stdout] test tests::rtree::tests::std_action ... ok
[INFO] [stdout] test tests::simulator::file ... ok
[INFO] [stdout] test tests::nav::export_smoke ... FAILED
[INFO] [stdout] test tests::simulator::smoke_remote ... ignored
[INFO] [stdout] test tests::simulator::text ... FAILED
[INFO] [stdout] test tests::simulator::smoke ... FAILED
[INFO] [stdout] test tests::flow::sequence_reset_after_running_failure ... ok
[INFO] [stdout] test tests::tracer::custom_state ... ok
[INFO] [stdout] test tests::tracer::file ... FAILED
[INFO] [stdout] test tests::vizualizer::manual ... ignored
[INFO] [stdout] test tracer::tests::smoke_mem ... ok
[INFO] [stdout] test tests::trimmer::naive ... FAILED
[INFO] [stdout] test tree::parser::lexer::tests::number ... ok
[INFO] [stdout] test tree::parser::lexer::tests::string ... ok
[INFO] [stdout] test tree::parser::tests::arguments::call_arg ... ok
[INFO] [stdout] test tree::parser::tests::arguments::call_arg_part ... ok
[INFO] [stdout] test tree::parser::tests::arguments::plain_arg ... ok
[INFO] [stdout] test tree::parser::tests::call::calls ... ok
[INFO] [stdout] test tree::parser::tests::call::capt_invocation ... ok
[INFO] [stdout] test tree::parser::tests::call::invocation ... ok
[INFO] [stdout] test tests::trimmer::smoke ... FAILED
[INFO] [stdout] test tree::parser::tests::definition::definition ... ok
[INFO] [stdout] test tree::parser::tests::call::lambda ... ok
[INFO] [stdout] test tree::parser::tests::definition::impl_definition ... ok
[INFO] [stdout] test tests::flow::sequence_running ... ok
[INFO] [stdout] test tree::parser::tests::call::decorator ... ok
[INFO] [stdout] test tree::parser::tests::definition::short_definition ... ok
[INFO] [stdout] test tree::parser::tests::import::import ... ok
[INFO] [stdout] test tree::parser::tests::import::import_names ... ok
[INFO] [stdout] test tree::parser::tests::file::file ... ok
[INFO] [stdout] test tree::parser::tests::file::short_description_file ... ok
[INFO] [stdout] test tree::parser::tests::import::import_names_alias ... ok
[INFO] [stdout] test tree::parser::tests::message::object ... ok
[INFO] [stdout] test tree::parser::tests::params::params ... ok
[INFO] [stdout] test tree::parser::tests::tests::script ... ok
[INFO] [stdout] test tree::parser::tests::message::simple_mess ... ok
[INFO] [stdout] test visualizer::tests::smoke ... ok
[INFO] [stdout] test tests::tracer::smoke ... ok
[INFO] [stdout] test tests::actions_re::smoke_serv ... ok
[INFO] [stdout] test tests::decorator::simple_delay ... ok
[INFO] [stdout] test tests::daemons::smoke ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::actions::builtin_http_get stdout ----
[INFO] [stdout] [loop] node = 2, tick = 32
[INFO] [stdout] [flow[ready]] tick:32, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:32, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 32
[INFO] [stdout] [flow[run]] tick:32, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:32, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 32
[INFO] [stdout] [leaf[run]] tick:32, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:32, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 32
[INFO] [stdout] [flow[run]] tick:32, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:32, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:32, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 32
[INFO] [stdout] [flow[ready]] tick:32, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:32, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 32
[INFO] [stdout] [flow[run]] tick:32, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:32, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:33
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 33
[INFO] [stdout] [flow[ready]] tick:33, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:33, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 33
[INFO] [stdout] [flow[run]] tick:33, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:33, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 33
[INFO] [stdout] [leaf[run]] tick:33, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:33, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 33
[INFO] [stdout] [flow[run]] tick:33, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:33, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:33, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 33
[INFO] [stdout] [flow[ready]] tick:33, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:33, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 33
[INFO] [stdout] [flow[run]] tick:33, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:33, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:34
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 34
[INFO] [stdout] [reqwest::blocking::wait] (ThreadId(102)) park without timeout
[INFO] [stdout] [reqwest::blocking::client] (ThreadId(128)) start runtime::block_on
[INFO] [stdout] [flow[ready]] tick:34, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:34, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 34
[INFO] [stdout] [flow[run]] tick:34, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:34, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 34
[INFO] [stdout] [leaf[run]] tick:34, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:34, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 34
[INFO] [stdout] [flow[run]] tick:34, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:34, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:34, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 34
[INFO] [stdout] [flow[ready]] tick:34, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:34, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 34
[INFO] [stdout] [flow[run]] tick:34, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:34, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:35
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 35
[INFO] [stdout] [flow[ready]] tick:35, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:35, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 35
[INFO] [stdout] [flow[run]] tick:35, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:35, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 35
[INFO] [stdout] [leaf[run]] tick:35, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:35, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 35
[INFO] [stdout] [flow[run]] tick:35, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:35, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:35, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 35
[INFO] [stdout] [flow[ready]] tick:35, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:35, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 35
[INFO] [stdout] [flow[run]] tick:35, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:35, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:36
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 36
[INFO] [stdout] [flow[ready]] tick:36, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:36, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 36
[INFO] [stdout] [flow[run]] tick:36, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:36, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 36
[INFO] [stdout] [leaf[run]] tick:36, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:36, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 36
[INFO] [stdout] [flow[run]] tick:36, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:36, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:36, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 36
[INFO] [stdout] [flow[ready]] tick:36, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:36, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 36
[INFO] [stdout] [flow[run]] tick:36, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:36, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:37
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 37
[INFO] [stdout] [flow[ready]] tick:37, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:37, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 37
[INFO] [stdout] [flow[run]] tick:37, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:37, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 37
[INFO] [stdout] [leaf[run]] tick:37, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:37, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 37
[INFO] [stdout] [flow[run]] tick:37, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:37, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:37, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 37
[INFO] [stdout] [flow[ready]] tick:37, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:37, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 37
[INFO] [stdout] [flow[run]] tick:37, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:37, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:38
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 38
[INFO] [stdout] [flow[ready]] tick:38, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:38, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 38
[INFO] [stdout] [flow[run]] tick:38, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:38, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 38
[INFO] [stdout] [leaf[run]] tick:38, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:38, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 38
[INFO] [stdout] [flow[run]] tick:38, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:38, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:38, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 38
[INFO] [stdout] [flow[ready]] tick:38, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:38, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 38
[INFO] [stdout] [flow[run]] tick:38, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:38, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:39
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 39
[INFO] [stdout] [flow[ready]] tick:39, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:39, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 39
[INFO] [stdout] [flow[run]] tick:39, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:39, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 39
[INFO] [stdout] [leaf[run]] tick:39, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:39, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 39
[INFO] [stdout] [flow[run]] tick:39, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:39, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:39, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 39
[INFO] [stdout] [flow[ready]] tick:39, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:39, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 39
[INFO] [stdout] [flow[run]] tick:39, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:39, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:40
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 40
[INFO] [stdout] [flow[ready]] tick:40, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:40, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 40
[INFO] [stdout] [flow[run]] tick:40, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:40, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 40
[INFO] [stdout] [leaf[run]] tick:40, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:40, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 40
[INFO] [stdout] [flow[run]] tick:40, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:40, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:40, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 40
[INFO] [stdout] [flow[ready]] tick:40, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:40, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 40
[INFO] [stdout] [flow[run]] tick:40, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:40, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:41
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 41
[INFO] [stdout] [flow[ready]] tick:41, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:41, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 41
[INFO] [stdout] [flow[run]] tick:41, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:41, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 41
[INFO] [stdout] [leaf[run]] tick:41, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:41, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 41
[INFO] [stdout] [flow[run]] tick:41, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:41, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:41, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 41
[INFO] [stdout] [flow[ready]] tick:41, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:41, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 41
[INFO] [stdout] [flow[run]] tick:41, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:41, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:42
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 42
[INFO] [stdout] [flow[ready]] tick:42, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:42, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 42
[INFO] [stdout] [flow[run]] tick:42, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:42, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 42
[INFO] [stdout] [leaf[run]] tick:42, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:42, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 42
[INFO] [stdout] [flow[run]] tick:42, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:42, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:42, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 42
[INFO] [stdout] [flow[ready]] tick:42, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:42, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 42
[INFO] [stdout] [flow[run]] tick:42, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:42, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:43
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 43
[INFO] [stdout] [flow[ready]] tick:43, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:43, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 43
[INFO] [stdout] [flow[run]] tick:43, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:43, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 43
[INFO] [stdout] [leaf[run]] tick:43, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:43, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 43
[INFO] [stdout] [flow[run]] tick:43, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:43, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:43, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 43
[INFO] [stdout] [flow[ready]] tick:43, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:43, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 43
[INFO] [stdout] [flow[run]] tick:43, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:43, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:44
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 44
[INFO] [stdout] [flow[ready]] tick:44, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:44, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 44
[INFO] [stdout] [flow[run]] tick:44, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:44, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 44
[INFO] [stdout] [leaf[run]] tick:44, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:44, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 44
[INFO] [stdout] [flow[run]] tick:44, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:44, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:44, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 44
[INFO] [stdout] [flow[ready]] tick:44, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:44, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 44
[INFO] [stdout] [flow[run]] tick:44, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:44, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:45
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 45
[INFO] [stdout] [flow[ready]] tick:45, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:45, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 45
[INFO] [stdout] [flow[run]] tick:45, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:45, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 45
[INFO] [stdout] [leaf[run]] tick:45, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:45, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 45
[INFO] [stdout] [flow[run]] tick:45, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:45, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:45, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 45
[INFO] [stdout] [flow[ready]] tick:45, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:45, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 45
[INFO] [stdout] [flow[run]] tick:45, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:45, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:46
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 46
[INFO] [stdout] [flow[ready]] tick:46, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:46, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 46
[INFO] [stdout] [flow[run]] tick:46, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:46, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 46
[INFO] [stdout] [leaf[run]] tick:46, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:46, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 46
[INFO] [stdout] [flow[run]] tick:46, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:46, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:46, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 46
[INFO] [stdout] [flow[ready]] tick:46, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:46, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 46
[INFO] [stdout] [flow[run]] tick:46, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:46, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:47
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 47
[INFO] [stdout] [flow[ready]] tick:47, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:47, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 47
[INFO] [stdout] [flow[run]] tick:47, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:47, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 47
[INFO] [stdout] [leaf[run]] tick:47, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:47, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 47
[INFO] [stdout] [flow[run]] tick:47, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:47, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:47, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 47
[INFO] [stdout] [flow[ready]] tick:47, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:47, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 47
[INFO] [stdout] [flow[run]] tick:47, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:47, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:48
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 48
[INFO] [stdout] [flow[ready]] tick:48, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:48, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 48
[INFO] [stdout] [flow[run]] tick:48, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:48, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 48
[INFO] [stdout] [leaf[run]] tick:48, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:48, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 48
[INFO] [stdout] [flow[run]] tick:48, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:48, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:48, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 48
[INFO] [stdout] [flow[ready]] tick:48, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:48, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 48
[INFO] [stdout] [flow[run]] tick:48, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:48, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:49
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 49
[INFO] [stdout] [flow[ready]] tick:49, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:49, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 49
[INFO] [stdout] [flow[run]] tick:49, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:49, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 49
[INFO] [stdout] [leaf[run]] tick:49, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:49, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 49
[INFO] [stdout] [flow[run]] tick:49, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:49, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:49, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 49
[INFO] [stdout] [flow[ready]] tick:49, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:49, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 49
[INFO] [stdout] [flow[run]] tick:49, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:49, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:50
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 50
[INFO] [stdout] [flow[ready]] tick:50, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:50, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 50
[INFO] [stdout] [flow[run]] tick:50, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:50, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 50
[INFO] [stdout] [leaf[run]] tick:50, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:50, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 50
[INFO] [stdout] [flow[run]] tick:50, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:50, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:50, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 50
[INFO] [stdout] [flow[ready]] tick:50, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:50, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 50
[INFO] [stdout] [flow[run]] tick:50, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:50, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:51
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 51
[INFO] [stdout] [flow[ready]] tick:51, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:51, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 51
[INFO] [stdout] [flow[run]] tick:51, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:51, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 51
[INFO] [stdout] [leaf[run]] tick:51, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:51, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 51
[INFO] [stdout] [flow[run]] tick:51, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:51, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:51, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 51
[INFO] [stdout] [flow[ready]] tick:51, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:51, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 51
[INFO] [stdout] [flow[run]] tick:51, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:51, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:52
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 52
[INFO] [stdout] [flow[ready]] tick:52, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:52, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 52
[INFO] [stdout] [flow[run]] tick:52, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:52, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 52
[INFO] [stdout] [leaf[run]] tick:52, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:52, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 52
[INFO] [stdout] [flow[run]] tick:52, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:52, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:52, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 52
[INFO] [stdout] [flow[ready]] tick:52, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:52, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 52
[INFO] [stdout] [flow[run]] tick:52, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:52, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:53
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 53
[INFO] [stdout] [flow[ready]] tick:53, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:53, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 53
[INFO] [stdout] [flow[run]] tick:53, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:53, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 53
[INFO] [stdout] [leaf[run]] tick:53, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:53, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 53
[INFO] [stdout] [flow[run]] tick:53, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:53, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:53, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 53
[INFO] [stdout] [flow[ready]] tick:53, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:53, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 53
[INFO] [stdout] [flow[run]] tick:53, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:53, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:54
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 54
[INFO] [stdout] [flow[ready]] tick:54, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:54, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 54
[INFO] [stdout] [flow[run]] tick:54, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:54, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 54
[INFO] [stdout] [leaf[run]] tick:54, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:54, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 54
[INFO] [stdout] [flow[run]] tick:54, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:54, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:54, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 54
[INFO] [stdout] [flow[ready]] tick:54, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:54, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 54
[INFO] [stdout] [flow[run]] tick:54, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:54, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:55
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 55
[INFO] [stdout] [flow[ready]] tick:55, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:55, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 55
[INFO] [stdout] [flow[run]] tick:55, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:55, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 55
[INFO] [stdout] [leaf[run]] tick:55, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:55, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 55
[INFO] [stdout] [flow[run]] tick:55, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:55, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:55, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 55
[INFO] [stdout] [flow[ready]] tick:55, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:55, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 55
[INFO] [stdout] [flow[run]] tick:55, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:55, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:56
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 56
[INFO] [stdout] [flow[ready]] tick:56, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:56, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 56
[INFO] [stdout] [flow[run]] tick:56, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:56, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 56
[INFO] [stdout] [leaf[run]] tick:56, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:56, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 56
[INFO] [stdout] [flow[run]] tick:56, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:56, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:56, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 56
[INFO] [stdout] [flow[ready]] tick:56, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:56, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 56
[INFO] [stdout] [flow[run]] tick:56, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:56, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:57
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 57
[INFO] [stdout] [flow[ready]] tick:57, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:57, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 57
[INFO] [stdout] [flow[run]] tick:57, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:57, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 57
[INFO] [stdout] [leaf[run]] tick:57, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:57, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 57
[INFO] [stdout] [flow[run]] tick:57, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:57, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:57, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 57
[INFO] [stdout] [flow[ready]] tick:57, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:57, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 57
[INFO] [stdout] [flow[run]] tick:57, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:57, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:58
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 58
[INFO] [stdout] [flow[ready]] tick:58, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:58, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 58
[INFO] [stdout] [flow[run]] tick:58, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:58, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 58
[INFO] [stdout] [leaf[run]] tick:58, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:58, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 58
[INFO] [stdout] [flow[run]] tick:58, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:58, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:58, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 58
[INFO] [stdout] [flow[ready]] tick:58, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:58, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 58
[INFO] [stdout] [flow[run]] tick:58, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:58, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:59
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 59
[INFO] [stdout] [flow[ready]] tick:59, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:59, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 59
[INFO] [stdout] [flow[run]] tick:59, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:59, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 59
[INFO] [stdout] [leaf[run]] tick:59, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:59, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 59
[INFO] [stdout] [flow[run]] tick:59, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:59, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:59, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 59
[INFO] [stdout] [flow[ready]] tick:59, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:59, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 59
[INFO] [stdout] [flow[run]] tick:59, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:59, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:60
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 60
[INFO] [stdout] [flow[ready]] tick:60, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:60, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 60
[INFO] [stdout] [flow[run]] tick:60, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:60, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 60
[INFO] [stdout] [leaf[run]] tick:60, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:60, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 60
[INFO] [stdout] [flow[run]] tick:60, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:60, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:60, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 60
[INFO] [stdout] [flow[ready]] tick:60, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:60, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 60
[INFO] [stdout] [flow[run]] tick:60, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:60, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:61
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 61
[INFO] [stdout] [flow[ready]] tick:61, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:61, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 61
[INFO] [stdout] [flow[run]] tick:61, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:61, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 61
[INFO] [stdout] [leaf[run]] tick:61, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:61, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 61
[INFO] [stdout] [flow[run]] tick:61, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:61, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:61, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 61
[INFO] [stdout] [flow[ready]] tick:61, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:61, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 61
[INFO] [stdout] [flow[run]] tick:61, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:61, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:62
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 62
[INFO] [stdout] [flow[ready]] tick:62, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:62, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 62
[INFO] [stdout] [flow[run]] tick:62, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:62, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 62
[INFO] [stdout] [leaf[run]] tick:62, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:62, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 62
[INFO] [stdout] [flow[run]] tick:62, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:62, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:62, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 62
[INFO] [stdout] [flow[ready]] tick:62, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:62, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 62
[INFO] [stdout] [flow[run]] tick:62, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:62, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:63
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 63
[INFO] [stdout] [flow[ready]] tick:63, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:63, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 63
[INFO] [stdout] [flow[run]] tick:63, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:63, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 63
[INFO] [stdout] [leaf[run]] tick:63, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:63, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 63
[INFO] [stdout] [flow[run]] tick:63, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:63, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:63, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 63
[INFO] [stdout] [flow[ready]] tick:63, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:63, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 63
[INFO] [stdout] [flow[run]] tick:63, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:63, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:64
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 64
[INFO] [stdout] [flow[ready]] tick:64, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:64, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 64
[INFO] [stdout] [flow[run]] tick:64, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:64, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 64
[INFO] [stdout] [leaf[run]] tick:64, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:64, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 64
[INFO] [stdout] [flow[run]] tick:64, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:64, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:64, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 64
[INFO] [stdout] [flow[ready]] tick:64, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:64, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 64
[INFO] [stdout] [flow[run]] tick:64, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:64, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:65
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 65
[INFO] [stdout] [flow[ready]] tick:65, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:65, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 65
[INFO] [stdout] [flow[run]] tick:65, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:65, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 65
[INFO] [stdout] [leaf[run]] tick:65, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:65, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 65
[INFO] [stdout] [flow[run]] tick:65, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:65, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:65, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 65
[INFO] [stdout] [flow[ready]] tick:65, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:65, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 65
[INFO] [stdout] [flow[run]] tick:65, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:65, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:66
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 66
[INFO] [stdout] [flow[ready]] tick:66, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:66, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 66
[INFO] [stdout] [flow[run]] tick:66, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:66, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 66
[INFO] [stdout] [leaf[run]] tick:66, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:66, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 66
[INFO] [stdout] [flow[run]] tick:66, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:66, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:66, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 66
[INFO] [stdout] [flow[ready]] tick:66, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:66, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 66
[INFO] [stdout] [flow[run]] tick:66, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:66, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:67
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 67
[INFO] [stdout] [flow[ready]] tick:67, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:67, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 67
[INFO] [stdout] [flow[run]] tick:67, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:67, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 67
[INFO] [stdout] [leaf[run]] tick:67, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:67, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 67
[INFO] [stdout] [flow[run]] tick:67, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:67, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:67, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 67
[INFO] [stdout] [flow[ready]] tick:67, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:67, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 67
[INFO] [stdout] [flow[run]] tick:67, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:67, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:68
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 68
[INFO] [stdout] [flow[ready]] tick:68, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:68, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 68
[INFO] [stdout] [flow[run]] tick:68, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:68, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 68
[INFO] [stdout] [leaf[run]] tick:68, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:68, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 68
[INFO] [stdout] [flow[run]] tick:68, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:68, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:68, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 68
[INFO] [stdout] [flow[ready]] tick:68, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:68, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 68
[INFO] [stdout] [flow[run]] tick:68, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:68, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:69
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 69
[INFO] [stdout] [flow[ready]] tick:69, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:69, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 69
[INFO] [stdout] [flow[run]] tick:69, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:69, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 69
[INFO] [stdout] [leaf[run]] tick:69, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:69, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 69
[INFO] [stdout] [flow[run]] tick:69, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:69, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:69, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 69
[INFO] [stdout] [flow[ready]] tick:69, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:69, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 69
[INFO] [stdout] [flow[run]] tick:69, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:69, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:70
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 70
[INFO] [stdout] [flow[ready]] tick:70, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:70, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 70
[INFO] [stdout] [flow[run]] tick:70, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:70, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 70
[INFO] [stdout] [leaf[run]] tick:70, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:70, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 70
[INFO] [stdout] [flow[run]] tick:70, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:70, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:70, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 70
[INFO] [stdout] [flow[ready]] tick:70, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:70, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 70
[INFO] [stdout] [flow[run]] tick:70, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:70, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:71
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 71
[INFO] [stdout] [flow[ready]] tick:71, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:71, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 71
[INFO] [stdout] [flow[run]] tick:71, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:71, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 71
[INFO] [stdout] [leaf[run]] tick:71, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:71, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 71
[INFO] [stdout] [flow[run]] tick:71, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:71, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:71, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 71
[INFO] [stdout] [flow[ready]] tick:71, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:71, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 71
[INFO] [stdout] [flow[run]] tick:71, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:71, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:72
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 72
[INFO] [stdout] [flow[ready]] tick:72, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:72, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 72
[INFO] [stdout] [flow[run]] tick:72, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:72, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 72
[INFO] [stdout] [leaf[run]] tick:72, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:72, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 72
[INFO] [stdout] [flow[run]] tick:72, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:72, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:72, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 72
[INFO] [stdout] [flow[ready]] tick:72, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:72, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 72
[INFO] [stdout] [flow[run]] tick:72, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:72, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:73
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 73
[INFO] [stdout] [flow[ready]] tick:73, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:73, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 73
[INFO] [stdout] [flow[run]] tick:73, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:73, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 73
[INFO] [stdout] [leaf[run]] tick:73, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:73, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 73
[INFO] [stdout] [flow[run]] tick:73, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:73, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:73, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 73
[INFO] [stdout] [flow[ready]] tick:73, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:73, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 73
[INFO] [stdout] [flow[run]] tick:73, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:73, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:74
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 74
[INFO] [stdout] [flow[ready]] tick:74, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:74, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 74
[INFO] [stdout] [flow[run]] tick:74, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:74, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 74
[INFO] [stdout] [leaf[run]] tick:74, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:74, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 74
[INFO] [stdout] [flow[run]] tick:74, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:74, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:74, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 74
[INFO] [stdout] [flow[ready]] tick:74, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:74, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 74
[INFO] [stdout] [flow[run]] tick:74, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:74, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:75
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 75
[INFO] [stdout] [flow[ready]] tick:75, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:75, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 75
[INFO] [stdout] [flow[run]] tick:75, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:75, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 75
[INFO] [stdout] [leaf[run]] tick:75, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:75, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 75
[INFO] [stdout] [flow[run]] tick:75, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:75, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:75, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 75
[INFO] [stdout] [flow[ready]] tick:75, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:75, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 75
[INFO] [stdout] [flow[run]] tick:75, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:75, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:76
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 76
[INFO] [stdout] [flow[ready]] tick:76, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:76, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 76
[INFO] [stdout] [flow[run]] tick:76, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:76, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 76
[INFO] [stdout] [leaf[run]] tick:76, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:76, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 76
[INFO] [stdout] [flow[run]] tick:76, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:76, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:76, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 76
[INFO] [stdout] [flow[ready]] tick:76, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:76, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 76
[INFO] [stdout] [flow[run]] tick:76, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:76, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:77
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 77
[INFO] [stdout] [flow[ready]] tick:77, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:77, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 77
[INFO] [stdout] [flow[run]] tick:77, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:77, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 77
[INFO] [stdout] [leaf[run]] tick:77, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:77, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 77
[INFO] [stdout] [flow[run]] tick:77, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:77, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:77, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 77
[INFO] [stdout] [flow[ready]] tick:77, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:77, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 77
[INFO] [stdout] [flow[run]] tick:77, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:77, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:78
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 78
[INFO] [stdout] [flow[ready]] tick:78, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:78, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 78
[INFO] [stdout] [flow[run]] tick:78, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:78, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 78
[INFO] [stdout] [leaf[run]] tick:78, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:78, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 78
[INFO] [stdout] [flow[run]] tick:78, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:78, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:78, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 78
[INFO] [stdout] [flow[ready]] tick:78, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:78, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 78
[INFO] [stdout] [flow[run]] tick:78, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:78, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:79
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 79
[INFO] [stdout] [flow[ready]] tick:79, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:79, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 79
[INFO] [stdout] [flow[run]] tick:79, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:79, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 79
[INFO] [stdout] [leaf[run]] tick:79, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:79, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 79
[INFO] [stdout] [flow[run]] tick:79, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:79, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:79, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 79
[INFO] [stdout] [flow[ready]] tick:79, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:79, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 79
[INFO] [stdout] [flow[run]] tick:79, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:79, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:80
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 80
[INFO] [stdout] [flow[ready]] tick:80, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:80, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 80
[INFO] [stdout] [flow[run]] tick:80, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:80, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 80
[INFO] [stdout] [leaf[run]] tick:80, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:80, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 80
[INFO] [stdout] [flow[run]] tick:80, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:80, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:80, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 80
[INFO] [stdout] [flow[ready]] tick:80, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:80, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 80
[INFO] [stdout] [flow[run]] tick:80, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:80, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:81
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 81
[INFO] [stdout] [flow[ready]] tick:81, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:81, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 81
[INFO] [stdout] [flow[run]] tick:81, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:81, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 81
[INFO] [stdout] [leaf[run]] tick:81, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:81, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 81
[INFO] [stdout] [flow[run]] tick:81, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:81, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:81, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 81
[INFO] [stdout] [flow[ready]] tick:81, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:81, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 81
[INFO] [stdout] [flow[run]] tick:81, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:81, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:82
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 82
[INFO] [stdout] [flow[ready]] tick:82, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:82, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 82
[INFO] [stdout] [flow[run]] tick:82, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:82, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 82
[INFO] [stdout] [leaf[run]] tick:82, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:82, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 82
[INFO] [stdout] [flow[run]] tick:82, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:82, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:82, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 82
[INFO] [stdout] [flow[ready]] tick:82, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:82, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 82
[INFO] [stdout] [flow[run]] tick:82, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:82, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:83
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 83
[INFO] [stdout] [flow[ready]] tick:83, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:83, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 83
[INFO] [stdout] [flow[run]] tick:83, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:83, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 83
[INFO] [stdout] [leaf[run]] tick:83, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:83, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 83
[INFO] [stdout] [flow[run]] tick:83, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:83, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:83, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 83
[INFO] [stdout] [flow[ready]] tick:83, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:83, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 83
[INFO] [stdout] [flow[run]] tick:83, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:83, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:84
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 84
[INFO] [stdout] [flow[ready]] tick:84, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:84, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 84
[INFO] [stdout] [flow[run]] tick:84, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:84, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 84
[INFO] [stdout] [leaf[run]] tick:84, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:84, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 84
[INFO] [stdout] [flow[run]] tick:84, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:84, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:84, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 84
[INFO] [stdout] [flow[ready]] tick:84, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:84, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 84
[INFO] [stdout] [flow[run]] tick:84, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:84, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:85
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 85
[INFO] [stdout] [flow[ready]] tick:85, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:85, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 85
[INFO] [stdout] [flow[run]] tick:85, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:85, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 85
[INFO] [stdout] [leaf[run]] tick:85, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:85, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 85
[INFO] [stdout] [flow[run]] tick:85, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:85, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:85, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 85
[INFO] [stdout] [flow[ready]] tick:85, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:85, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 85
[INFO] [stdout] [flow[run]] tick:85, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:85, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:86
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 86
[INFO] [stdout] [flow[ready]] tick:86, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:86, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 86
[INFO] [stdout] [flow[run]] tick:86, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:86, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 86
[INFO] [stdout] [leaf[run]] tick:86, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:86, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 86
[INFO] [stdout] [flow[run]] tick:86, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:86, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:86, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 86
[INFO] [stdout] [flow[ready]] tick:86, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:86, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 86
[INFO] [stdout] [flow[run]] tick:86, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:86, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:87
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 87
[INFO] [stdout] [flow[ready]] tick:87, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:87, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 87
[INFO] [stdout] [flow[run]] tick:87, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:87, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 87
[INFO] [stdout] [leaf[run]] tick:87, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:87, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 87
[INFO] [stdout] [flow[run]] tick:87, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:87, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:87, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 87
[INFO] [stdout] [flow[ready]] tick:87, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:87, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 87
[INFO] [stdout] [flow[run]] tick:87, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:87, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:88
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 88
[INFO] [stdout] [flow[ready]] tick:88, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:88, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 88
[INFO] [stdout] [flow[run]] tick:88, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:88, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 88
[INFO] [stdout] [leaf[run]] tick:88, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:88, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 88
[INFO] [stdout] [flow[run]] tick:88, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:88, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:88, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 88
[INFO] [stdout] [flow[ready]] tick:88, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:88, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 88
[INFO] [stdout] [flow[run]] tick:88, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:88, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:89
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 89
[INFO] [stdout] [flow[ready]] tick:89, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:89, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 89
[INFO] [stdout] [flow[run]] tick:89, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:89, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 89
[INFO] [stdout] [leaf[run]] tick:89, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:89, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 89
[INFO] [stdout] [flow[run]] tick:89, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:89, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:89, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 89
[INFO] [stdout] [flow[ready]] tick:89, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:89, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 89
[INFO] [stdout] [flow[run]] tick:89, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:89, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:90
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 90
[INFO] [stdout] [flow[ready]] tick:90, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:90, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 90
[INFO] [stdout] [flow[run]] tick:90, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:90, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 90
[INFO] [stdout] [leaf[run]] tick:90, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:90, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 90
[INFO] [stdout] [flow[run]] tick:90, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:90, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:90, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 90
[INFO] [stdout] [flow[ready]] tick:90, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:90, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 90
[INFO] [stdout] [flow[run]] tick:90, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:90, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:91
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 91
[INFO] [stdout] [flow[ready]] tick:91, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:91, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 91
[INFO] [stdout] [flow[run]] tick:91, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:91, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 91
[INFO] [stdout] [leaf[run]] tick:91, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:91, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 91
[INFO] [stdout] [flow[run]] tick:91, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:91, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:91, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 91
[INFO] [stdout] [flow[ready]] tick:91, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:91, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 91
[INFO] [stdout] [flow[run]] tick:91, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:91, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:92
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 92
[INFO] [stdout] [flow[ready]] tick:92, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:92, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 92
[INFO] [stdout] [flow[run]] tick:92, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:92, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 92
[INFO] [stdout] [leaf[run]] tick:92, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:92, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 92
[INFO] [stdout] [flow[run]] tick:92, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:92, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:92, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 92
[INFO] [stdout] [flow[ready]] tick:92, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:92, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 92
[INFO] [stdout] [flow[run]] tick:92, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:92, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:93
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 93
[INFO] [stdout] [flow[ready]] tick:93, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:93, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 93
[INFO] [stdout] [flow[run]] tick:93, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:93, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 93
[INFO] [stdout] [leaf[run]] tick:93, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:93, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 93
[INFO] [stdout] [flow[run]] tick:93, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:93, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:93, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 93
[INFO] [stdout] [flow[ready]] tick:93, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:93, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 93
[INFO] [stdout] [flow[run]] tick:93, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:93, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:94
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 94
[INFO] [stdout] [flow[ready]] tick:94, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:94, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 94
[INFO] [stdout] [flow[run]] tick:94, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:94, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 94
[INFO] [stdout] [leaf[run]] tick:94, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:94, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 94
[INFO] [stdout] [flow[run]] tick:94, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:94, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:94, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 94
[INFO] [stdout] [flow[ready]] tick:94, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:94, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 94
[INFO] [stdout] [flow[run]] tick:94, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:94, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:95
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 95
[INFO] [stdout] [flow[ready]] tick:95, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:95, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 95
[INFO] [stdout] [flow[run]] tick:95, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:95, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 95
[INFO] [stdout] [leaf[run]] tick:95, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:95, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 95
[INFO] [stdout] [flow[run]] tick:95, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:95, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:95, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 95
[INFO] [stdout] [flow[ready]] tick:95, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:95, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 95
[INFO] [stdout] [flow[run]] tick:95, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:95, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:96
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 96
[INFO] [stdout] [flow[ready]] tick:96, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:96, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 96
[INFO] [stdout] [flow[run]] tick:96, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:96, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [reqwest::blocking::wait] wait at most 30s
[INFO] [stdout] [loop] node = 3, tick = 96
[INFO] [stdout] [reqwest::blocking::wait] (ThreadId(102)) park timeout 29.99999686s
[INFO] [stdout] [leaf[run]] tick:96, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:96, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 96
[INFO] [stdout] [flow[run]] tick:96, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:96, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:96, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 96
[INFO] [stdout] [flow[ready]] tick:96, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:96, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 96
[INFO] [stdout] [flow[run]] tick:96, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:96, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:97
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 97
[INFO] [stdout] [flow[ready]] tick:97, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [hyper::client::pool] checkout waiting for idle connection: ("https", google.com)
[INFO] [stdout] [reqwest::connect] starting new connection: https://google.com/
[INFO] [stdout] [hyper::client::connect::http] Http::connect; scheme=Some("https"), host=Some("google.com"), port=None
[INFO] [stdout] [flow[ready]] tick:97, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [hyper::client::connect::dns] resolving host="google.com"
[INFO] [stdout] [loop] node = 2, tick = 97
[INFO] [stdout] [flow[run]] tick:97, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:97, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 97
[INFO] [stdout] [leaf[run]] tick:97, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:97, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 97
[INFO] [stdout] [flow[run]] tick:97, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:97, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:97, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 97
[INFO] [stdout] [flow[ready]] tick:97, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:97, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 97
[INFO] [stdout] [flow[run]] tick:97, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:97, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:98
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 98
[INFO] [stdout] [flow[ready]] tick:98, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:98, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 98
[INFO] [stdout] [flow[run]] tick:98, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:98, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 98
[INFO] [stdout] [leaf[run]] tick:98, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:98, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 98
[INFO] [stdout] [flow[run]] tick:98, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:98, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:98, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 98
[INFO] [stdout] [flow[ready]] tick:98, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:98, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 98
[INFO] [stdout] [flow[run]] tick:98, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:98, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:99
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 99
[INFO] [stdout] [flow[ready]] tick:99, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:99, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 99
[INFO] [stdout] [flow[run]] tick:99, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:99, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 99
[INFO] [stdout] [hyper::client::pool] checkout dropped for ("https", google.com)
[INFO] [stdout] [leaf[run]] tick:99, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:99, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [reqwest::blocking::client] closing runtime thread (ThreadId(128))
[INFO] [stdout] [loop] node = 2, tick = 99
[INFO] [stdout] [flow[run]] tick:99, sequence. Running child 3, cursor:0
[INFO] [stdout] [reqwest::blocking::client] signaled close for runtime thread (ThreadId(128))
[INFO] [stdout] [flow[run]] tick:99, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [reqwest::blocking::client] (ThreadId(128)) Receiver is shutdown
[INFO] [stdout] [flow[run]] tick:99, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 99
[INFO] [stdout] [flow[ready]] tick:99, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:99, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 99
[INFO] [stdout] [flow[run]] tick:99, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:99, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:100
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 100
[INFO] [stdout] [flow[ready]] tick:100, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:100, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 100
[INFO] [stdout] [flow[run]] tick:100, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:100, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 100
[INFO] [stdout] [leaf[run]] tick:100, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:100, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 100
[INFO] [stdout] [flow[run]] tick:100, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:100, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:100, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 100
[INFO] [stdout] [flow[ready]] tick:100, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:100, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 100
[INFO] [stdout] [flow[run]] tick:100, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:100, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:101
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 101
[INFO] [stdout] [flow[ready]] tick:101, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:101, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 101
[INFO] [stdout] [flow[run]] tick:101, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:101, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 101
[INFO] [stdout] [leaf[run]] tick:101, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:101, the new state: Running(url=https://google.com,bb_key=out1)
[INFO] [stdout] [loop] node = 2, tick = 101
[INFO] [stdout] [flow[run]] tick:101, sequence. Running child 3, cursor:0
[INFO] [stdout] [reqwest::blocking::client] (ThreadId(128)) end runtime::block_on
[INFO] [stdout] [flow[run]] tick:101, sequence. The '3' is running, decide to go up or stay here.
[INFO] [stdout] [flow[run]] tick:101, sequence. Go up with the new state: Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 1, tick = 101
[INFO] [stdout] [flow[ready]] tick:101, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:101, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [reqwest::blocking::client] (ThreadId(128)) finished
[INFO] [stdout] [loop] node = 1, tick = 101
[INFO] [stdout] [flow[run]] tick:101, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:101, root. The '2' is running, tick up the flow. 
[INFO] [stdout] [root] tick up the flow to:102
[INFO] [stdout] [trim] trying to trim the tree, the number of tasks in the q is 0
[INFO] [stdout] [reqwest::blocking::client] closed runtime thread (ThreadId(128))
[INFO] [stdout] [trim] attempt to trim is  Ok(())
[INFO] [stdout] [loop] node = 2, tick = 102
[INFO] [stdout] [flow[ready]] tick:102, sequence. Start node
[INFO] [stdout] [params] cursor=0,len=2,running_child=0,prev_cursor=0, cur:0, len:2
[INFO] [stdout] [flow[ready]] tick:102, sequence. Switch to the new_state:Running(cursor=0,len=2,running_child=0,prev_cursor=0)
[INFO] [stdout] [loop] node = 2, tick = 102
[INFO] [stdout] [flow[run]] tick:102, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:102, sequence. The '3' is ready, push it on the stack
[INFO] [stdout] [loop] node = 3, tick = 102
[INFO] [stdout] [leaf[run]] tick:102, args :RtArgs([RtArgument { name: "url", value: String("https://google.com") }, RtArgument { name: "bb_key", value: String("out1") }])
[INFO] [stdout] [leaf] tick:102, the new state: Failure(url=https://google.com,bb_key=out1,reason=error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution)
[INFO] [stdout] [loop] node = 2, tick = 102
[INFO] [stdout] [flow[run]] tick:102, sequence. Running child 3, cursor:0
[INFO] [stdout] [flow[run]] tick:102, sequence. The '3' is finished, decide to go up or stay here.
[INFO] [stdout] [params] cursor=0,len=2,reason=error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution, cur:0, len:2
[INFO] [stdout] [flow[run]] tick:102, sequence. The '3' is finished as Failure(url=https://google.com,bb_key=out1,reason=error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution), the new state: Failure(cursor=0,len=2,reason=error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution). Stay at this node. 
[INFO] [stdout] [loop] node = 2, tick = 102
[INFO] [stdout] [flow[fin]] tick:102,sequence gets popped up
[INFO] [stdout] [loop] node = 1, tick = 102
[INFO] [stdout] [flow[ready]] tick:102, root. Start node
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:102, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] [loop] node = 1, tick = 102
[INFO] [stdout] [flow[run]] tick:102, root. Running child 2, cursor:0
[INFO] [stdout] [flow[run]] tick:102, root. The '2' is finished, decide to go up or stay here.
[INFO] [stdout] [params] cursor=0,len=1, cur:0, len:1
[INFO] [stdout] [flow[run]] tick:102, root. The '2' is finished as Failure(cursor=0,len=2,reason=error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution), the new state: Failure(cursor=0,len=1,reason=error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution). Stay at this node. 
[INFO] [stdout] [loop] node = 1, tick = 102
[INFO] [stdout] [flow[fin]] tick:102,root gets popped up
[INFO] [stdout] thread 'tests::actions::builtin_http_get' panicked at src/tests/actions.rs:68:5:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: Ok(Failure("error error sending request for url (https://google.com/): error trying to connect: dns error: failed to lookup address information: Temporary failure in name resolution"))
[INFO] [stdout]  right: Ok(Success)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d025 - core::panicking::assert_failed_inner::h04f38944d74a80a8
[INFO] [stdout]   19:     0x616f6c354d15 - core::panicking::assert_failed::hdadb72276d608064
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:377:5
[INFO] [stdout]   20:     0x616f6c2784d9 - forester_rs::tests::actions::builtin_http_get::h7c8628730f22b351
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/actions.rs:68:5
[INFO] [stdout]   21:     0x616f6c278287 - forester_rs::tests::actions::builtin_http_get::{{closure}}::hffcbdbe42cafdc8e
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/actions.rs:65:22
[INFO] [stdout]   22:     0x616f6c5522c6 - core::ops::function::FnOnce::call_once::hee8dc1141a3eeae9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::nav::export_smoke stdout ----
[INFO] [stdout] [ast] built project with root: "/opt/rustwide/workdir/tree/tests/ros/nav/smoke", main file: main.tree and root definition: MainTree 
[INFO] [stdout] [tree[construct]] found invocation , id 2 in parent 1
[INFO] [stdout] [tree[construct]] found import from another file,  id 2 in parent 1
[INFO] [stdout] [tree[construct]] found ho invocation with id 3 in parent 2
[INFO] [stdout] [tree[construct]] found invocation , id 3 in parent 1
[INFO] [stdout] [tree[construct]] found invocation , id 4 in parent 3
[INFO] [stdout] [tree[construct]] found import from another file,  id 4 in parent 3
[INFO] [stdout] [tree[construct]] found decorator retry, id 5 in parent 3
[INFO] [stdout] [tree[construct]] found ho invocation with id 6 in parent 4
[INFO] [stdout] [tree[construct]] found invocation , id 6 in parent 3
[INFO] [stdout] [tree[construct]] found import from another file,  id 6 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 7 in parent 5
[INFO] [stdout] [tree[construct]] found ho invocation with id 8 in parent 6
[INFO] [stdout] [tree[construct]] found decorator retry, id 8 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 9 in parent 7
[INFO] [stdout] [tree[construct]] found import from another file,  id 9 in parent 7
[INFO] [stdout] [tree[construct]] found invocation , id 10 in parent 7
[INFO] [stdout] [tree[construct]] found invocation , id 11 in parent 8
[INFO] [stdout] [tree[construct]] found invocation , id 12 in parent 10
[INFO] [stdout] [tree[construct]] found import from another file,  id 12 in parent 10
[INFO] [stdout] [tree[construct]] found invocation , id 13 in parent 10
[INFO] [stdout] [tree[construct]] found import from another file,  id 13 in parent 10
[INFO] [stdout] [tree[construct]] found invocation , id 14 in parent 11
[INFO] [stdout] [tree[construct]] found import from another file,  id 14 in parent 11
[INFO] [stdout] [tree[construct]] found invocation , id 15 in parent 11
[INFO] [stdout] [tree[construct]] found invocation , id 16 in parent 15
[INFO] [stdout] [tree[construct]] found import from another file,  id 16 in parent 15
[INFO] [stdout] [tree[construct]] found invocation , id 17 in parent 15
[INFO] [stdout] [tree[construct]] found import from another file,  id 17 in parent 15
[INFO] [stdout] thread 'tests::nav::export_smoke' panicked at src/tests/nav.rs:16:33:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: io: Read-only file system (os error 30)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d0a6 - core::result::unwrap_failed::h5e8218e4d4338b8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1698:5
[INFO] [stdout]   19:     0x616f6c4c872c - core::result::Result<T,E>::unwrap::hf3f67699aa27a66f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1103:23
[INFO] [stdout]   20:     0x616f6c4c872c - forester_rs::tests::nav::export_smoke::he64b70d77a9d5693
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/nav.rs:16:5
[INFO] [stdout]   21:     0x616f6c4c82d7 - forester_rs::tests::nav::export_smoke::{{closure}}::hc057d1ab7f53b984
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/nav.rs:9:18
[INFO] [stdout]   22:     0x616f6c54d0d6 - core::ops::function::FnOnce::call_once::h652be487446d2c8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::simulator::text stdout ----
[INFO] [stdout] thread 'tests::simulator::text' panicked at src/tests/simulator.rs:249:67:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 30, kind: ReadOnlyFilesystem, message: "Read-only file system" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d0a6 - core::result::unwrap_failed::h5e8218e4d4338b8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1698:5
[INFO] [stdout]   19:     0x616f6c2e40cd - core::result::Result<T,E>::unwrap::h02e9cbba5a9e8a76
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1103:23
[INFO] [stdout]   20:     0x616f6c2e40cd - forester_rs::tests::simulator::text::h6ae9391374447385
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/simulator.rs:249:20
[INFO] [stdout]   21:     0x616f6c2e3b27 - forester_rs::tests::simulator::text::{{closure}}::h2236407cfcd42640
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/simulator.rs:214:10
[INFO] [stdout]   22:     0x616f6c54b2e6 - core::ops::function::FnOnce::call_once::h324f1ce46385a75b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::simulator::smoke stdout ----
[INFO] [stdout] [tracer] create new tracer from TracerConfig { indent: 2, time_format: Some("%M:%S%.3f"), to_file: Some("/opt/rustwide/workdir/tree/tests/simulator/smoke/gen\\main.log") }
[INFO] [stdout] thread 'tests::simulator::smoke' panicked at src/tests/simulator.rs:157:30:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: io: Read-only file system (os error 30)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d0a6 - core::result::unwrap_failed::h5e8218e4d4338b8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1698:5
[INFO] [stdout]   19:     0x616f6c2e2da7 - core::result::Result<T,E>::unwrap::h4c8d7300c9227a93
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1103:23
[INFO] [stdout]   20:     0x616f6c2e2da7 - forester_rs::tests::simulator::smoke::h6717832b27380571
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/simulator.rs:157:19
[INFO] [stdout]   21:     0x616f6c2e2b27 - forester_rs::tests::simulator::smoke::{{closure}}::hcfe1d7873a08e50b
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/simulator.rs:143:11
[INFO] [stdout]   22:     0x616f6c54e796 - core::ops::function::FnOnce::call_once::h8dd29e9ca01cb830
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::tracer::file stdout ----
[INFO] [stdout] [tracer] create new tracer from TracerConfig { indent: 2, time_format: None, to_file: Some("/opt/rustwide/workdir/tree/tests/tracer/custom/main.trace") }
[INFO] [stdout] thread 'tests::tracer::file' panicked at src/tests/tracer.rs:283:10:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: io: Read-only file system (os error 30)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d0a6 - core::result::unwrap_failed::h5e8218e4d4338b8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1698:5
[INFO] [stdout]   19:     0x616f6c243e40 - core::result::Result<T,E>::unwrap::h7fbc2b4f231d3ed0
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1103:23
[INFO] [stdout]   20:     0x616f6c243e40 - forester_rs::tests::tracer::file::he5d6f54f3c7285d6
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/tracer.rs:278:9
[INFO] [stdout]   21:     0x616f6c243bf7 - forester_rs::tests::tracer::file::{{closure}}::hccb64bfb7317b831
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/tracer.rs:248:10
[INFO] [stdout]   22:     0x616f6c54d576 - core::ops::function::FnOnce::call_once::h703bcfd347fa7e1a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::trimmer::naive stdout ----
[INFO] [stdout] [tracer] create new tracer from TracerConfig { indent: 2, time_format: None, to_file: Some("/opt/rustwide/workdir/tree/tests/trimmer/naive/main.log") }
[INFO] [stdout] [ast] built project with root: "/opt/rustwide/workdir/tree/tests/trimmer/naive", main file: main.tree and root definition: main 
[INFO] [stdout] [tree[construct]] found lambda r_sequence: id 2 and parent 1
[INFO] [stdout] [tree[construct]] found invocation , id 3 in parent 2
[INFO] [stdout] [tree[construct]] found invocation , id 4 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 5 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 6 in parent 3
[INFO] [stdout] [action] register action pick with the given impl
[INFO] [stdout] [action] register action place with the given impl
[INFO] [stdout] [action] register action validate with the given impl
[INFO] [stdout] 1 Flow(Root, Name("main", "main.tree"), RtArgs([]), [2])
[INFO] [stdout] 2 Flow(RSequence, Lambda, RtArgs([]), [3])
[INFO] [stdout] 3 Flow(RFallback, Name("work", "main.tree"), RtArgs([RtArgument { name: "item", value: Object({"y": Number(Int(1)), "x": Number(Int(1))}) }]), [4, 5, 6])
[INFO] [stdout] 4 Leaf(Name("pick", "main.tree"), RtArgs([RtArgument { name: "item", value: Object({"x": Number(Int(1)), "y": Number(Int(1))}) }]))
[INFO] [stdout] 5 Leaf(Name("validate", "main.tree"), RtArgs([]))
[INFO] [stdout] 6 Leaf(Name("place", "main.tree"), RtArgs([]))
[INFO] [stdout] [loop] node = 1, tick = 1
[INFO] [stdout] [flow[ready]] tick:1, root. Start node
[INFO] [stdout] [params] , cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:1, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] thread 'tests::trimmer::naive' panicked at src/tests/trimmer.rs:88:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: io: Read-only file system (os error 30)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d0a6 - core::result::unwrap_failed::h5e8218e4d4338b8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1698:5
[INFO] [stdout]   19:     0x616f6c245c9c - core::result::Result<T,E>::unwrap::h001a2e1fe8c68e0a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1103:23
[INFO] [stdout]   20:     0x616f6c245c9c - forester_rs::tests::trimmer::naive::hface4ce7b625d062
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/trimmer.rs:88:18
[INFO] [stdout]   21:     0x616f6c245547 - forester_rs::tests::trimmer::naive::{{closure}}::hbd0982036010741e
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/trimmer.rs:69:11
[INFO] [stdout]   22:     0x616f6c54dbb6 - core::ops::function::FnOnce::call_once::h7ad077ba70944fe5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- tests::trimmer::smoke stdout ----
[INFO] [stdout] [tracer] create new tracer from TracerConfig { indent: 2, time_format: None, to_file: Some("/opt/rustwide/workdir/tree/tests/trimmer/smoke/main.log") }
[INFO] [stdout] [ast] built project with root: "/opt/rustwide/workdir/tree/tests/trimmer/smoke", main file: main.tree and root definition: main 
[INFO] [stdout] [tree[construct]] found lambda r_fallback: id 2 and parent 1
[INFO] [stdout] [tree[construct]] found invocation , id 3 in parent 2
[INFO] [stdout] [tree[construct]] found invocation , id 4 in parent 2
[INFO] [stdout] [tree[construct]] found import from another file,  id 4 in parent 2
[INFO] [stdout] [tree[construct]] found invocation , id 5 in parent 3
[INFO] [stdout] [tree[construct]] found import from another file,  id 5 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 6 in parent 3
[INFO] [stdout] [tree[construct]] found import from another file,  id 6 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 7 in parent 3
[INFO] [stdout] [tree[construct]] found import from another file,  id 7 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 8 in parent 3
[INFO] [stdout] [tree[construct]] found invocation , id 9 in parent 8
[INFO] [stdout] [tree[construct]] found import from another file,  id 9 in parent 8
[INFO] [stdout] [tree[construct]] found invocation , id 10 in parent 8
[INFO] [stdout] [tree[construct]] found import from another file,  id 10 in parent 8
[INFO] [stdout] [tree[construct]] found invocation , id 11 in parent 8
[INFO] [stdout] [tree[construct]] found import from another file,  id 11 in parent 8
[INFO] [stdout] [tree[construct]] found invocation , id 12 in parent 8
[INFO] [stdout] [tree[construct]] found import from another file,  id 12 in parent 8
[INFO] [stdout] [action] register action running with the given impl
[INFO] [stdout] [action] register action success with the given impl
[INFO] [stdout] [action] register action fail_empty with the given impl
[INFO] [stdout] [loop] node = 1, tick = 1
[INFO] [stdout] [flow[ready]] tick:1, root. Start node
[INFO] [stdout] [params] , cur:0, len:1
[INFO] [stdout] [flow[ready]] tick:1, root. Switch to the new_state:Running(cursor=0,len=1)
[INFO] [stdout] thread 'tests::trimmer::smoke' panicked at src/tests/trimmer.rs:33:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: io: Read-only file system (os error 30)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x616f6d2521ca - std::backtrace_rs::backtrace::libunwind::trace::h207520d6e9873551
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/libunwind.rs:116:5
[INFO] [stdout]    1:     0x616f6d2521ca - std::backtrace_rs::backtrace::trace_unsynchronized::h2ed7433f370e3eca
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x616f6d2521ca - std::sys::backtrace::_print_fmt::h916fac51166b18d7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:66:9
[INFO] [stdout]    3:     0x616f6d2521ca - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::ha0a4c9b8ce3802e6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:39:26
[INFO] [stdout]    4:     0x616f6d27f283 - core::fmt::rt::Argument::fmt::h4cfc8587d52b3eb5
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/rt.rs:177:76
[INFO] [stdout]    5:     0x616f6d27f283 - core::fmt::write::h522cc39e5b36ae9f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/fmt/mod.rs:1190:21
[INFO] [stdout]    6:     0x616f6d24d993 - std::io::Write::write_fmt::h7d3c26752da97734
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/io/mod.rs:1839:15
[INFO] [stdout]    7:     0x616f6d252012 - std::sys::backtrace::BacktraceLock::print::h4b9e00b29e9c0942
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:42:9
[INFO] [stdout]    8:     0x616f6d25365a - std::panicking::default_hook::{{closure}}::hb59b88280d0324d9
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:268:22
[INFO] [stdout]    9:     0x616f6d253464 - std::panicking::default_hook::he1dd496665be4a29
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:292:9
[INFO] [stdout]   10:     0x616f6c6d12c9 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h4ff71de18744e1dc
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   11:     0x616f6c6d12c9 - test::test_main::{{closure}}::hcf7c055ce117aa81
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:133:21
[INFO] [stdout]   12:     0x616f6d253d58 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h1d66c6bbd60c9692
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2468:9
[INFO] [stdout]   13:     0x616f6d253d58 - std::panicking::rust_panic_with_hook::h2ff17fe8f26dfcf8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:809:13
[INFO] [stdout]   14:     0x616f6d253b1a - std::panicking::begin_panic_handler::{{closure}}::h4fad77951c07e101
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:674:13
[INFO] [stdout]   15:     0x616f6d2526a9 - std::sys::backtrace::__rust_end_short_backtrace::hb1a25b3da5d0ea08
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:170:18
[INFO] [stdout]   16:     0x616f6d2537ac - rust_begin_unwind
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:665:5
[INFO] [stdout]   17:     0x616f6d27cbc0 - core::panicking::panic_fmt::ha9db07b529f9b256
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panicking.rs:75:14
[INFO] [stdout]   18:     0x616f6d27d0a6 - core::result::unwrap_failed::h5e8218e4d4338b8a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1698:5
[INFO] [stdout]   19:     0x616f6c2453b5 - core::result::Result<T,E>::unwrap::h001a2e1fe8c68e0a
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/result.rs:1103:23
[INFO] [stdout]   20:     0x616f6c2453b5 - forester_rs::tests::trimmer::smoke::h89208762d6c1d66d
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/trimmer.rs:33:18
[INFO] [stdout]   21:     0x616f6c244ea7 - forester_rs::tests::trimmer::smoke::{{closure}}::h5e33b5bf1793319b
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests/trimmer.rs:20:11
[INFO] [stdout]   22:     0x616f6c54f816 - core::ops::function::FnOnce::call_once::ha4db29e07a528ab8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x616f6c6d5b9b - core::ops::function::FnOnce::call_once::h22c3ba5715e93f05
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x616f6c6d5b9b - test::__rust_begin_short_backtrace::h8e82d8113d0da17f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:621:18
[INFO] [stdout]   25:     0x616f6c6d5517 - test::run_test_in_process::{{closure}}::h33235f4f20b7b7c7
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:60
[INFO] [stdout]   26:     0x616f6c6d5517 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hb5043206310c586f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   27:     0x616f6c6d5517 - std::panicking::try::do_call::h9ad4731a60ab23e2
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   28:     0x616f6c6d5517 - std::panicking::try::h3824d23db772a716
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   29:     0x616f6c6d5517 - std::panic::catch_unwind::h5afd514605fde46b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   30:     0x616f6c6d5517 - test::run_test_in_process::h0d5cbfa7067d1720
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:644:27
[INFO] [stdout]   31:     0x616f6c6d5517 - test::run_test::{{closure}}::h708eb8872708115d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:565:43
[INFO] [stdout]   32:     0x616f6c698c34 - test::run_test::{{closure}}::h392c51cee94713ab
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/test/src/lib.rs:595:41
[INFO] [stdout]   33:     0x616f6c698c34 - std::sys::backtrace::__rust_begin_short_backtrace::h2f40290710935a8b
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/backtrace.rs:154:18
[INFO] [stdout]   34:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h3fd968d89053a903
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:541:17
[INFO] [stdout]   35:     0x616f6c69c3de - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h743f637ba4229880
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   36:     0x616f6c69c3de - std::panicking::try::do_call::h07440897322525a6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:557:40
[INFO] [stdout]   37:     0x616f6c69c3de - std::panicking::try::he5a2e00188156637
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panicking.rs:520:19
[INFO] [stdout]   38:     0x616f6c69c3de - std::panic::catch_unwind::hbaf3cae49b3c6433
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/panic.rs:358:14
[INFO] [stdout]   39:     0x616f6c69c3de - std::thread::Builder::spawn_unchecked_::{{closure}}::h8e141ea224820bce
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/thread/mod.rs:540:30
[INFO] [stdout]   40:     0x616f6c69c3de - core::ops::function::FnOnce::call_once{{vtable.shim}}::h90d312fedd7b8ea8
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h69aa08ddb6adb8b6
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   42:     0x616f6d25833b - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h5aa36efdb79c806f
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/alloc/src/boxed.rs:2454:9
[INFO] [stdout]   43:     0x616f6d25833b - std::sys::pal::unix::thread::Thread::new::thread_start::h6cd80a1b4be5158d
[INFO] [stdout]                                at /rustc/df4ca44d3fbc9301ee17f30f4d474e6ae4b05252/library/std/src/sys/pal/unix/thread.rs:105:17
[INFO] [stdout]   44:     0x758ff5484ac3 - <unknown>
[INFO] [stdout]   45:     0x758ff5515a04 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::actions::builtin_http_get
[INFO] [stdout]     tests::nav::export_smoke
[INFO] [stdout]     tests::simulator::smoke
[INFO] [stdout]     tests::simulator::text
[INFO] [stdout]     tests::tracer::file
[INFO] [stdout]     tests::trimmer::naive
[INFO] [stdout]     tests::trimmer::smoke
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 94 passed; 7 failed; 5 ignored; 0 measured; 0 filtered out; finished in 2.64s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "1df7ea2b4fbb2f3a0b26d46307cc037adcaad62237924aaa8267c927799272ac", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1df7ea2b4fbb2f3a0b26d46307cc037adcaad62237924aaa8267c927799272ac", kill_on_drop: false }`
[INFO] [stdout] 1df7ea2b4fbb2f3a0b26d46307cc037adcaad62237924aaa8267c927799272ac
