[INFO] fetching crate fast-rpc 0.3.0...
[INFO] testing fast-rpc-0.3.0 against try#8262275854dc0e5381c9d42d56257d94932c5a87 for pr-90799
[INFO] extracting crate fast-rpc 0.3.0 into /workspace/builds/worker-11/source
[INFO] validating manifest of crates.io crate fast-rpc 0.3.0 on toolchain 8262275854dc0e5381c9d42d56257d94932c5a87
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate fast-rpc 0.3.0
[INFO] finished tweaking crates.io crate fast-rpc 0.3.0
[INFO] tweaked toml for crates.io crate fast-rpc 0.3.0 written to /workspace/builds/worker-11/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "+8262275854dc0e5381c9d42d56257d94932c5a87" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 07dbff1705e45661be4590cfeee3410ff60870edfcaf4de34e235be9ec8f9dda
[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" "07dbff1705e45661be4590cfeee3410ff60870edfcaf4de34e235be9ec8f9dda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "07dbff1705e45661be4590cfeee3410ff60870edfcaf4de34e235be9ec8f9dda", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "07dbff1705e45661be4590cfeee3410ff60870edfcaf4de34e235be9ec8f9dda", kill_on_drop: false }`
[INFO] [stdout] 07dbff1705e45661be4590cfeee3410ff60870edfcaf4de34e235be9ec8f9dda
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "+8262275854dc0e5381c9d42d56257d94932c5a87" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 37191960575e39ff5482ddfe9d40f22d28455748666f7538c3f18337ccf3862a
[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" "37191960575e39ff5482ddfe9d40f22d28455748666f7538c3f18337ccf3862a", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling futures v0.1.31
[INFO] [stderr]    Compiling autocfg v0.1.7
[INFO] [stderr]    Compiling memchr v2.4.1
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling arc-swap v1.5.0
[INFO] [stderr]    Compiling crc16 v0.4.0
[INFO] [stderr]    Compiling crossbeam v0.2.12
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling lock_api v0.3.4
[INFO] [stderr]    Compiling rand_jitter v0.1.4
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling memoffset v0.5.6
[INFO] [stderr]    Compiling crossbeam-epoch v0.8.2
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling rustc_version v0.2.3
[INFO] [stderr]    Compiling rand_hc v0.1.0
[INFO] [stderr]    Compiling rand_isaac v0.1.1
[INFO] [stderr]    Compiling rand_xorshift v0.1.1
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling net2 v0.2.37
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling quote v1.0.10
[INFO] [stderr]    Compiling serde_json v1.0.72
[INFO] [stderr]    Compiling log v0.3.9
[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 syn v1.0.82
[INFO] [stderr]    Compiling parking_lot_core v0.6.2
[INFO] [stderr]    Compiling parking_lot v0.9.0
[INFO] [stderr]    Compiling mio v0.6.23
[INFO] [stderr]    Compiling crossbeam-queue v0.2.3
[INFO] [stderr]    Compiling slog-scope v4.4.0
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling crossbeam-deque v0.7.4
[INFO] [stderr]    Compiling slog-stdlog v3.0.5
[INFO] [stderr]    Compiling tokio-executor v0.1.10
[INFO] [stderr]    Compiling tokio-io v0.1.13
[INFO] [stderr]    Compiling tokio-sync v0.1.8
[INFO] [stderr]    Compiling mio-uds v0.6.8
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling tokio-threadpool v0.1.18
[INFO] [stderr]    Compiling tokio-current-thread v0.1.7
[INFO] [stderr]    Compiling tokio-timer v0.2.13
[INFO] [stderr]    Compiling tokio-reactor v0.1.12
[INFO] [stderr]    Compiling tokio-codec v0.1.2
[INFO] [stderr]    Compiling regex v1.5.4
[INFO] [stderr]    Compiling tokio-tcp v0.1.4
[INFO] [stderr]    Compiling tokio-uds v0.2.7
[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 num v0.2.1
[INFO] [stderr]    Compiling env_logger v0.6.2
[INFO] [stderr]    Compiling serde_derive v1.0.130
[INFO] [stderr]    Compiling num-derive v0.3.3
[INFO] [stderr]    Compiling quickcheck v0.8.5
[INFO] [stderr]    Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 08s
[INFO] running `Command { std: "docker" "inspect" "37191960575e39ff5482ddfe9d40f22d28455748666f7538c3f18337ccf3862a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "37191960575e39ff5482ddfe9d40f22d28455748666f7538c3f18337ccf3862a", kill_on_drop: false }`
[INFO] [stdout] 37191960575e39ff5482ddfe9d40f22d28455748666f7538c3f18337ccf3862a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "+8262275854dc0e5381c9d42d56257d94932c5a87" "test" "--frozen" "--no-run" "--message-format=json", 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] ae236317f029fedc395ed5fdb88bb904f517b192cff6e7559173f007370bbf86
[INFO] running `Command { std: "docker" "start" "-a" "ae236317f029fedc395ed5fdb88bb904f517b192cff6e7559173f007370bbf86", kill_on_drop: false }`
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling strsim v0.8.0
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling clap v2.34.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 19.19s
[INFO] running `Command { std: "docker" "inspect" "ae236317f029fedc395ed5fdb88bb904f517b192cff6e7559173f007370bbf86", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ae236317f029fedc395ed5fdb88bb904f517b192cff6e7559173f007370bbf86", kill_on_drop: false }`
[INFO] [stdout] ae236317f029fedc395ed5fdb88bb904f517b192cff6e7559173f007370bbf86
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-11/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" "+8262275854dc0e5381c9d42d56257d94932c5a87" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] aa7a88ed381cba63fccf2dbdca4672abb0c597878c321f858e250946238d4894
[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" "aa7a88ed381cba63fccf2dbdca4672abb0c597878c321f858e250946238d4894", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-1224649ba75c26ea)
[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-e2686fcd0c8a7c01)
[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.27s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] Dec 02 08:06:21.246 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.254 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.254 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.254 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.254 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.260 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.260 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.260 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.260 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.265 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.266 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.266 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.266 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.274 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.274 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.274 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.274 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.282 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.282 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.282 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.282 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.290 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.290 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.290 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.290 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.295 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.295 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.295 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.295 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.306 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.306 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.306 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.306 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.314 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.314 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.314 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.314 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.318 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.318 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.318 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.318 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.331 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.331 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.331 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.331 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.343 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.343 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.343 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.343 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.351 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.351 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.351 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.351 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.359 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.359 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.359 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.359 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.365 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.365 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.365 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.365 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.375 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.375 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.375 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.375 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.383 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.383 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.383 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.383 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.390 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.390 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.390 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.390 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.411 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.411 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.411 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.411 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.418 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.418 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.418 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.418 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.426 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.426 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.426 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.427 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.443 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.444 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.444 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.444 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.452 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.453 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.453 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.453 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.462 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.462 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.462 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.462 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.471 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.471 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.471 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.471 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.493 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.493 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.493 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.493 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.503 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.503 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.503 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.503 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.514 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.514 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.514 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.514 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.529 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.529 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.529 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.529 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.541 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.541 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.541 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.541 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.552 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.553 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.553 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.553 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.564 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.565 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.565 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.565 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.577 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.577 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.577 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.577 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.593 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.593 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.593 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.593 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.606 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.606 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.606 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.606 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.620 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.620 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.620 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.620 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.634 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.634 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.634 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.634 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.652 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.652 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.652 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.652 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.666 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.666 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.666 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.666 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.681 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.681 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.681 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.681 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.698 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.698 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.698 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.698 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.715 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.716 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.716 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.716 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.732 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.732 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.732 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.732 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.755 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.755 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.755 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.755 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.772 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.772 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.772 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.773 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.793 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.793 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.793 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.793 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.811 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.811 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.811 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.811 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.829 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.829 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.829 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.829 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.852 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.852 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.852 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.852 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.875 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.875 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.875 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.875 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.898 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.898 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.898 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.898 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.918 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.918 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.918 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.918 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.938 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.938 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.938 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.938 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.964 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.964 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.964 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.964 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.985 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.986 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.986 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:21.986 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.014 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.014 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.014 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.014 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.039 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.039 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.039 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.039 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.061 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.061 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.061 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.061 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.101 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.101 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.101 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.101 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.131 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.131 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.132 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.132 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.158 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.159 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.159 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.159 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.190 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.190 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.190 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.190 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.217 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.217 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.217 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.217 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.246 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.246 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.246 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.246 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.276 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.276 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.276 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.276 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.305 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.305 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.305 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.305 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.340 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.340 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.340 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.340 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.369 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.370 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.370 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.370 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.399 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.399 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.399 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.399 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.434 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.434 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.434 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.434 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.461 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.461 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.461 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.461 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.488 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.488 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.488 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.488 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.520 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.520 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.520 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.520 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.555 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.555 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.555 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.555 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.591 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.591 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.591 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.591 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.619 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.619 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.619 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.619 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.648 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.648 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.648 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.648 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.677 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.677 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.677 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.677 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.715 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.715 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.715 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.715 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.744 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.744 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.744 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.744 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.774 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.774 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.774 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.774 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.816 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.816 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.816 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.816 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.856 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.856 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.856 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.856 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.904 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.904 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.904 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.905 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.940 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.940 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.940 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.940 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.983 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.984 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.984 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:22.984 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.020 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.020 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.020 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.020 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.058 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.058 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.058 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.058 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.105 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.105 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.105 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.105 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.154 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.154 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.154 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.154 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.196 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.196 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.196 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.196 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.237 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.238 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.238 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.238 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.300 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.300 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.300 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.300 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.342 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.342 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.342 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.342 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.409 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.409 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.409 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.409 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.453 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.453 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.453 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.453 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.518 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.518 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.518 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.518 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.555 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.555 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.555 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.555 DEBG generated response, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.596 DEBG processing fast message, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.597 DEBG responding to 1 messages, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.597 DEBG handling echo function request, build-id: 0.1.0
[INFO] [stdout] Dec 02 08:06:23.597 DEBG generated response, build-id: 0.1.0
[INFO] [stderr]    Doc-tests fast-rpc
[INFO] [stdout] test client_server_comms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.38s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "aa7a88ed381cba63fccf2dbdca4672abb0c597878c321f858e250946238d4894", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aa7a88ed381cba63fccf2dbdca4672abb0c597878c321f858e250946238d4894", kill_on_drop: false }`
[INFO] [stdout] aa7a88ed381cba63fccf2dbdca4672abb0c597878c321f858e250946238d4894