[INFO] fetching crate fast-rpc 0.3.0...
[INFO] testing fast-rpc-0.3.0 against beta-2021-09-08 for beta-1.56-1
[INFO] extracting crate fast-rpc 0.3.0 into /workspace/builds/worker-12/source
[INFO] validating manifest of crates.io crate fast-rpc 0.3.0 on toolchain beta-2021-09-08
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "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-12/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-09-08" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+beta-2021-09-08" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] 30a025ae262e061fb610e1f3961a9ffc2c2c403a9cf1da55a37a1e3e935eea54
[INFO] running `Command { std: "docker" "start" "-a" "30a025ae262e061fb610e1f3961a9ffc2c2c403a9cf1da55a37a1e3e935eea54", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "30a025ae262e061fb610e1f3961a9ffc2c2c403a9cf1da55a37a1e3e935eea54", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "30a025ae262e061fb610e1f3961a9ffc2c2c403a9cf1da55a37a1e3e935eea54", kill_on_drop: false }`
[INFO] [stdout] 30a025ae262e061fb610e1f3961a9ffc2c2c403a9cf1da55a37a1e3e935eea54
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+beta-2021-09-08" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e5e3567331d9aa756f4a83ae1cf32f52860fdffb9ba64e5aba514c3c51a42c57
[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" "e5e3567331d9aa756f4a83ae1cf32f52860fdffb9ba64e5aba514c3c51a42c57", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling arc-swap v1.3.2
[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 tokio-fs v0.1.7
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling slog-scope v4.4.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 12.59s
[INFO] running `Command { std: "docker" "inspect" "e5e3567331d9aa756f4a83ae1cf32f52860fdffb9ba64e5aba514c3c51a42c57", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e5e3567331d9aa756f4a83ae1cf32f52860fdffb9ba64e5aba514c3c51a42c57", kill_on_drop: false }`
[INFO] [stdout] e5e3567331d9aa756f4a83ae1cf32f52860fdffb9ba64e5aba514c3c51a42c57
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+beta-2021-09-08" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ac53fbd481918e264cd449b6221ebd084a1435c7a18e089e5f121514595cb424
[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" "ac53fbd481918e264cd449b6221ebd084a1435c7a18e089e5f121514595cb424", kill_on_drop: false }`
[INFO] [stderr]    Compiling thread_local v1.1.3
[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 11.19s
[INFO] running `Command { std: "docker" "inspect" "ac53fbd481918e264cd449b6221ebd084a1435c7a18e089e5f121514595cb424", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ac53fbd481918e264cd449b6221ebd084a1435c7a18e089e5f121514595cb424", kill_on_drop: false }`
[INFO] [stdout] ac53fbd481918e264cd449b6221ebd084a1435c7a18e089e5f121514595cb424
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-12/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" "+beta-2021-09-08" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 97b7901a9841053878c58f50f66877eba2633bdd3ef1211d0093d7308e19eb44
[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" "97b7901a9841053878c58f50f66877eba2633bdd3ef1211d0093d7308e19eb44", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-8b90d012a61fae6f)
[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-d8d483d60c8b24c5)
[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 2.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Sep 10 23:20:41.498 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.508 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.508 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.508 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.508 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.509 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.509 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.509 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.509 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.514 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.514 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.514 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.514 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.516 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.516 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.516 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.516 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.518 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.518 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.518 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.518 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.521 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.521 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.521 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.521 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.524 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.524 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.524 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.524 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.527 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.528 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.528 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.528 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.531 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.532 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.532 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.532 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.536 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.536 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.536 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.536 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.548 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.548 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.548 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.548 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.554 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.554 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.554 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.554 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.559 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.559 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.559 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.559 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.564 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.564 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.565 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.565 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.571 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.571 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.571 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.571 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.585 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.585 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.585 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.585 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.594 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.594 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.594 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.594 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.602 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.602 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.602 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.602 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.615 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.615 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.616 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.616 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.623 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.623 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.623 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.624 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.635 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.635 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.635 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.635 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.647 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.648 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.648 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.648 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.656 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.657 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.657 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.657 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.665 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.666 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.666 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.666 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.681 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.681 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.681 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.681 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.691 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.692 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.692 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.692 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.702 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.702 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.702 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.702 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.718 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.718 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.718 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.718 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.734 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.734 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.734 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.734 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.748 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.748 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.748 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.748 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.760 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.760 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.760 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.760 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.772 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.772 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.772 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.772 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.789 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.789 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.789 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.789 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.802 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.802 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.802 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.802 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.815 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.815 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.815 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.815 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.828 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.828 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.828 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.828 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.842 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.842 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.842 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.842 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.857 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.857 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.857 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.857 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.872 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.872 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.872 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.872 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.888 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.888 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.888 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.888 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.903 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.903 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.903 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.903 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.919 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.919 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.919 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.919 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.935 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.935 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.935 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.935 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.952 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.952 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.952 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.952 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.969 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.969 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.969 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.969 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.986 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.986 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.986 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:41.986 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.004 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.004 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.004 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.004 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.028 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.028 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.028 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.028 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.047 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.048 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.048 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.048 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.067 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.067 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.067 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.067 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.087 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.087 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.087 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.087 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.107 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.107 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.107 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.107 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.126 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.126 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.126 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.127 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.151 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.151 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.151 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.151 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.171 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.171 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.171 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.171 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.193 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.193 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.193 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.193 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.217 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.217 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.217 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.217 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.238 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.239 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.239 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.239 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.264 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.265 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.265 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.265 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.288 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.288 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.288 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.288 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.311 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.311 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.311 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.311 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.339 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.339 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.339 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.339 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.363 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.363 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.363 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.363 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.386 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.386 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.386 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.386 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.416 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.416 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.416 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.416 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.441 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.441 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.441 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.441 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.466 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.466 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.467 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.467 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.493 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.493 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.493 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.493 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.519 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.519 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.519 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.520 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.546 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.546 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.546 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.546 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.581 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.582 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.582 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.582 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.625 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.625 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.625 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.626 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.666 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.666 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.666 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.666 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.694 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.695 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.695 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.695 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.727 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.727 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.727 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.727 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.772 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.772 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.772 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.772 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.805 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.805 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.805 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.805 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.838 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.838 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.838 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.838 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.875 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.875 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.875 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.875 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.910 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.910 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.910 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.910 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.952 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.952 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.953 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.953 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.987 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.987 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.987 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:42.987 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.022 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.022 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.022 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.022 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.089 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.089 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.089 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.089 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.131 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.131 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.131 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.131 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.172 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.172 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.172 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.172 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.208 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.208 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.208 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.209 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.241 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.241 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.241 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.241 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.275 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.276 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.276 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.276 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.309 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.310 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.310 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.310 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.344 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.344 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.344 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.344 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.383 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.383 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.383 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.383 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.420 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.420 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.420 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.420 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.460 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.460 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.460 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.461 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.502 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.502 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.502 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.502 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.539 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.539 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.539 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.539 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.583 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.583 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.583 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.583 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.629 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.629 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.629 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.629 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.666 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.666 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.666 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Sep 10 23:20:43.666 DEBG generated response, build-id: 0.1.0
[INFO] [stderr]    Doc-tests fast-rpc
[INFO] [stdout] test client_server_comms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.19s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "97b7901a9841053878c58f50f66877eba2633bdd3ef1211d0093d7308e19eb44", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "97b7901a9841053878c58f50f66877eba2633bdd3ef1211d0093d7308e19eb44", kill_on_drop: false }`
[INFO] [stdout] 97b7901a9841053878c58f50f66877eba2633bdd3ef1211d0093d7308e19eb44