[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#722e1797249a965b6335aebd65d777f917e498f1 for pr-91031
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-12/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-12/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 722e1797249a965b6335aebd65d777f917e498f1
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "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-12/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded crc16 v0.4.0
[INFO] [stderr]   Downloaded crossbeam v0.2.12
[INFO] [stderr]   Downloaded term v0.7.0
[INFO] [stderr]   Downloaded arc-swap v1.5.0
[INFO] [stderr]   Downloaded slog-stdlog v3.0.5
[INFO] [stderr]   Downloaded slog-term v2.8.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 72147bc2d725fa208d808a54260b166d526feeebadbfbba3a4c8e0c12bc5de28
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "72147bc2d725fa208d808a54260b166d526feeebadbfbba3a4c8e0c12bc5de28", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "72147bc2d725fa208d808a54260b166d526feeebadbfbba3a4c8e0c12bc5de28", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "72147bc2d725fa208d808a54260b166d526feeebadbfbba3a4c8e0c12bc5de28", kill_on_drop: false }`
[INFO] [stdout] 72147bc2d725fa208d808a54260b166d526feeebadbfbba3a4c8e0c12bc5de28
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1f9ff26d3759118462e4afbb56d83732f5f26b9c5822f6a37b3324514938f53e
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "1f9ff26d3759118462e4afbb56d83732f5f26b9c5822f6a37b3324514938f53e", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling futures v0.1.31
[INFO] [stderr]    Compiling byteorder v1.4.3
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling syn v1.0.81
[INFO] [stderr]    Compiling arc-swap v1.5.0
[INFO] [stderr]    Compiling serde_json v1.0.71
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling libc v0.2.108
[INFO] [stderr]    Compiling proc-macro2 v1.0.32
[INFO] [stderr]    Compiling smallvec v0.6.14
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling net2 v0.2.37
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling rand_os v0.1.3
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling crossbeam-deque v0.7.4
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling serde_derive v1.0.130
[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 22s
[INFO] running `Command { std: "docker" "inspect" "1f9ff26d3759118462e4afbb56d83732f5f26b9c5822f6a37b3324514938f53e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1f9ff26d3759118462e4afbb56d83732f5f26b9c5822f6a37b3324514938f53e", kill_on_drop: false }`
[INFO] [stdout] 1f9ff26d3759118462e4afbb56d83732f5f26b9c5822f6a37b3324514938f53e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 26ed106cbb5b8d0cddfab6f929a3e168a6c3f2401b2b3248b479f27d56d3b3bd
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "26ed106cbb5b8d0cddfab6f929a3e168a6c3f2401b2b3248b479f27d56d3b3bd", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[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 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 24.92s
[INFO] running `Command { std: "docker" "inspect" "26ed106cbb5b8d0cddfab6f929a3e168a6c3f2401b2b3248b479f27d56d3b3bd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "26ed106cbb5b8d0cddfab6f929a3e168a6c3f2401b2b3248b479f27d56d3b3bd", kill_on_drop: false }`
[INFO] [stdout] 26ed106cbb5b8d0cddfab6f929a3e168a6c3f2401b2b3248b479f27d56d3b3bd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+722e1797249a965b6335aebd65d777f917e498f1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 9c7d6cbc5df700f5428e1db32489e7a6f2009b409488056a0fafd495fc76441d
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "9c7d6cbc5df700f5428e1db32489e7a6f2009b409488056a0fafd495fc76441d", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.17s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-f709b659d842c6ba)
[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.34s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-dc0f92c158b67956)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Nov 23 22:01:19.315 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.362 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.362 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.362 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.362 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.363 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.363 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.363 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.363 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.365 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.365 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.365 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.365 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.372 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.372 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.372 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.372 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.374 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.374 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.374 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.374 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.376 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.377 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.377 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.377 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.379 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.379 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.380 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.380 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.383 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.383 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.383 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.383 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.386 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.387 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.387 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.387 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.391 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.391 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.391 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.391 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.395 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.395 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.395 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.395 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.400 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.400 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.400 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.400 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.405 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.405 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.405 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.405 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.410 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.410 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.410 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.410 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.416 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.416 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.416 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.417 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.423 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.423 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.423 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.423 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.429 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.430 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.430 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.430 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.437 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.437 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.437 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.437 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.444 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.444 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.444 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.445 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.452 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.452 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.452 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.452 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.460 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.461 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.461 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.461 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.469 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.469 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.469 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.469 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.478 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.478 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.478 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.478 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.488 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.488 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.488 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.488 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.497 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.497 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.497 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.497 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.507 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.507 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.507 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.507 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.518 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.518 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.518 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.518 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.529 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.529 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.529 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.529 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.540 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.540 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.540 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.540 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.551 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.552 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.552 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.552 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.563 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.563 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.563 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.563 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.575 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.575 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.575 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.576 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.588 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.588 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.588 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.588 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.605 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.605 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.605 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.605 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.639 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.639 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.639 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.639 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.653 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.653 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.653 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.653 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.669 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.669 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.669 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.670 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.691 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.691 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.691 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.691 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.718 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.718 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.718 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.718 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.733 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.733 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.733 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.733 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.750 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.751 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.751 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.751 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.767 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.767 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.767 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.767 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.783 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.783 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.783 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.783 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.816 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.816 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.816 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.816 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.845 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.845 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.845 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.847 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.870 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.870 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.870 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.870 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.904 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.904 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.904 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.904 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.927 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.927 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.927 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.927 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.945 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.946 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.946 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.946 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.964 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.965 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.965 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.965 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.988 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.988 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.988 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:19.988 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.007 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.007 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.007 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.007 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.030 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.030 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.030 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.030 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.079 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.080 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.080 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.080 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.101 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.104 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.104 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.104 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.124 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.124 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.125 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.125 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.150 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.150 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.150 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.150 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.181 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.181 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.181 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.181 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.209 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.209 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.209 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.209 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.236 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.236 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.236 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.236 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.267 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.268 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.268 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.268 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.294 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.294 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.294 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.294 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.352 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.352 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.352 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.352 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.379 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.379 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.379 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.379 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.418 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.418 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.418 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.418 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.447 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.447 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.447 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.447 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.484 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.484 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.484 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.484 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.510 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.510 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.510 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.510 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.552 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.553 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.553 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.553 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.578 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.578 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.578 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.578 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.606 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.606 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.606 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.606 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.658 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.659 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.659 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.659 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.708 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.708 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.708 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.708 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.745 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.745 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.746 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.746 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.778 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.778 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.778 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.778 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.814 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.814 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.814 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.814 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.856 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.857 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.857 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.857 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.891 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.891 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.891 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.891 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.927 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.927 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.927 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.927 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.968 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.968 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.968 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:20.968 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.003 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.003 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.003 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.003 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.042 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.042 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.042 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.042 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.077 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.077 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.077 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.077 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.109 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.109 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.109 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.110 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.156 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.156 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.156 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.156 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.196 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.196 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.196 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.196 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.230 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.230 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.230 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.230 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.270 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.271 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.271 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.271 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.309 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.310 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.310 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.315 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.356 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.363 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.363 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.363 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.405 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.405 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.405 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.405 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.449 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.451 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.452 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.452 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.501 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.501 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.501 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.501 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.543 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.544 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.544 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.544 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.581 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.581 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.581 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.581 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.625 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.625 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.625 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.625 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.664 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.664 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.664 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.664 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.714 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.714 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.714 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.714 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.751 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.752 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.752 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:21.752 DEBG generated response, 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.46s
[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" "9c7d6cbc5df700f5428e1db32489e7a6f2009b409488056a0fafd495fc76441d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9c7d6cbc5df700f5428e1db32489e7a6f2009b409488056a0fafd495fc76441d", kill_on_drop: false }`
[INFO] [stdout] 9c7d6cbc5df700f5428e1db32489e7a6f2009b409488056a0fafd495fc76441d