[INFO] cloning repository https://github.com/joyent/rust-fast
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/joyent/rust-fast" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1ddf01bba94c010d5627bf0eb0ff260b8085a460
[INFO] testing joyent/rust-fast against try#d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2 for pr-86479
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-3/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/joyent/rust-fast
[INFO] finished tweaking git repo https://github.com/joyent/rust-fast
[INFO] tweaked toml for git repo https://github.com/joyent/rust-fast written to /workspace/builds/worker-3/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded crc16 v0.4.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 66682b4029c84821127c7e885e962bbc5bf677d718ed2906238f2476ada1d8a0
[INFO] running `Command { std: "docker" "start" "-a" "66682b4029c84821127c7e885e962bbc5bf677d718ed2906238f2476ada1d8a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "66682b4029c84821127c7e885e962bbc5bf677d718ed2906238f2476ada1d8a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "66682b4029c84821127c7e885e962bbc5bf677d718ed2906238f2476ada1d8a0", kill_on_drop: false }`
[INFO] [stdout] 66682b4029c84821127c7e885e962bbc5bf677d718ed2906238f2476ada1d8a0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ef60444d4b977c024cf83b61899c86966fcccf9111691f21e077a19cd712ee2f
[INFO] running `Command { std: "docker" "start" "-a" "ef60444d4b977c024cf83b61899c86966fcccf9111691f21e077a19cd712ee2f", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.98
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling futures v0.1.31
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling rand_core v0.4.2
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling memchr v2.4.0
[INFO] [stderr]    Compiling proc-macro2 v1.0.28
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling syn v1.0.74
[INFO] [stderr]    Compiling serde v1.0.127
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling serde_json v1.0.66
[INFO] [stderr]    Compiling serde_derive v1.0.127
[INFO] [stderr]    Compiling arc-swap v1.3.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling itoa v0.4.7
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling rand_pcg v0.1.2
[INFO] [stderr]    Compiling rand_chacha v0.1.1
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling lock_api v0.3.4
[INFO] [stderr]    Compiling rand_core v0.3.1
[INFO] [stderr]    Compiling rand_jitter v0.1.4
[INFO] [stderr]    Compiling smallvec v0.6.14
[INFO] [stderr]    Compiling rand_hc v0.1.0
[INFO] [stderr]    Compiling rand_xorshift v0.1.1
[INFO] [stderr]    Compiling rand_isaac v0.1.1
[INFO] [stderr]    Compiling rustc_version v0.2.3
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling crossbeam-deque v0.7.4
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling net2 v0.2.37
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling rand_os v0.1.3
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 50s
[INFO] running `Command { std: "docker" "inspect" "ef60444d4b977c024cf83b61899c86966fcccf9111691f21e077a19cd712ee2f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ef60444d4b977c024cf83b61899c86966fcccf9111691f21e077a19cd712ee2f", kill_on_drop: false }`
[INFO] [stdout] ef60444d4b977c024cf83b61899c86966fcccf9111691f21e077a19cd712ee2f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 01fab142fffd9b9831e90a5df2b7e64f776d78b0cb9f981990c95b172196e58b
[INFO] running `Command { std: "docker" "start" "-a" "01fab142fffd9b9831e90a5df2b7e64f776d78b0cb9f981990c95b172196e58b", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-width v0.1.8
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling slog-term v2.8.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 33.50s
[INFO] running `Command { std: "docker" "inspect" "01fab142fffd9b9831e90a5df2b7e64f776d78b0cb9f981990c95b172196e58b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "01fab142fffd9b9831e90a5df2b7e64f776d78b0cb9f981990c95b172196e58b", kill_on_drop: false }`
[INFO] [stdout] 01fab142fffd9b9831e90a5df2b7e64f776d78b0cb9f981990c95b172196e58b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+d2e9fff28e244f1ea4927194f1f9b77f93e2ffe2" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 7bb3b744b48d95067c4c69fe1203e85508fb225925246d4c49a719f049e5f9d1
[INFO] running `Command { std: "docker" "start" "-a" "7bb3b744b48d95067c4c69fe1203e85508fb225925246d4c49a719f049e5f9d1", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.32s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-f5fafbc542c30012)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test protocol::test::prop_fast_message_roundtrip ... ok
[INFO] [stdout] test protocol::test::prop_fast_message_bundling ... ok
[INFO] [stdout] test protocol::test::prop_fast_message_decoding ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.52s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-c5bc71e176913022)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Aug 05 22:53:19.941 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:19.994 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:19.994 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:19.994 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:19.994 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.008 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.008 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.008 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.008 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.020 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.021 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.021 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.021 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.024 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.024 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.024 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.024 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.027 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.027 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.027 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.027 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.031 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.031 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.031 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.031 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.046 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.046 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.046 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.046 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.050 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.050 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.050 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.050 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.073 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.073 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.073 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.073 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.078 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.078 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.078 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.078 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.083 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.084 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.084 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.084 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.089 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.089 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.089 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.091 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.104 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.104 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.104 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.105 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.111 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.111 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.111 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.112 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.122 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.122 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.122 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.122 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.129 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.130 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.130 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.130 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.150 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.150 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.151 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.151 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.159 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.168 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.168 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.168 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.176 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.176 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.176 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.177 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.185 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.186 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.186 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.186 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.196 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.196 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.196 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.196 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.205 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.205 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.205 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.205 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.222 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.223 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.223 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.223 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.233 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.234 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.234 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.234 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.244 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.245 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.245 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.245 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.259 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.260 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.260 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.260 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.270 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.270 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.270 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.270 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.291 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.291 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.291 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.291 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.303 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.303 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.303 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.303 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.315 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.316 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.316 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.316 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.327 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.327 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.328 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.328 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.340 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.341 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.341 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.341 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.354 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.355 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.355 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.355 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.368 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.369 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.369 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.369 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.383 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.383 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.383 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.383 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.397 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.398 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.398 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.398 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.413 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.413 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.413 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.413 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.436 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.436 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.436 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.437 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.452 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.453 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.453 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.453 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.468 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.468 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.468 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.468 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.485 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.485 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.485 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.485 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.502 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.502 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.502 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.502 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.519 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.519 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.519 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.519 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.536 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.537 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.537 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.537 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.554 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.554 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.554 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.554 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.572 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.572 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.572 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.572 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.591 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.591 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.591 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.591 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.633 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.633 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.633 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.633 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.657 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.657 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.657 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.657 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.677 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.677 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.677 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.677 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.701 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.701 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.701 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.701 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.721 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.721 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.721 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.721 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.749 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.749 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.749 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.749 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.779 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.779 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.779 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.779 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.812 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.812 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.812 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.812 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.838 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.838 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.838 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.838 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.865 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.865 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.865 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.865 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.892 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.892 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.892 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.892 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.915 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.915 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.915 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.916 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.951 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.951 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.951 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.951 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.976 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.976 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.976 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:20.976 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.002 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.002 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.002 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.002 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.031 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.032 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.032 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.032 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.073 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.073 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.073 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.073 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.107 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.107 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.107 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.107 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.135 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.135 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.135 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.135 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.162 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.163 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.163 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.163 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.199 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.199 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.199 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.199 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.242 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.242 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.242 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.242 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.282 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.288 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.288 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.288 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.316 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.316 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.316 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.316 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.356 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.356 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.356 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.356 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.412 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.412 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.412 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.412 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.460 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.460 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.460 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.460 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.498 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.498 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.498 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.498 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.548 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.548 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.548 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.548 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.582 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.582 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.582 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.582 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.617 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.617 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.617 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.617 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.648 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.648 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.648 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.648 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.688 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.692 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.692 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.692 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.728 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.729 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.729 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.729 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.761 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.761 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.761 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.762 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.795 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.804 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.804 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.804 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.841 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.841 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.842 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.842 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.875 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.875 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.875 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.876 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.914 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.914 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.914 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.915 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.949 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.949 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.949 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:21.949 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.016 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.016 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.016 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.016 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.055 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.056 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.056 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.056 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.109 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.109 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.109 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.109 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.147 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.147 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.147 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.147 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.215 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.215 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.215 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.215 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.275 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.276 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.276 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.280 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.331 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.331 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.331 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.331 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.372 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.372 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.372 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.372 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.424 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.425 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.425 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.425 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.470 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.480 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.480 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.480 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.519 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.519 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.519 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.520 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.558 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.558 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.559 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.559 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 05 22:53:22.580 DEBG transmitted response to client, build-id: 0.1.0
[INFO] [stdout] test client_server_comms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.64s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests fast-rpc
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "7bb3b744b48d95067c4c69fe1203e85508fb225925246d4c49a719f049e5f9d1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7bb3b744b48d95067c4c69fe1203e85508fb225925246d4c49a719f049e5f9d1", kill_on_drop: false }`
[INFO] [stdout] 7bb3b744b48d95067c4c69fe1203e85508fb225925246d4c49a719f049e5f9d1