[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 master#5d5ff84130da0d74c6ece368dbe821d8f83fa526 for pr-79296
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-5/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 5d5ff84130da0d74c6ece368dbe821d8f83fa526
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "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-5/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "rustops/crates-build-env@sha256:6eabd152ff4036248d66efda456a36cb33d24b7291b33f25f75140726c88da35" "/opt/rustwide/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 3bc6a40b5ebb70cf6afcb0e0aec05aa0a86dab52fe7ec8740ed2c2ce145a45bb
[INFO] running `Command { std: "docker" "start" "-a" "3bc6a40b5ebb70cf6afcb0e0aec05aa0a86dab52fe7ec8740ed2c2ce145a45bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3bc6a40b5ebb70cf6afcb0e0aec05aa0a86dab52fe7ec8740ed2c2ce145a45bb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3bc6a40b5ebb70cf6afcb0e0aec05aa0a86dab52fe7ec8740ed2c2ce145a45bb", kill_on_drop: false }`
[INFO] [stdout] 3bc6a40b5ebb70cf6afcb0e0aec05aa0a86dab52fe7ec8740ed2c2ce145a45bb
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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" "rustops/crates-build-env@sha256:6eabd152ff4036248d66efda456a36cb33d24b7291b33f25f75140726c88da35" "/opt/rustwide/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8dab7ba13a8755777f1ebef017dcf6c3e0d0e55f6fc1a53efdaa49dd772f0417
[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" "8dab7ba13a8755777f1ebef017dcf6c3e0d0e55f6fc1a53efdaa49dd772f0417", kill_on_drop: false }`
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling semver-parser v0.7.0
[INFO] [stderr]    Compiling futures v0.1.30
[INFO] [stderr]    Compiling scopeguard v1.1.0
[INFO] [stderr]    Compiling byteorder v1.3.4
[INFO] [stderr]    Compiling rand_core v0.4.2
[INFO] [stderr]    Compiling slab v0.4.2
[INFO] [stderr]    Compiling autocfg v0.1.7
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling regex-syntax v0.6.21
[INFO] [stderr]    Compiling arc-swap v0.4.7
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[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-complex v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling lock_api v0.3.4
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling rand_core v0.3.1
[INFO] [stderr]    Compiling rand_jitter v0.1.4
[INFO] [stderr]    Compiling log v0.4.11
[INFO] [stderr]    Compiling rand_xorshift v0.1.1
[INFO] [stderr]    Compiling rand_isaac v0.1.1
[INFO] [stderr]    Compiling rand_hc v0.1.0
[INFO] [stderr]    Compiling net2 v0.2.36
[INFO] [stderr]    Compiling rand_os v0.1.3
[INFO] [stderr]    Compiling smallvec v0.6.13
[INFO] [stderr]    Compiling serde_json v1.0.59
[INFO] [stderr]    Compiling rand_chacha v0.1.1
[INFO] [stderr]    Compiling rand_pcg v0.1.2
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling syn v1.0.53
[INFO] [stderr]    Compiling rustc_version v0.2.3
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling mio v0.6.22
[INFO] [stderr]    Compiling slog-scope v4.3.0
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling crossbeam-deque v0.7.3
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling regex v1.4.2
[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 serde_derive v1.0.117
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling quickcheck v0.8.5
[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" "8dab7ba13a8755777f1ebef017dcf6c3e0d0e55f6fc1a53efdaa49dd772f0417", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8dab7ba13a8755777f1ebef017dcf6c3e0d0e55f6fc1a53efdaa49dd772f0417", kill_on_drop: false }`
[INFO] [stdout] 8dab7ba13a8755777f1ebef017dcf6c3e0d0e55f6fc1a53efdaa49dd772f0417
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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" "rustops/crates-build-env@sha256:6eabd152ff4036248d66efda456a36cb33d24b7291b33f25f75140726c88da35" "/opt/rustwide/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 17947fd059d7287553eb5a051c2fdb1f2574104ac5ad1f0dce233c429fa1894b
[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" "17947fd059d7287553eb5a051c2fdb1f2574104ac5ad1f0dce233c429fa1894b", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling dirs v2.0.2
[INFO] [stderr]    Compiling term v0.6.1
[INFO] [stderr]    Compiling clap v2.33.3
[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 26.65s
[INFO] running `Command { std: "docker" "inspect" "17947fd059d7287553eb5a051c2fdb1f2574104ac5ad1f0dce233c429fa1894b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "17947fd059d7287553eb5a051c2fdb1f2574104ac5ad1f0dce233c429fa1894b", kill_on_drop: false }`
[INFO] [stdout] 17947fd059d7287553eb5a051c2fdb1f2574104ac5ad1f0dce233c429fa1894b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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" "rustops/crates-build-env@sha256:6eabd152ff4036248d66efda456a36cb33d24b7291b33f25f75140726c88da35" "/opt/rustwide/cargo-home/bin/cargo" "+5d5ff84130da0d74c6ece368dbe821d8f83fa526" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 1665cb8a77d6e6580793dd9c0ef6345bded8c56becc661d4ac1f5827758952f3
[INFO] running `Command { std: "docker" "start" "-a" "1665cb8a77d6e6580793dd9c0ef6345bded8c56becc661d4ac1f5827758952f3", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/fast_rpc-5655750864f91142
[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] [stderr]      Running /opt/rustwide/target/debug/deps/client_server_test-9bbf23018e9438e5
[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] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Nov 30 23:12:57.188 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.196 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.196 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.196 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.196 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.204 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.204 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.204 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.204 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.206 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.206 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.206 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.206 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.212 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.212 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.212 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.212 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.215 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.215 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.215 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.215 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.222 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.222 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.222 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.222 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.225 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.225 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.225 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.225 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.236 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.236 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.236 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.236 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.241 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.241 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.241 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.241 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.246 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.246 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.246 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.246 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.251 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.252 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.252 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.252 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.257 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.257 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.257 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.257 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.264 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.264 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.264 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.264 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.277 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.277 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.277 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.277 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.285 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.285 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.285 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.285 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.293 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.293 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.293 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.293 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.301 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.301 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.301 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.301 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.310 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.310 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.310 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.310 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.319 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.319 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.319 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.319 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.334 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.334 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.334 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.334 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.345 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.345 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.345 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.345 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.356 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.356 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.356 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.356 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.368 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.368 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.368 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.368 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.380 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.380 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.380 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.380 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.396 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.396 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.396 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.396 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.409 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.409 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.409 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.409 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.421 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.421 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.421 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.421 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.434 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.434 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.434 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.434 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.455 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.455 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.455 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.455 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.470 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.470 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.470 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.470 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.484 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.484 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.484 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.484 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.498 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.499 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.499 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.499 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.513 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.514 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.514 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.514 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.529 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.529 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.530 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.530 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.546 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.546 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.546 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.546 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.562 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.562 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.562 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.562 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.579 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.579 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.579 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.579 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.596 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.596 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.596 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.597 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.614 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.614 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.615 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.615 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.633 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.633 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.633 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.633 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.652 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.652 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.653 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.653 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.673 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.673 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.673 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.673 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.693 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.693 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.694 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.694 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.713 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.713 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.713 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.713 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.733 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.734 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.734 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.734 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.754 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.754 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.754 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.754 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.775 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.775 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.775 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.775 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.796 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.796 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.796 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.796 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.818 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.819 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.819 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.819 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.842 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.842 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.842 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.842 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.864 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.865 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.865 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.865 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.888 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.888 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.888 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.888 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.912 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.913 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.913 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.913 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.937 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.937 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.937 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.938 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.962 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.962 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.962 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.962 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.987 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.988 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.988 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:57.988 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.013 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.013 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.013 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.013 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.043 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.043 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.043 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.044 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.077 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.077 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.077 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.077 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.105 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.105 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.105 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.105 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.132 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.132 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.132 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.132 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.161 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.161 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.161 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.161 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.191 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.191 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.191 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.191 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.227 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.227 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.227 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.227 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.257 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.257 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.258 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.258 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.291 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.291 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.291 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.291 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.322 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.322 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.322 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.322 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.368 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.368 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.368 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.368 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.406 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.407 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.407 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.407 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.446 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.446 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.446 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.446 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.486 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.487 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.487 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.487 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.526 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.527 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.527 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.527 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.567 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.567 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.567 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.567 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.612 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.613 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.613 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.613 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.646 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.646 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.646 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.647 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.683 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.683 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.683 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.683 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.718 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.718 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.718 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.718 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.765 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.766 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.766 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.766 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.806 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.806 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.807 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.807 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.850 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.851 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.851 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.851 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.895 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.895 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.895 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.895 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.941 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.941 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.941 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.941 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.982 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.983 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.983 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:58.983 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.024 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.024 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.024 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.024 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.068 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.068 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.068 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.068 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.112 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.112 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.112 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.112 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.152 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.152 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.152 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.152 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.192 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.192 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.192 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.192 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.233 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.233 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.233 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.234 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.274 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.274 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.275 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.275 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.322 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.322 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.322 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.322 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.382 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.382 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.383 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.383 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.439 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.439 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.439 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.439 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.499 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.499 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.499 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.499 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.550 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.551 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.551 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.551 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.610 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.610 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.610 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.610 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.663 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.663 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.663 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.663 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.716 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.716 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.716 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.716 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.772 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.772 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.772 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 30 23:12:59.772 DEBG generated response, build-id: 0.1.0
[INFO] [stderr]    Doc-tests fast-rpc
[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] [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" "1665cb8a77d6e6580793dd9c0ef6345bded8c56becc661d4ac1f5827758952f3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1665cb8a77d6e6580793dd9c0ef6345bded8c56becc661d4ac1f5827758952f3", kill_on_drop: false }`
[INFO] [stdout] 1665cb8a77d6e6580793dd9c0ef6345bded8c56becc661d4ac1f5827758952f3