[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#432e145bd5a974c5b6f4dd9b352891bd7502b69d for pr-87041
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-6/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 432e145bd5a974c5b6f4dd9b352891bd7502b69d
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/joyent/rust-fast
[INFO] finished tweaking git repo https://github.com/joyent/rust-fast
[INFO] tweaked toml for git repo https://github.com/joyent/rust-fast written to /workspace/builds/worker-6/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0d0535e7da589d3ce35ea81b19a42b2c4d7c480052ec5cc2824c32ed6cfa481b
[INFO] running `Command { std: "docker" "start" "-a" "0d0535e7da589d3ce35ea81b19a42b2c4d7c480052ec5cc2824c32ed6cfa481b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0d0535e7da589d3ce35ea81b19a42b2c4d7c480052ec5cc2824c32ed6cfa481b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0d0535e7da589d3ce35ea81b19a42b2c4d7c480052ec5cc2824c32ed6cfa481b", kill_on_drop: false }`
[INFO] [stdout] 0d0535e7da589d3ce35ea81b19a42b2c4d7c480052ec5cc2824c32ed6cfa481b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ebcdd8bdfbb9081ec11a95079bc7c594716a70b4e4bb8396bda96c09cfe1cd82
[INFO] running `Command { std: "docker" "start" "-a" "ebcdd8bdfbb9081ec11a95079bc7c594716a70b4e4bb8396bda96c09cfe1cd82", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.98
[INFO] [stderr]    Compiling cfg-if v0.1.10
[INFO] [stderr]    Compiling lazy_static v1.4.0
[INFO] [stderr]    Compiling futures v0.1.31
[INFO] [stderr]    Compiling autocfg v0.1.7
[INFO] [stderr]    Compiling rand_core v0.4.2
[INFO] [stderr]    Compiling slab v0.4.3
[INFO] [stderr]    Compiling memchr v2.4.0
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling proc-macro2 v1.0.27
[INFO] [stderr]    Compiling serde v1.0.126
[INFO] [stderr]    Compiling syn v1.0.73
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling ryu v1.0.5
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling arc-swap v1.3.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling itoa v0.4.7
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling smallvec v0.6.14
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling rand_core v0.3.1
[INFO] [stderr]    Compiling rand_jitter v0.1.4
[INFO] [stderr]    Compiling rand_hc v0.1.0
[INFO] [stderr]    Compiling rand_isaac v0.1.1
[INFO] [stderr]    Compiling rand_xorshift v0.1.1
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling crossbeam-deque v0.7.3
[INFO] [stderr]    Compiling rand_pcg v0.1.2
[INFO] [stderr]    Compiling rand_chacha v0.1.1
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling net2 v0.2.37
[INFO] [stderr]    Compiling rand_os v0.1.3
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling serde_json v1.0.64
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling serde_derive v1.0.126
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 42.92s
[INFO] running `Command { std: "docker" "inspect" "ebcdd8bdfbb9081ec11a95079bc7c594716a70b4e4bb8396bda96c09cfe1cd82", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ebcdd8bdfbb9081ec11a95079bc7c594716a70b4e4bb8396bda96c09cfe1cd82", kill_on_drop: false }`
[INFO] [stdout] ebcdd8bdfbb9081ec11a95079bc7c594716a70b4e4bb8396bda96c09cfe1cd82
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8eeabcfd2cfc52b8e3d9fa9a5a83b73c18b7682b54527f4687ef3e590a853ade
[INFO] running `Command { std: "docker" "start" "-a" "8eeabcfd2cfc52b8e3d9fa9a5a83b73c18b7682b54527f4687ef3e590a853ade", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling once_cell v1.8.0
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling thread_local v1.1.3
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling slog-term v2.8.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 14.80s
[INFO] running `Command { std: "docker" "inspect" "8eeabcfd2cfc52b8e3d9fa9a5a83b73c18b7682b54527f4687ef3e590a853ade", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8eeabcfd2cfc52b8e3d9fa9a5a83b73c18b7682b54527f4687ef3e590a853ade", kill_on_drop: false }`
[INFO] [stdout] 8eeabcfd2cfc52b8e3d9fa9a5a83b73c18b7682b54527f4687ef3e590a853ade
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+432e145bd5a974c5b6f4dd9b352891bd7502b69d" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] daff46a565481412b1a08643a66640502e22bbc772cc72e0275dc43cf3530771
[INFO] running `Command { std: "docker" "start" "-a" "daff46a565481412b1a08643a66640502e22bbc772cc72e0275dc43cf3530771", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-c18e93ca5995ea2d)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test protocol::test::prop_fast_message_roundtrip ... ok
[INFO] [stdout] test protocol::test::prop_fast_message_bundling ... ok
[INFO] [stdout] test protocol::test::prop_fast_message_decoding ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.51s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-cdb1138006bdedb5)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Jul 19 13:48:45.611 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.682 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.682 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.682 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.682 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.687 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.687 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.687 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.687 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.695 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.695 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.695 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.695 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.704 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.704 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.704 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.704 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.716 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.716 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.716 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.716 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.721 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.721 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.721 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.721 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.738 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.738 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.738 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.738 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.748 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.748 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.749 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.749 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.756 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.756 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.756 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.757 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.762 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.762 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.763 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.763 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.769 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.769 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.769 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.770 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.779 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.780 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.780 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.780 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.787 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.787 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.787 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.787 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.803 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.803 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.803 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.803 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.817 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.817 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.817 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.817 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.826 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.826 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.826 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.827 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.840 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.840 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.840 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.840 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.854 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.854 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.854 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.854 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.865 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.865 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.865 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.865 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.886 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.886 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.886 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.886 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.897 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.897 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.897 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.897 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.914 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.914 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.914 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.914 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.927 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.927 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.927 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.927 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.940 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.940 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.940 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.941 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.954 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.954 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.954 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.954 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.968 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.969 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.969 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.969 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.983 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.983 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.983 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.983 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.999 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.999 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.999 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:45.999 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.014 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.014 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.014 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.014 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.030 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.031 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.031 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.031 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.047 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.047 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.047 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.048 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.065 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.065 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.065 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.065 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.083 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.083 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.083 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.083 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.101 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.101 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.101 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.102 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.121 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.121 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.121 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.121 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.141 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.141 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.141 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.141 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.169 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.169 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.169 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.169 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.192 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.192 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.192 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.193 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.213 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.214 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.214 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.214 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.235 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.235 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.235 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.235 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.257 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.257 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.257 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.257 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.279 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.279 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.279 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.280 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.302 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.302 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.303 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.303 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.327 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.327 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.327 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.327 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.351 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.351 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.351 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.351 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.379 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.379 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.379 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.379 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.407 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.407 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.407 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.407 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.444 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.444 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.444 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.444 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.470 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.470 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.470 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.470 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.504 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.504 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.504 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.504 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.533 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.533 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.534 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.534 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.561 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.562 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.563 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.563 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.601 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.601 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.601 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.602 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.644 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.644 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.644 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.644 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.677 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.677 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.677 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.678 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.707 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.707 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.708 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.708 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.742 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.742 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.742 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.742 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.773 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.774 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.774 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.774 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.806 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.806 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.806 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.806 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.842 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.842 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.842 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.842 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.875 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.875 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.875 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.875 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.909 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.909 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.909 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.909 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.943 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.943 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.943 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.943 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.977 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.977 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.977 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:46.977 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.011 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.012 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.012 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.012 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.054 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.055 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.055 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.055 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.090 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.090 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.090 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.090 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.133 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.133 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.134 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.134 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.175 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.176 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.176 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.176 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.211 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.211 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.211 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.211 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.249 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.249 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.249 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.249 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.286 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.286 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.286 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.286 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.331 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.331 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.332 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.332 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.377 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.377 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.377 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.377 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.423 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.424 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.424 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.424 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.466 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.474 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.474 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.474 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.538 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.538 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.538 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.538 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.585 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.585 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.585 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.585 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.634 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.634 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.634 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.634 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.696 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.696 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.696 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.696 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.737 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.737 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.737 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.737 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.784 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.784 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.784 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.784 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.827 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.827 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.827 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.827 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.869 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.869 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.869 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.870 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.917 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.917 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.917 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.918 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.965 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.965 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.966 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:47.966 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.017 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.017 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.018 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.018 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.063 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.063 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.063 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.063 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.109 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.109 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.109 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.109 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.169 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.169 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.169 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.169 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.232 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.232 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.232 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.232 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.295 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.295 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.295 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.295 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.356 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.357 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.357 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.357 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.414 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.414 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.414 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.414 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.467 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.468 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.468 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.468 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.523 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.523 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.523 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.523 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.577 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.577 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.577 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.577 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.628 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.628 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.628 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.628 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.679 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.679 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.679 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jul 19 13:48:48.680 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] test client_server_comms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.11s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests fast-rpc
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "daff46a565481412b1a08643a66640502e22bbc772cc72e0275dc43cf3530771", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "daff46a565481412b1a08643a66640502e22bbc772cc72e0275dc43cf3530771", kill_on_drop: false }`
[INFO] [stdout] daff46a565481412b1a08643a66640502e22bbc772cc72e0275dc43cf3530771