[INFO] updating cached repository https://github.com/joyent/rust-fast
[INFO] running `"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"`
[INFO] [stderr] From https://github.com/joyent/rust-fast
[INFO] [stderr]    6aca860..1c79380  rust-1.40  -> rust-1.40
[INFO] running `"git" "rev-parse" "HEAD"`
[INFO] [stdout] aa65a63adb90ba9b663e5570453747460900a7a4
[INFO] testing joyent/rust-fast against try#41997647ba6a77908f6ef64401414feb00bccf16 for pr-71274
[INFO] running `"git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-3/source"`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 41997647ba6a77908f6ef64401414feb00bccf16
[INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "read-manifest" "--manifest-path" "Cargo.toml"`
[INFO] started tweaking git repo https://github.com/joyent/rust-fast
[INFO] finished tweaking git repo https://github.com/joyent/rust-fast
[INFO] tweaked toml for git repo https://github.com/joyent/rust-fast written to /workspace/builds/worker-3/source/Cargo.toml
[INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update"`
[INFO] running `"/workspace/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "fetch" "--locked" "--manifest-path" "Cargo.toml"`
[INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "build" "--frozen"`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 76baa2d1085c9a3b753332870013989a28d02737b406a485105302a8ea489bfa
[INFO] running `"docker" "start" "-a" "76baa2d1085c9a3b753332870013989a28d02737b406a485105302a8ea489bfa"`
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling log v0.4.8
[INFO] [stderr]    Compiling maybe-uninit v2.0.0
[INFO] [stderr]    Compiling futures v0.1.29
[INFO] [stderr]    Compiling slab v0.4.2
[INFO] [stderr]    Compiling proc-macro2 v1.0.10
[INFO] [stderr]    Compiling fnv v1.0.6
[INFO] [stderr]    Compiling unicode-xid v0.2.0
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling arc-swap v0.4.6
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling num-traits v0.2.11
[INFO] [stderr]    Compiling memoffset v0.5.4
[INFO] [stderr]    Compiling num-integer v0.1.42
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.40
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling net2 v0.2.33
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling slog-scope v4.3.0
[INFO] [stderr]    Compiling smallvec v0.6.13
[INFO] [stderr]    Compiling mio v0.6.21
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling crossbeam-queue v0.2.1
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling quote v1.0.3
[INFO] [stderr]    Compiling crossbeam-deque v0.7.3
[INFO] [stderr]    Compiling syn v1.0.18
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling mio-uds v0.6.7
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling chrono v0.4.11
[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 tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio-uds v0.2.6
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling num-derive v0.3.0
[INFO] [stderr]    Compiling serde_derive v1.0.106
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 22s
[INFO] running `"docker" "inspect" "76baa2d1085c9a3b753332870013989a28d02737b406a485105302a8ea489bfa"`
[INFO] running `"docker" "rm" "-f" "76baa2d1085c9a3b753332870013989a28d02737b406a485105302a8ea489bfa"`
[INFO] [stdout] 76baa2d1085c9a3b753332870013989a28d02737b406a485105302a8ea489bfa
[INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "test" "--frozen" "--no-run"`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 7a5f3936fa5f93021571126dfa93701bb9344f5f31e0b670301d93fd17a6fb5f
[INFO] running `"docker" "start" "-a" "7a5f3936fa5f93021571126dfa93701bb9344f5f31e0b670301d93fd17a6fb5f"`
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling vec_map v0.8.1
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling ansi_term v0.11.0
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling dirs-sys v0.3.4
[INFO] [stderr]    Compiling dirs v2.0.2
[INFO] [stderr]    Compiling term v0.6.1
[INFO] [stderr]    Compiling clap v2.33.0
[INFO] [stderr]    Compiling slog-term v2.5.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 33.19s
[INFO] running `"docker" "inspect" "7a5f3936fa5f93021571126dfa93701bb9344f5f31e0b670301d93fd17a6fb5f"`
[INFO] running `"docker" "rm" "-f" "7a5f3936fa5f93021571126dfa93701bb9344f5f31e0b670301d93fd17a6fb5f"`
[INFO] [stdout] 7a5f3936fa5f93021571126dfa93701bb9344f5f31e0b670301d93fd17a6fb5f
[INFO] running `"docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env" "/opt/rustwide/cargo-home/bin/cargo" "+41997647ba6a77908f6ef64401414feb00bccf16" "test" "--frozen"`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 11bb6b1a6991d79f66527eeb6d1bdc7aa3a4396eac5db20462f215188ddd3d19
[INFO] running `"docker" "start" "-a" "11bb6b1a6991d79f66527eeb6d1bdc7aa3a4396eac5db20462f215188ddd3d19"`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.12s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/fast_rpc-815aa1f11cc35d18
[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-b95c5a81be79cab7
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Apr 25 21:45:02.290 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.316 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.316 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.316 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.316 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.335 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.335 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.335 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.335 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.343 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.343 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.343 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.343 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.351 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.351 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.351 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.351 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.367 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.367 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.367 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.367 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.375 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.375 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.375 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.375 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.391 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.391 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.391 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.392 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.405 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.405 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.405 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.406 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.418 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.418 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.418 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.418 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.423 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.423 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.423 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.423 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.441 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.441 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.441 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.441 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.447 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.448 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.448 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.448 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.468 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.468 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.468 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.468 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.476 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.477 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.477 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.477 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.485 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.486 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.486 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.486 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.494 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.494 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.494 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.494 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.502 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.502 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.502 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.503 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.514 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.514 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.514 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.514 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.524 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.525 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.525 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.525 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.535 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.535 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.535 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.535 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.549 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.549 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.549 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.549 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.560 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.560 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.560 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.560 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.576 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.576 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.576 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.576 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.592 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.593 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.593 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.594 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.606 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.607 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.608 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.608 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.622 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.623 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.623 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.623 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.638 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.639 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.639 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.639 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.654 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.655 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.656 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.656 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.670 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.670 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.671 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.671 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.686 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.686 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.686 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.686 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.702 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.702 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.703 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.703 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.720 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.723 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.723 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.723 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.742 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.743 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.743 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.744 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.771 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.771 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.772 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.772 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.789 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.794 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.795 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.795 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.819 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.819 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.820 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.820 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.843 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.843 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.843 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.844 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.868 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.870 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.870 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.871 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.890 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.891 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.891 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.892 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.912 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.912 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.912 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.912 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.932 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.932 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.932 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.932 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.960 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.960 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.960 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.960 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.981 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.981 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.981 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:02.981 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.008 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.008 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.008 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.009 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.030 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.030 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.030 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.030 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.054 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.054 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.054 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.054 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.077 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.077 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.077 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.077 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.112 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.112 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.112 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.113 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.135 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.136 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.136 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.137 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.160 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.161 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.162 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.162 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.186 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.187 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.188 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.188 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.215 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.215 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.215 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.215 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.248 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.248 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.249 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.249 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.274 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.275 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.275 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.276 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.307 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.307 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.307 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.308 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.343 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.344 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.344 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.344 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.382 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.382 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.382 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.382 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.414 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.414 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.414 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.414 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.448 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.448 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.448 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.448 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.477 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.477 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.477 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.477 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.509 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.509 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.509 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.510 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.543 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.543 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.543 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.543 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.578 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.578 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.578 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.578 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.614 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.614 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.615 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.615 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.646 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.646 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.646 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.646 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.677 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.677 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.677 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.677 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.713 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.713 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.713 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.714 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.746 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.746 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.746 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.746 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.782 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.782 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.782 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.782 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.820 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.820 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.820 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.821 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.862 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.866 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.866 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.867 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.901 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.901 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.901 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.901 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.936 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.936 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.936 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.937 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.971 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.971 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.971 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:03.972 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.020 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.021 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.021 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.021 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.072 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.072 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.072 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.072 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.114 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.115 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.115 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.115 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.152 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.154 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.154 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.154 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.249 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.250 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.250 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.250 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.299 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.300 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.300 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.301 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.344 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.348 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.349 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.350 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.401 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.406 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.407 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.408 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.476 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.486 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.487 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.488 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.532 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.532 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.533 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.534 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.575 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.575 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.576 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.577 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.618 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.619 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.619 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.620 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.662 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.662 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.663 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.664 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.713 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.714 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.715 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.716 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.766 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.767 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.768 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.768 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.815 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.816 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.817 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.818 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.866 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.866 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.867 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.867 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.915 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.916 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.916 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.917 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.966 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.966 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.966 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:04.966 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.022 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.022 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.022 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.022 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.067 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.067 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.067 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.067 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.116 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.116 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.116 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.116 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.166 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.166 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.167 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.167 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.218 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.218 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.218 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.218 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.273 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.273 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.273 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.273 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Apr 25 21:45:05.299 DEBG transmitted response to client, build-id: 0.1.0
[INFO] [stdout] test client_server_comms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[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 `"docker" "inspect" "11bb6b1a6991d79f66527eeb6d1bdc7aa3a4396eac5db20462f215188ddd3d19"`
[INFO] running `"docker" "rm" "-f" "11bb6b1a6991d79f66527eeb6d1bdc7aa3a4396eac5db20462f215188ddd3d19"`
[INFO] [stdout] 11bb6b1a6991d79f66527eeb6d1bdc7aa3a4396eac5db20462f215188ddd3d19