[INFO] cloning repository https://github.com/joyent/rust-fast
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/joyent/rust-fast" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1ddf01bba94c010d5627bf0eb0ff260b8085a460
[INFO] testing joyent/rust-fast against beta-2022-02-22 for beta-1.60-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-16/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-16/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain beta-2022-02-22
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/joyent/rust-fast
[INFO] finished tweaking git repo https://github.com/joyent/rust-fast
[INFO] tweaked toml for git repo https://github.com/joyent/rust-fast written to /workspace/builds/worker-16/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 76e39b488c5a6e2035f2603e04e5fa356116d7a82615ace55adc562bd9c0b7fc
[INFO] running `Command { std: "docker" "start" "-a" "76e39b488c5a6e2035f2603e04e5fa356116d7a82615ace55adc562bd9c0b7fc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "76e39b488c5a6e2035f2603e04e5fa356116d7a82615ace55adc562bd9c0b7fc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "76e39b488c5a6e2035f2603e04e5fa356116d7a82615ace55adc562bd9c0b7fc", kill_on_drop: false }`
[INFO] [stdout] 76e39b488c5a6e2035f2603e04e5fa356116d7a82615ace55adc562bd9c0b7fc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 41b6d52a756ce55c7e43a59e42b9f34a5d5a4f9a3faf405a7653e6a50fe08edd
[INFO] running `Command { std: "docker" "start" "-a" "41b6d52a756ce55c7e43a59e42b9f34a5d5a4f9a3faf405a7653e6a50fe08edd", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.119
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling futures v0.1.31
[INFO] [stderr]    Compiling slab v0.4.5
[INFO] [stderr]    Compiling proc-macro2 v1.0.36
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling syn v1.0.86
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling arc-swap v1.5.0
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling itoa v1.0.1
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling autocfg v0.1.8
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling lock_api v0.3.4
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling smallvec v0.6.14
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling rand_pcg v0.1.2
[INFO] [stderr]    Compiling rand_chacha v0.1.1
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling quote v1.0.15
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling net2 v0.2.37
[INFO] [stderr]    Compiling rand_os v0.1.3
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling crossbeam-deque v0.7.4
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.60s
[INFO] running `Command { std: "docker" "inspect" "41b6d52a756ce55c7e43a59e42b9f34a5d5a4f9a3faf405a7653e6a50fe08edd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "41b6d52a756ce55c7e43a59e42b9f34a5d5a4f9a3faf405a7653e6a50fe08edd", kill_on_drop: false }`
[INFO] [stdout] 41b6d52a756ce55c7e43a59e42b9f34a5d5a4f9a3faf405a7653e6a50fe08edd
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ab9cc5025c3da4f1e6c2907f159b061d46beee268baa2dd84b57dfa44ca7933f
[INFO] running `Command { std: "docker" "start" "-a" "ab9cc5025c3da4f1e6c2907f159b061d46beee268baa2dd84b57dfa44ca7933f", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-width v0.1.9
[INFO] [stderr]    Compiling time-macros v0.2.3
[INFO] [stderr]    Compiling once_cell v1.9.0
[INFO] [stderr]    Compiling num_threads v0.1.3
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling clap v2.34.0
[INFO] [stderr]    Compiling time v0.3.7
[INFO] [stderr]    Compiling slog-term v2.9.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.10s
[INFO] running `Command { std: "docker" "inspect" "ab9cc5025c3da4f1e6c2907f159b061d46beee268baa2dd84b57dfa44ca7933f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ab9cc5025c3da4f1e6c2907f159b061d46beee268baa2dd84b57dfa44ca7933f", kill_on_drop: false }`
[INFO] [stdout] ab9cc5025c3da4f1e6c2907f159b061d46beee268baa2dd84b57dfa44ca7933f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-16/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0437c49cf6ec8db712ae3480c8ae08ba30c3e4149b969c7fa1700ea779f33232
[INFO] running `Command { std: "docker" "start" "-a" "0437c49cf6ec8db712ae3480c8ae08ba30c3e4149b969c7fa1700ea779f33232", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-21fc83b07ed7414b)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test protocol::test::prop_fast_message_roundtrip ... ok
[INFO] [stdout] test protocol::test::prop_fast_message_bundling ... ok
[INFO] [stderr]      Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-61086cd9739ecad2)
[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 0.96s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Feb 28 11:40:50.646 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.754 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.754 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.754 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.754 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.759 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.760 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.760 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.760 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.761 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.761 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.761 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.761 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.768 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.768 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.768 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.768 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.770 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.770 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.770 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.770 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.773 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.773 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.773 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.773 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.781 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.781 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.781 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.781 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.785 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.785 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.785 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.785 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.788 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.788 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.788 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.788 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.793 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.793 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.793 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.793 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.798 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.798 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.798 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.798 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.803 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.803 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.803 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.803 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.808 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.808 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.808 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.808 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.814 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.814 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.814 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.814 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.820 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.835 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.835 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.835 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.843 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.843 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.843 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.843 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.850 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.850 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.850 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.850 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.857 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.857 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.857 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.857 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.865 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.865 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.865 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.865 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.873 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.873 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.873 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.873 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.882 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.882 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.882 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.882 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.891 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.891 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.891 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.891 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.901 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.901 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.901 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.901 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.915 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.915 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.915 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.915 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.925 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.925 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.925 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.925 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.936 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.936 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.936 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.936 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.947 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.947 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.947 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.947 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.958 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.958 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.958 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.958 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.970 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.970 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.970 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.970 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.982 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.982 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.982 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.982 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.994 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.994 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.994 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:50.994 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.007 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.007 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.007 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.007 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.021 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.021 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.021 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.021 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.034 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.034 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.034 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.034 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.049 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.049 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.049 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.049 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.064 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.064 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.064 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.064 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.080 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.080 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.080 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.080 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.095 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.095 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.095 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.095 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.111 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.111 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.111 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.111 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.128 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.128 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.128 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.128 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.145 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.146 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.146 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.146 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.163 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.163 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.163 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.163 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.181 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.181 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.181 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.181 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.199 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.199 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.199 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.199 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.217 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.217 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.217 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.217 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.236 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.236 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.236 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.236 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.255 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.255 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.255 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.255 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.274 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.274 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.274 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.275 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.295 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.295 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.295 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.295 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.315 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.315 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.315 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.315 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.336 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.336 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.336 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.336 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.357 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.357 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.357 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.357 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.379 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.379 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.379 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.379 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.402 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.402 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.402 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.402 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.424 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.424 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.424 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.424 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.447 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.447 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.447 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.447 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.470 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.470 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.470 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.470 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.494 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.494 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.494 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.494 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.518 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.518 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.518 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.518 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.546 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.546 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.546 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.546 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.571 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.571 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.571 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.571 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.596 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.597 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.597 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.597 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.622 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.623 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.623 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.623 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.648 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.648 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.648 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.649 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.675 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.675 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.675 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.675 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.701 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.701 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.701 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.701 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.729 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.729 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.729 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.729 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.756 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.756 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.757 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.757 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.783 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.783 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.783 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.783 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.811 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.811 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.811 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.811 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.839 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.840 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.840 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.840 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.868 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.868 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.868 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.868 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.898 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.898 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.898 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.898 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.926 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.927 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.927 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.927 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.956 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.957 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.957 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.957 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.987 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.987 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.987 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:51.987 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.019 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.019 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.019 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.019 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.050 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.050 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.050 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.051 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.082 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.082 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.082 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.082 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.114 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.119 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.119 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.119 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.151 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.152 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.152 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.152 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.184 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.184 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.185 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.185 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.218 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.218 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.218 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.218 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.264 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.264 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.264 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.264 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.298 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.298 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.298 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.298 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.331 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.331 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.331 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.331 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.366 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.366 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.366 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.366 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.400 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.400 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.400 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.400 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.446 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.446 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.446 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.446 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.485 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.485 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.485 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.485 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.522 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.522 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.522 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.522 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.559 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.563 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.563 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.563 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.605 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.606 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.606 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.606 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.646 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.651 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.651 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.651 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.694 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.694 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.694 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.694 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.732 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.732 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.732 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.732 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.771 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.771 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.771 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.771 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.810 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.811 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.811 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.811 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.850 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.850 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.851 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Feb 28 11:40:52.851 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.22s
[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" "0437c49cf6ec8db712ae3480c8ae08ba30c3e4149b969c7fa1700ea779f33232", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0437c49cf6ec8db712ae3480c8ae08ba30c3e4149b969c7fa1700ea779f33232", kill_on_drop: false }`
[INFO] [stdout] 0437c49cf6ec8db712ae3480c8ae08ba30c3e4149b969c7fa1700ea779f33232