[INFO] fetching crate fast-rpc 0.3.0...
[INFO] testing fast-rpc-0.3.0 against 1.49.0 for beta-1.50-1
[INFO] extracting crate fast-rpc 0.3.0 into /workspace/builds/worker-8/source
[INFO] validating manifest of crates.io crate fast-rpc 0.3.0 on toolchain 1.49.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.49.0" "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-8/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.49.0" "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" "+1.49.0" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.49.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b51ede91e668ce17d8a8f3da7d904f419d1cd5bdf7dcbda55a7bea651fe2d51d
[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" "b51ede91e668ce17d8a8f3da7d904f419d1cd5bdf7dcbda55a7bea651fe2d51d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b51ede91e668ce17d8a8f3da7d904f419d1cd5bdf7dcbda55a7bea651fe2d51d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b51ede91e668ce17d8a8f3da7d904f419d1cd5bdf7dcbda55a7bea651fe2d51d", kill_on_drop: false }`
[INFO] [stdout] b51ede91e668ce17d8a8f3da7d904f419d1cd5bdf7dcbda55a7bea651fe2d51d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.49.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8ef1d4e5820009385b26f0c71a6d44bac0bb0d9c4fe1576e8c9e87aa15ffea29
[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" "8ef1d4e5820009385b26f0c71a6d44bac0bb0d9c4fe1576e8c9e87aa15ffea29", kill_on_drop: false }`
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling chrono v0.4.19
[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-rational v0.2.4
[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 15.27s
[INFO] running `Command { std: "docker" "inspect" "8ef1d4e5820009385b26f0c71a6d44bac0bb0d9c4fe1576e8c9e87aa15ffea29", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8ef1d4e5820009385b26f0c71a6d44bac0bb0d9c4fe1576e8c9e87aa15ffea29", kill_on_drop: false }`
[INFO] [stdout] 8ef1d4e5820009385b26f0c71a6d44bac0bb0d9c4fe1576e8c9e87aa15ffea29
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.49.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2ffade05f92c121d19c70f4b18fbbd18364c394287b9421a9acf7e2890b0ab25
[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" "2ffade05f92c121d19c70f4b18fbbd18364c394287b9421a9acf7e2890b0ab25", kill_on_drop: false }`
[INFO] [stderr]    Compiling dirs v2.0.2
[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 15.09s
[INFO] running `Command { std: "docker" "inspect" "2ffade05f92c121d19c70f4b18fbbd18364c394287b9421a9acf7e2890b0ab25", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2ffade05f92c121d19c70f4b18fbbd18364c394287b9421a9acf7e2890b0ab25", kill_on_drop: false }`
[INFO] [stdout] 2ffade05f92c121d19c70f4b18fbbd18364c394287b9421a9acf7e2890b0ab25
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+1.49.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5979b8656c0743635b7548661859895da01e93694690cd7614b977afb3c4bcf2
[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" "5979b8656c0743635b7548661859895da01e93694690cd7614b977afb3c4bcf2", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.25s
[INFO] [stderr]      Running /opt/rustwide/target/debug/deps/fast_rpc-f79133f2ffa12a2f
[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-1875561b211da39c
[INFO] [stdout] test protocol::test::prop_fast_message_decoding ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Jan 04 19:56:51.262 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.271 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.271 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.271 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.271 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.278 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.279 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.279 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.279 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.283 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.283 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.283 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.283 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.295 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.295 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.295 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.295 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.307 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.307 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.307 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.307 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.313 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.313 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.313 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.313 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.317 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.317 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.317 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.317 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.332 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.332 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.332 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.332 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.338 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.338 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.338 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.338 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.352 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.352 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.352 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.352 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.360 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.360 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.360 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.360 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.366 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.367 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.367 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.367 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.373 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.373 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.373 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.373 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.379 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.379 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.379 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.379 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.386 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.386 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.386 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.386 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.399 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.400 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.400 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.400 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.409 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.409 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.409 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.409 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.417 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.417 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.417 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.417 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.431 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.431 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.431 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.431 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.456 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.456 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.456 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.456 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.470 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.470 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.482 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.482 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.482 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.482 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.499 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.499 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.499 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.499 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.512 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.512 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.512 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.512 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.524 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.524 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.524 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.524 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.537 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.537 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.537 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.537 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.552 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.552 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.552 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.552 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.566 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.567 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.567 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.567 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.585 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.585 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.585 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.585 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.600 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.600 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.600 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.600 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.616 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.616 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.616 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.616 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.636 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.636 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.636 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.636 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.658 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.658 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.658 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.658 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.675 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.675 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.675 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.675 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.693 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.693 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.693 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.693 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.715 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.715 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.715 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.715 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.734 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.734 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.734 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.734 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.753 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.753 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.753 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.753 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.788 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.788 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.788 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.788 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.820 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.820 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.820 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.820 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.850 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.850 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.850 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.850 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.888 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.888 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.888 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.888 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.922 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.922 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.922 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.922 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.956 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.956 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.956 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.956 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.979 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.979 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.979 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:51.979 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.006 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.006 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.006 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.006 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.036 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.036 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.036 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.036 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.060 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.060 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.089 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.089 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.089 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.089 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.115 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.115 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.115 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.115 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.140 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.140 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.140 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.140 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.165 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.165 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.165 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.165 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.190 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.190 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.190 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.190 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.214 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.215 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.215 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.215 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.244 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.244 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.244 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.244 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.270 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.270 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.270 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.270 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.302 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.302 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.302 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.302 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.334 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.334 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.334 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.334 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.363 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.364 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.364 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.364 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.393 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.393 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.393 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.393 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.424 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.424 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.424 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.424 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.454 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.454 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.454 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.455 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.517 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.517 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.517 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.517 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.549 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.549 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.549 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.549 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.596 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.596 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.596 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.596 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.632 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.632 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.632 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.632 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.671 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.672 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.672 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.672 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.712 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.712 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.712 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.712 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.747 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.747 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.747 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.747 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.786 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.786 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.786 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.786 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.821 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.821 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.821 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.821 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.862 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.862 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.862 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.862 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.903 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.903 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.903 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.903 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.953 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.953 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.953 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.953 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.998 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.998 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.998 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:52.998 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.040 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.041 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.041 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.041 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.083 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.083 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.083 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.083 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.126 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.126 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.126 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.126 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.170 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.170 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.170 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.170 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.211 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.211 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.211 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.211 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.255 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.255 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.255 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.255 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.295 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.295 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.295 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.295 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.335 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.335 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.335 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.335 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.373 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.374 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.374 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.374 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.458 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.458 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.458 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.458 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.502 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.503 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.503 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.503 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.547 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.547 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.547 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.547 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.594 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.594 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.594 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.594 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.637 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.638 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.638 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.638 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.694 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.694 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.694 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.694 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.757 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.757 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.757 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.757 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.817 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.817 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.817 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.817 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.872 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.872 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.872 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.872 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.932 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.932 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.932 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.932 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.984 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.984 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.984 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:53.984 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.037 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.037 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.037 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.037 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.093 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.093 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.093 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Jan 04 19:56:54.093 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
[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
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "5979b8656c0743635b7548661859895da01e93694690cd7614b977afb3c4bcf2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5979b8656c0743635b7548661859895da01e93694690cd7614b977afb3c4bcf2", kill_on_drop: false }`
[INFO] [stdout] 5979b8656c0743635b7548661859895da01e93694690cd7614b977afb3c4bcf2