[INFO] fetching crate fast-rpc 0.3.0...
[INFO] testing fast-rpc-0.3.0 against 1.55.0 for beta-1.56-1
[INFO] extracting crate fast-rpc 0.3.0 into /workspace/builds/worker-13/source
[INFO] validating manifest of crates.io crate fast-rpc 0.3.0 on toolchain 1.55.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate fast-rpc 0.3.0
[INFO] finished tweaking crates.io crate fast-rpc 0.3.0
[INFO] tweaked toml for crates.io crate fast-rpc 0.3.0 written to /workspace/builds/worker-13/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.55.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 834049f53dd7f8e61d5e061396ac1303bc1c0d218b4aeebf600723306300103b
[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" "834049f53dd7f8e61d5e061396ac1303bc1c0d218b4aeebf600723306300103b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "834049f53dd7f8e61d5e061396ac1303bc1c0d218b4aeebf600723306300103b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "834049f53dd7f8e61d5e061396ac1303bc1c0d218b4aeebf600723306300103b", kill_on_drop: false }`
[INFO] [stdout] 834049f53dd7f8e61d5e061396ac1303bc1c0d218b4aeebf600723306300103b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 73a5b7b59107615755409007956ba80fdb71518571942e1e3b68a91b3f723773
[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" "73a5b7b59107615755409007956ba80fdb71518571942e1e3b68a91b3f723773", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling arc-swap v1.3.2
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[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 num v0.2.1
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.46s
[INFO] running `Command { std: "docker" "inspect" "73a5b7b59107615755409007956ba80fdb71518571942e1e3b68a91b3f723773", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73a5b7b59107615755409007956ba80fdb71518571942e1e3b68a91b3f723773", kill_on_drop: false }`
[INFO] [stdout] 73a5b7b59107615755409007956ba80fdb71518571942e1e3b68a91b3f723773
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8ea08c32125cbda62500e5528e2effee130d9fa5b2a7ea16c2fac3808556d00e
[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" "8ea08c32125cbda62500e5528e2effee130d9fa5b2a7ea16c2fac3808556d00e", kill_on_drop: false }`
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling slog-term v2.8.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 13.38s
[INFO] running `Command { std: "docker" "inspect" "8ea08c32125cbda62500e5528e2effee130d9fa5b2a7ea16c2fac3808556d00e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ea08c32125cbda62500e5528e2effee130d9fa5b2a7ea16c2fac3808556d00e", kill_on_drop: false }`
[INFO] [stdout] 8ea08c32125cbda62500e5528e2effee130d9fa5b2a7ea16c2fac3808556d00e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-13/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=warn" "-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:a89201d6b5b9fd45c15d5952ece0a0129e7e80cb26ec023fd59cf09bc26f1604" "/opt/rustwide/cargo-home/bin/cargo" "+1.55.0" "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] 6ce22b55c488dfc743e8db839a0b32e7f68cdcaea90d4c85962d3367708711fe
[INFO] running `Command { std: "docker" "start" "-a" "6ce22b55c488dfc743e8db839a0b32e7f68cdcaea90d4c85962d3367708711fe", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-24478094bdb577d5)
[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 tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-28e1143deb58c677)
[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.21s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Sep 10 23:20:44.943 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.950 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.950 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.950 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.950 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.958 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.958 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.958 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.958 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.960 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.960 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.960 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.960 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.967 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.967 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.967 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.967 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.969 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.969 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.969 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.969 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.972 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.973 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.973 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.973 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.976 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.976 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.976 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.976 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.979 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.979 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.979 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.979 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.990 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.990 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.990 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.990 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.995 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.995 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.995 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.995 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:44.999 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.000 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.000 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.000 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.006 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.006 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.006 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.006 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.012 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.012 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.012 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.012 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.018 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.018 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.018 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.018 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.024 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.024 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.025 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.025 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.031 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.031 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.031 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.031 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.038 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.038 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.038 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.038 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.045 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.045 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.046 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.046 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.056 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.056 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.056 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.056 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.064 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.064 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.064 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.064 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.072 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.072 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.072 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.072 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.080 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.080 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.081 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.081 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.094 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.094 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.094 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.094 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.103 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.103 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.103 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.103 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.113 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.113 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.113 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.113 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.123 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.123 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.123 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.123 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.136 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.136 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.136 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.136 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.147 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.148 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.148 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.148 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.159 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.159 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.159 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.159 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.171 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.171 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.171 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.171 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.183 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.184 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.184 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.184 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.196 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.196 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.196 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.196 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.209 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.209 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.209 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.209 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.223 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.223 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.223 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.223 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.237 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.237 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.237 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.237 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.251 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.251 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.251 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.251 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.266 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.266 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.266 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.266 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.281 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.281 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.281 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.281 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.296 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.296 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.296 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.296 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.313 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.313 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.313 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.313 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.337 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.337 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.337 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.337 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.354 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.354 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.354 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.354 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.371 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.371 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.371 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.371 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.389 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.389 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.389 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.389 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.412 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.412 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.412 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.412 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.440 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.440 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.440 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.440 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.471 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.472 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.472 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.472 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.504 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.504 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.504 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.504 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.531 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.532 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.532 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.532 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.562 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.562 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.562 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.562 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.587 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.587 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.588 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.588 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.628 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.628 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.628 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.628 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.656 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.656 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.657 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.657 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.692 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.692 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.692 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.692 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.744 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.744 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.744 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.744 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.779 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.779 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.779 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.779 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.807 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.807 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.807 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.807 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.830 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.830 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.830 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.830 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.858 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.858 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.858 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.858 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.882 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.882 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.882 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.882 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.905 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.906 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.906 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.906 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.933 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.933 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.933 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.933 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.965 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.965 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.965 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:45.965 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.000 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.000 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.000 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.000 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.037 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.037 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.037 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.037 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.066 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.067 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.067 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.067 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.095 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.095 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.095 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.095 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.120 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.120 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.120 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.120 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.157 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.157 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.157 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.168 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.196 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.196 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.196 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.196 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.234 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.234 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.234 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.234 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.267 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.267 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.267 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.267 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.305 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.305 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.305 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.305 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.355 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.355 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.355 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.355 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.391 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.391 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.391 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.391 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.433 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.433 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.434 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.434 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.464 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.464 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.464 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.464 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.524 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.524 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.524 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.524 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.567 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.567 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.567 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.567 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.616 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.616 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.616 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.616 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.651 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.651 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.651 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.651 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.697 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.697 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.697 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.697 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.743 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.743 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.743 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.744 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.777 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.777 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.777 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.777 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.814 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.814 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.814 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.814 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.857 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.857 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.857 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.857 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.941 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.941 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.941 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.941 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.991 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.991 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.991 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:46.991 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.053 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.053 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.053 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.053 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.093 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.093 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.093 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.093 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.139 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.139 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.139 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.139 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.182 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.182 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.182 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.182 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.235 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.235 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.235 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.235 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.287 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.287 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.287 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.287 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.328 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.328 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.328 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.328 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.378 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.378 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.378 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.378 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.417 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.417 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.417 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.417 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.460 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.460 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.461 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.461 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.513 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.513 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.513 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:47.513 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] test client_server_comms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.59s
[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" "6ce22b55c488dfc743e8db839a0b32e7f68cdcaea90d4c85962d3367708711fe", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6ce22b55c488dfc743e8db839a0b32e7f68cdcaea90d4c85962d3367708711fe", kill_on_drop: false }`
[INFO] [stdout] 6ce22b55c488dfc743e8db839a0b32e7f68cdcaea90d4c85962d3367708711fe