[INFO] fetching crate fast-rpc 0.3.0...
[INFO] testing fast-rpc-0.3.0 against beta-2021-01-01 for beta-1.50-1
[INFO] extracting crate fast-rpc 0.3.0 into /workspace/builds/worker-1/source
[INFO] validating manifest of crates.io crate fast-rpc 0.3.0 on toolchain beta-2021-01-01
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "read-manifest" "--manifest-path" "Cargo.toml", 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-1/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 2355f586446c68006dfd2815b3a7081a8eb8e9432c4705324d6f2d8006bb5c63
[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" "2355f586446c68006dfd2815b3a7081a8eb8e9432c4705324d6f2d8006bb5c63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2355f586446c68006dfd2815b3a7081a8eb8e9432c4705324d6f2d8006bb5c63", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2355f586446c68006dfd2815b3a7081a8eb8e9432c4705324d6f2d8006bb5c63", kill_on_drop: false }`
[INFO] [stdout] 2355f586446c68006dfd2815b3a7081a8eb8e9432c4705324d6f2d8006bb5c63
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d25185dc172af4095c78e2bbeb4664d51c2adabc1a2437c09fcdebc4c141397e
[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" "d25185dc172af4095c78e2bbeb4664d51c2adabc1a2437c09fcdebc4c141397e", kill_on_drop: false }`
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[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.42
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling slog-scope v4.3.0
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.80s
[INFO] running `Command { std: "docker" "inspect" "d25185dc172af4095c78e2bbeb4664d51c2adabc1a2437c09fcdebc4c141397e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d25185dc172af4095c78e2bbeb4664d51c2adabc1a2437c09fcdebc4c141397e", kill_on_drop: false }`
[INFO] [stdout] d25185dc172af4095c78e2bbeb4664d51c2adabc1a2437c09fcdebc4c141397e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] cfddb027a5e0c94c318bfc793e44332f46592614de3e923e66d1c6710671a0a1
[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" "cfddb027a5e0c94c318bfc793e44332f46592614de3e923e66d1c6710671a0a1", kill_on_drop: false }`
[INFO] [stderr]    Compiling term v0.6.1
[INFO] [stderr]    Compiling slog-term v2.6.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 13.84s
[INFO] running `Command { std: "docker" "inspect" "cfddb027a5e0c94c318bfc793e44332f46592614de3e923e66d1c6710671a0a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cfddb027a5e0c94c318bfc793e44332f46592614de3e923e66d1c6710671a0a1", kill_on_drop: false }`
[INFO] [stdout] cfddb027a5e0c94c318bfc793e44332f46592614de3e923e66d1c6710671a0a1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "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] 7d2b78211dd402849b9463cb23cc4f4d68555dbaad9d30caf2a35fbc57ab8a00
[INFO] running `Command { std: "docker" "start" "-a" "7d2b78211dd402849b9463cb23cc4f4d68555dbaad9d30caf2a35fbc57ab8a00", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.21s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/fast_rpc-a515c02a807b3054
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test protocol::test::prop_fast_message_roundtrip ... ok
[INFO] [stdout] test protocol::test::prop_fast_message_bundling ... ok
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/client_server_test-5535094ad03e85f3
[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.99s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Jan 04 19:56:50.583 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.590 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.590 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.590 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.590 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.591 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.591 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.591 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.591 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.597 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.597 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.597 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.597 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.599 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.599 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.599 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.599 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.601 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.602 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.602 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.602 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.604 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.604 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.604 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.604 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.608 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.608 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.608 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.608 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.612 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.612 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.612 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.612 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.621 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.621 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.621 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.621 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.626 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.626 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.626 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.626 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.638 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.638 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.638 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.638 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.644 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.644 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.644 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.644 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.656 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.656 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.656 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.656 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.663 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.663 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.663 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.663 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.672 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.673 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.673 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.673 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.680 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.680 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.680 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.680 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.693 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.693 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.693 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.693 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.702 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.702 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.702 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.702 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.716 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.716 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.716 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.716 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.725 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.725 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.725 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.725 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.742 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.742 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.742 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.743 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.759 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.759 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.759 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.759 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.781 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.781 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.782 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.782 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.809 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.809 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.809 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.809 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.823 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.823 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.823 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.823 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.842 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.842 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.842 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.842 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.862 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.862 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.862 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.862 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.875 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.875 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.875 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.875 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.889 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.889 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.889 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.889 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.903 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.903 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.904 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.904 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.918 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.918 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.918 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.918 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.933 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.933 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.933 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.933 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.949 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.949 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.949 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.949 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.965 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.965 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.965 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.965 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.982 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.982 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.982 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.982 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.999 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.999 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.999 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:50.999 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.021 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.021 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.021 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.021 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.039 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.039 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.039 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.039 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.057 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.057 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.057 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.057 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.076 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.076 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.076 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.076 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.096 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.096 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.097 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.097 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.121 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.121 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.121 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.121 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.141 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.141 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.141 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.141 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.170 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.170 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.170 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.170 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.192 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.192 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.192 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.192 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.212 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.213 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.213 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.213 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.234 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.235 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.235 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.235 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.260 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.261 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.261 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.261 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.285 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.286 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.286 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.286 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.309 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.310 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.310 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.310 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.334 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.334 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.334 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.335 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.361 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.361 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.361 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.361 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.388 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.388 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.388 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.388 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.415 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.415 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.415 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.415 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.441 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.441 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.441 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.442 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.469 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.469 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.469 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.469 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.497 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.497 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.497 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.497 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.529 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.529 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.529 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.529 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.565 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.565 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.565 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.565 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.602 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.602 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.602 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.602 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.639 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.639 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.639 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.640 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.676 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.676 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.676 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.676 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.707 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.707 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.707 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.707 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.760 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.760 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.760 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.760 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.814 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.814 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.814 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.814 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.845 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.845 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.845 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.845 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.876 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.876 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.876 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.877 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.908 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.908 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.908 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.908 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.946 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.946 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.946 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.947 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.985 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.985 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.985 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.985 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.028 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.029 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.029 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.029 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.061 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.061 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.061 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.061 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.099 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.099 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.099 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.099 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.132 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.132 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.132 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.132 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.166 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.166 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.166 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.166 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.201 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.201 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.201 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.201 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.241 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.241 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.241 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.242 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.282 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.282 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.282 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.282 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.329 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.329 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.329 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.329 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.366 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.366 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.366 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.366 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.406 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.406 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.406 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.406 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.443 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.443 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.443 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.443 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.485 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.485 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.485 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.485 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.522 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.522 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.522 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.522 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.560 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.560 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.560 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.560 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.599 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.600 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.600 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.600 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.643 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.643 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.643 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.643 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.682 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.682 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.682 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.682 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.740 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.740 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.740 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.740 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.794 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.795 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.795 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.795 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.837 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.837 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.837 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.837 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.882 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.882 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.882 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.882 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.945 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.945 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.945 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.945 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.989 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.989 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.989 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.989 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.038 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.038 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.038 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.039 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.086 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.086 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.086 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.086 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.135 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.136 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.136 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.136 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.186 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.186 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.186 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.187 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.233 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.233 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.233 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.233 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.67s
[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" "7d2b78211dd402849b9463cb23cc4f4d68555dbaad9d30caf2a35fbc57ab8a00", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d2b78211dd402849b9463cb23cc4f4d68555dbaad9d30caf2a35fbc57ab8a00", kill_on_drop: false }`
[INFO] [stdout] 7d2b78211dd402849b9463cb23cc4f4d68555dbaad9d30caf2a35fbc57ab8a00