[INFO] updating cached 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" "-c" "remote.origin.fetch=refs/heads/*:refs/heads/*" "fetch" "origin" "--force" "--prune", kill_on_drop: false }`
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1ddf01bba94c010d5627bf0eb0ff260b8085a460
[INFO] testing joyent/rust-fast against 1.45.2 for beta-1.46-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 1.45.2
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.45.2" "read-manifest" "--manifest-path" "Cargo.toml", 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-2/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.45.2" "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" "+1.45.2" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env@sha256:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 09390db26de4ad34925f2e0b2d7071a66c3fe7a7bbff56500bbd63607adc83d2
[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" "09390db26de4ad34925f2e0b2d7071a66c3fe7a7bbff56500bbd63607adc83d2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "09390db26de4ad34925f2e0b2d7071a66c3fe7a7bbff56500bbd63607adc83d2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "09390db26de4ad34925f2e0b2d7071a66c3fe7a7bbff56500bbd63607adc83d2", kill_on_drop: false }`
[INFO] [stdout] 09390db26de4ad34925f2e0b2d7071a66c3fe7a7bbff56500bbd63607adc83d2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 374679d1df8d0708cf285af8907b812ae650cf5473ace461e40b88b579962d49
[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" "374679d1df8d0708cf285af8907b812ae650cf5473ace461e40b88b579962d49", kill_on_drop: false }`
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling num-iter v0.1.41
[INFO] [stderr]    Compiling slog-scope v4.3.0
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling chrono v0.4.13
[INFO] [stderr]    Compiling num-derive v0.3.1
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.27s
[INFO] running `Command { std: "docker" "inspect" "374679d1df8d0708cf285af8907b812ae650cf5473ace461e40b88b579962d49", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "374679d1df8d0708cf285af8907b812ae650cf5473ace461e40b88b579962d49", kill_on_drop: false }`
[INFO] [stdout] 374679d1df8d0708cf285af8907b812ae650cf5473ace461e40b88b579962d49
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9bedd39c448b0f7b7af2ddec5cefb0c7953e0f4594244ea6ed5193d2e5849891
[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" "9bedd39c448b0f7b7af2ddec5cefb0c7953e0f4594244ea6ed5193d2e5849891", kill_on_drop: false }`
[INFO] [stderr]    Compiling term v0.6.1
[INFO] [stderr]    Compiling slog-term v2.6.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 14.24s
[INFO] running `Command { std: "docker" "inspect" "9bedd39c448b0f7b7af2ddec5cefb0c7953e0f4594244ea6ed5193d2e5849891", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9bedd39c448b0f7b7af2ddec5cefb0c7953e0f4594244ea6ed5193d2e5849891", kill_on_drop: false }`
[INFO] [stdout] 9bedd39c448b0f7b7af2ddec5cefb0c7953e0f4594244ea6ed5193d2e5849891
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--network" "none" "rustops/crates-build-env@sha256:a636fd89d022f18117e1dc8fb37a0a009933cdbbe082814857f0e2024962d47c" "/opt/rustwide/cargo-home/bin/cargo" "+1.45.2" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a3118e56211a4b4f7f0f9a6004d366f327cead9604e52d11e8fa63ef7ea4cd5a
[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" "a3118e56211a4b4f7f0f9a6004d366f327cead9604e52d11e8fa63ef7ea4cd5a", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.44s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/fast_rpc-47e3cc682fa6193c
[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
[INFO] [stdout] 
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/client_server_test-96c473832c1013a5
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Aug 09 19:57:15.917 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.945 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.945 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.945 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.945 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.949 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.950 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.950 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.950 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.954 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.954 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.954 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.954 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.962 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.962 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.962 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.962 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.969 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.969 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.969 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.969 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.973 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.973 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.973 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.973 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.976 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.976 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.976 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.976 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.990 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.990 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.990 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.990 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.994 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.995 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.995 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:15.995 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.004 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.004 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.004 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.004 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.009 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.009 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.009 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.009 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.021 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.021 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.021 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.021 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.030 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.030 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.030 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.031 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.037 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.045 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.045 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.045 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.052 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.052 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.053 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.053 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.060 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.061 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.061 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.061 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.069 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.069 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.069 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.069 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.078 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.085 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.085 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.085 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.099 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.100 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.100 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.100 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.111 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.111 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.111 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.111 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.127 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.127 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.127 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.127 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.138 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.138 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.138 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.138 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.152 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.153 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.153 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.153 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.165 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.169 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.169 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.170 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.181 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.182 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.183 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.183 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.204 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.204 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.205 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.206 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.218 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.219 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.219 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.220 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.233 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.233 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.234 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.235 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.252 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.253 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.253 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.254 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.268 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.268 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.269 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.269 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.284 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.284 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.285 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.286 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.300 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.309 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.310 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.310 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.332 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.333 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.333 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.334 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.351 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.352 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.352 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.353 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.378 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.378 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.378 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.378 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.396 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.396 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.397 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.397 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.419 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.419 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.420 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.420 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.438 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.438 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.439 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.439 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.457 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.458 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.458 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.458 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.476 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.477 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.477 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.477 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.500 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.501 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.501 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.501 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.520 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.521 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.521 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.521 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.543 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.548 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.548 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.549 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.569 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.573 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.573 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.573 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.594 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.596 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.596 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.596 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.619 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.619 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.619 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.619 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.645 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.645 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.645 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.645 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.669 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.670 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.670 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.670 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.693 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.696 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.696 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.696 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.722 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.723 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.723 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.723 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.745 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.745 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.745 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.745 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.768 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.768 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.768 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.768 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.795 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.795 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.795 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.796 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.819 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.820 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.820 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.820 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.844 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.849 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.849 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.849 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.873 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.873 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.874 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.874 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.898 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.899 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.899 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.899 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.926 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.927 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.927 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.927 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.961 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.961 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.961 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.961 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.990 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.990 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.990 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:16.990 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.022 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.022 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.022 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.022 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.053 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.053 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.053 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.053 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.088 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.088 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.088 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.088 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.115 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.116 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.116 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.116 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.148 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.149 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.150 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.150 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.183 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.183 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.183 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.184 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.217 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.217 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.217 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.217 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.247 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.253 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.253 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.253 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.283 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.283 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.283 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.283 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.314 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.314 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.314 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.314 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.348 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.348 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.348 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.348 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.383 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.383 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.384 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.384 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.416 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.416 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.416 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.417 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.457 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.461 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.461 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.461 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.502 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.505 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.505 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.505 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.552 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.552 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.552 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.552 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.601 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.602 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.602 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.602 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.646 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.646 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.646 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.646 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.685 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.685 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.685 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.685 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.732 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.732 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.732 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.732 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.781 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.781 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.781 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.781 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.821 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.821 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.821 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.822 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.858 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.858 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.858 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.858 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.912 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.912 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.912 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.912 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.961 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.961 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.961 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:17.961 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.012 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.012 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.012 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.012 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.053 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.053 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.053 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.053 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.099 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.100 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.100 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.100 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.146 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.146 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.146 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.146 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.198 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.198 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.198 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.198 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.246 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.246 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.246 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.246 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.297 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.297 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.297 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.297 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.355 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.355 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.355 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.355 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.418 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.418 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.418 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.418 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.484 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.484 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.484 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.484 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.541 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.541 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.541 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.541 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.596 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.596 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.596 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.596 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.649 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.649 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.649 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.649 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.743 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.743 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.743 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Aug 09 19:57:18.743 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
[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
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "a3118e56211a4b4f7f0f9a6004d366f327cead9604e52d11e8fa63ef7ea4cd5a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a3118e56211a4b4f7f0f9a6004d366f327cead9604e52d11e8fa63ef7ea4cd5a", kill_on_drop: false }`
[INFO] [stdout] a3118e56211a4b4f7f0f9a6004d366f327cead9604e52d11e8fa63ef7ea4cd5a