[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 master#a77da2d454e6caa227a85b16410b95f93495e7e0 for pr-91031
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-8/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-8/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain a77da2d454e6caa227a85b16410b95f93495e7e0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "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-8/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "fetch" "--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" "ghcr.io/rust-lang/crates-build-env/linux@sha256:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 015a87c35d1c819d9f1d2613a00942cfb46c5fe2f327a990b1bb498c8d3da3e3
[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" "015a87c35d1c819d9f1d2613a00942cfb46c5fe2f327a990b1bb498c8d3da3e3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "015a87c35d1c819d9f1d2613a00942cfb46c5fe2f327a990b1bb498c8d3da3e3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "015a87c35d1c819d9f1d2613a00942cfb46c5fe2f327a990b1bb498c8d3da3e3", kill_on_drop: false }`
[INFO] [stdout] 015a87c35d1c819d9f1d2613a00942cfb46c5fe2f327a990b1bb498c8d3da3e3
[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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d077a45334ccd64d53f8e3a6da0393079bd938b1107cd6ffe5127c9aabed5315
[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" "d077a45334ccd64d53f8e3a6da0393079bd938b1107cd6ffe5127c9aabed5315", kill_on_drop: false }`
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling autocfg v0.1.7
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling serde v1.0.130
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling arc-swap v1.5.0
[INFO] [stderr]    Compiling itoa v0.4.8
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling semver v0.9.0
[INFO] [stderr]    Compiling lock_api v0.3.4
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling proc-macro2 v1.0.32
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling num_cpus v1.13.0
[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 smallvec v0.6.14
[INFO] [stderr]    Compiling rustc_version v0.2.3
[INFO] [stderr]    Compiling bytes v0.4.12
[INFO] [stderr]    Compiling rand_chacha v0.1.1
[INFO] [stderr]    Compiling rand_pcg v0.1.2
[INFO] [stderr]    Compiling rand v0.6.5
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling log v0.3.9
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling syn v1.0.81
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling crossbeam-deque v0.7.4
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling tokio-fs v0.1.7
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio-udp v0.1.6
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling tokio v0.1.22
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling serde_derive v1.0.130
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling serde_json v1.0.71
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 16s
[INFO] running `Command { std: "docker" "inspect" "d077a45334ccd64d53f8e3a6da0393079bd938b1107cd6ffe5127c9aabed5315", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d077a45334ccd64d53f8e3a6da0393079bd938b1107cd6ffe5127c9aabed5315", kill_on_drop: false }`
[INFO] [stdout] d077a45334ccd64d53f8e3a6da0393079bd938b1107cd6ffe5127c9aabed5315
[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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 139aeeacd1c1d1cc548d2f5016876dff9b9fa4edfb9419969530d886cf24676d
[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" "139aeeacd1c1d1cc548d2f5016876dff9b9fa4edfb9419969530d886cf24676d", kill_on_drop: false }`
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling slog-term v2.8.0
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 22.37s
[INFO] running `Command { std: "docker" "inspect" "139aeeacd1c1d1cc548d2f5016876dff9b9fa4edfb9419969530d886cf24676d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "139aeeacd1c1d1cc548d2f5016876dff9b9fa4edfb9419969530d886cf24676d", kill_on_drop: false }`
[INFO] [stdout] 139aeeacd1c1d1cc548d2f5016876dff9b9fa4edfb9419969530d886cf24676d
[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=forbid" "-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:5736fa189c1c60b01babf4b8b698fe57b6ecc41933a7ff2e0b8d7a221459412b" "/opt/rustwide/cargo-home/bin/cargo" "+a77da2d454e6caa227a85b16410b95f93495e7e0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] [stdout] c3b4a77f3163541c5e2c943ab83f7fada93c4e3c45bf039b8d8d68646f6316ab
[INFO] running `Command { std: "docker" "start" "-a" "c3b4a77f3163541c5e2c943ab83f7fada93c4e3c45bf039b8d8d68646f6316ab", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.16s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-f709b659d842c6ba)
[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-dc0f92c158b67956)
[INFO] [stdout] test protocol::test::prop_fast_message_decoding ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.46s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Nov 23 22:01:10.984 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:10.997 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:10.997 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:10.997 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:10.998 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.004 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.004 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.004 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.004 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.016 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.016 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.016 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.016 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.024 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.024 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.024 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.024 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.028 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.028 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.028 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.028 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.031 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.031 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.031 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.031 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.037 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.037 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.037 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.037 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.042 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.043 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.043 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.043 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.048 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.049 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.049 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.049 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.056 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.057 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.057 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.057 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.061 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.061 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.061 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.061 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.066 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.066 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.066 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.066 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.072 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.072 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.072 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.072 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.085 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.085 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.085 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.085 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.091 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.092 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.092 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.092 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.098 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.098 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.098 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.098 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.105 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.105 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.105 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.105 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.113 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.113 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.113 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.113 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.120 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.120 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.120 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.120 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.129 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.129 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.129 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.129 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.143 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.144 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.144 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.144 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.158 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.158 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.158 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.158 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.167 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.167 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.167 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.167 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.178 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.178 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.178 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.178 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.190 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.190 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.190 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.190 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.200 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.200 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.200 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.200 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.225 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.225 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.225 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.225 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.236 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.236 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.237 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.237 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.248 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.248 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.248 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.248 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.263 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.263 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.263 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.263 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.275 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.275 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.275 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.275 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.288 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.288 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.288 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.288 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.300 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.300 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.300 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.300 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.313 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.313 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.313 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.313 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.327 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.327 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.327 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.327 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.343 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.343 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.343 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.343 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.358 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.358 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.358 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.358 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.373 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.373 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.373 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.373 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.388 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.388 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.388 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.388 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.403 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.403 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.403 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.403 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.419 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.419 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.419 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.419 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.436 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.436 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.436 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.436 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.453 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.453 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.453 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.453 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.469 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.470 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.470 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.470 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.486 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.487 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.487 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.487 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.504 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.504 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.504 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.505 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.522 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.522 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.523 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.523 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.541 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.541 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.542 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.543 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.565 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.565 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.565 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.566 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.585 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.585 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.585 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.585 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.606 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.606 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.606 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.607 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.626 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.626 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.626 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.626 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.655 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.655 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.655 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.655 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.675 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.676 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.676 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.676 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.696 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.696 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.696 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.696 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.717 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.717 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.717 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.717 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.738 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.738 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.738 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.738 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.760 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.760 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.760 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.760 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.783 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.783 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.783 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.783 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.805 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.806 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.806 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.806 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.829 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.830 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.830 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.830 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.853 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.853 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.853 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.853 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.888 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.888 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.888 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.888 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.914 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.914 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.914 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.914 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.946 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.954 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.954 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.954 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.979 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.983 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.983 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:11.983 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.010 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.010 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.011 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.011 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.046 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.046 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.046 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.046 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.075 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.075 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.076 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.076 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.109 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.110 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.110 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.110 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.152 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.153 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.153 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.153 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.188 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.188 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.188 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.188 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.216 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.216 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.217 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.217 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.249 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.249 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.249 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.250 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.278 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.278 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.278 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.278 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.322 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.323 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.323 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.323 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.351 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.352 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.352 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.353 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.394 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.394 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.394 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.395 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.441 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.441 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.441 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.441 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.478 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.479 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.479 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.479 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.524 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.524 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.524 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.524 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.573 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.573 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.573 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.573 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.622 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.622 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.622 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.622 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.682 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.683 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.683 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.683 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.720 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.720 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.720 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.721 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.768 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.771 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.771 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.771 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.815 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.819 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.819 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.819 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.852 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.855 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.855 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.855 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.895 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.897 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.897 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.897 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.947 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.951 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.952 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.952 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.986 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.991 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.992 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:12.992 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.030 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.039 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.040 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.040 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.077 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.077 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.078 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.078 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.121 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.129 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.129 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.129 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.174 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.174 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.175 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.175 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.218 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.218 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.218 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.218 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.259 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.259 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.259 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.259 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.309 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.309 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.309 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.309 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.345 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.345 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.345 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Nov 23 22:01:13.345 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.38s
[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" "c3b4a77f3163541c5e2c943ab83f7fada93c4e3c45bf039b8d8d68646f6316ab", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c3b4a77f3163541c5e2c943ab83f7fada93c4e3c45bf039b8d8d68646f6316ab", kill_on_drop: false }`
[INFO] [stdout] c3b4a77f3163541c5e2c943ab83f7fada93c4e3c45bf039b8d8d68646f6316ab