[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 try#8262275854dc0e5381c9d42d56257d94932c5a87 for pr-90799 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-83/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-83/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/joyent/rust-fast 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 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-83/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] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded crc16 v0.4.0 [INFO] [stderr] Downloaded slog-scope v4.4.0 [INFO] [stderr] Downloaded arc-swap v1.5.0 [INFO] [stderr] Downloaded slog-term v2.8.0 [INFO] [stderr] Downloaded term v0.7.0 [INFO] [stderr] Downloaded slog-stdlog v3.0.5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 26f7193ff75a812eb4f22a73fc54f41a7a82e119a2d4709df7ad3f86a822b1ec [INFO] running `Command { std: "docker" "start" "-a" "26f7193ff75a812eb4f22a73fc54f41a7a82e119a2d4709df7ad3f86a822b1ec", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "26f7193ff75a812eb4f22a73fc54f41a7a82e119a2d4709df7ad3f86a822b1ec", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "26f7193ff75a812eb4f22a73fc54f41a7a82e119a2d4709df7ad3f86a822b1ec", kill_on_drop: false }` [INFO] [stdout] 26f7193ff75a812eb4f22a73fc54f41a7a82e119a2d4709df7ad3f86a822b1ec [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c0ae3ecda737bd2d645e173141ecb78e1f4192e27f25fb6c39310de963029d47 [INFO] running `Command { std: "docker" "start" "-a" "c0ae3ecda737bd2d645e173141ecb78e1f4192e27f25fb6c39310de963029d47", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling autocfg v1.0.1 [INFO] [stderr] Compiling libc v0.2.109 [INFO] [stderr] Compiling cfg-if v0.1.10 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling maybe-uninit v2.0.0 [INFO] [stderr] Compiling futures v0.1.31 [INFO] [stderr] Compiling semver-parser v0.7.0 [INFO] [stderr] Compiling autocfg v0.1.7 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling slog v2.7.0 [INFO] [stderr] Compiling syn v1.0.82 [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 serde_derive v1.0.130 [INFO] [stderr] Compiling itoa v0.4.8 [INFO] [stderr] Compiling crossbeam v0.2.12 [INFO] [stderr] Compiling lock_api v0.3.4 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling proc-macro2 v1.0.32 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling crossbeam-utils v0.7.2 [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-rational v0.2.4 [INFO] [stderr] Compiling num-iter v0.1.42 [INFO] [stderr] Compiling num-complex v0.2.4 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling serde_json v1.0.72 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling semver v0.9.0 [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 smallvec v0.6.14 [INFO] [stderr] Compiling quote v1.0.10 [INFO] [stderr] Compiling rustc_version v0.2.3 [INFO] [stderr] Compiling slog-scope v4.4.0 [INFO] [stderr] Compiling iovec v0.1.4 [INFO] [stderr] Compiling net2 v0.2.37 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling tokio-sync v0.1.8 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling slog-stdlog v3.0.5 [INFO] [stderr] Compiling bytes v0.4.12 [INFO] [stderr] Compiling parking_lot_core v0.6.2 [INFO] [stderr] Compiling parking_lot v0.9.0 [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-io v0.1.13 [INFO] [stderr] Compiling tokio-current-thread v0.1.7 [INFO] [stderr] Compiling tokio-timer v0.2.13 [INFO] [stderr] Compiling regex v1.5.4 [INFO] [stderr] Compiling crossbeam-deque v0.7.4 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling tokio-codec v0.1.2 [INFO] [stderr] Compiling tokio-threadpool v0.1.18 [INFO] [stderr] Compiling tokio-reactor v0.1.12 [INFO] [stderr] Compiling mio-uds v0.6.8 [INFO] [stderr] Compiling tokio-fs v0.1.7 [INFO] [stderr] Compiling num v0.2.1 [INFO] [stderr] Compiling tokio-uds v0.2.7 [INFO] [stderr] Compiling tokio-udp v0.1.6 [INFO] [stderr] Compiling tokio-tcp v0.1.4 [INFO] [stderr] Compiling tokio v0.1.22 [INFO] [stderr] Compiling env_logger v0.6.2 [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 25.66s [INFO] running `Command { std: "docker" "inspect" "c0ae3ecda737bd2d645e173141ecb78e1f4192e27f25fb6c39310de963029d47", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c0ae3ecda737bd2d645e173141ecb78e1f4192e27f25fb6c39310de963029d47", kill_on_drop: false }` [INFO] [stdout] c0ae3ecda737bd2d645e173141ecb78e1f4192e27f25fb6c39310de963029d47 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] bb8508e578ac9cbc1398d8b16dd812f64a6de5ae170cb8ec1c1e87026e6c8e45 [INFO] running `Command { std: "docker" "start" "-a" "bb8508e578ac9cbc1398d8b16dd812f64a6de5ae170cb8ec1c1e87026e6c8e45", kill_on_drop: false }` [INFO] [stderr] Compiling once_cell v1.8.0 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling thread_local v1.1.3 [INFO] [stderr] Compiling term v0.7.0 [INFO] [stderr] Compiling slog-term v2.8.0 [INFO] [stderr] Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 34.53s [INFO] running `Command { std: "docker" "inspect" "bb8508e578ac9cbc1398d8b16dd812f64a6de5ae170cb8ec1c1e87026e6c8e45", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "bb8508e578ac9cbc1398d8b16dd812f64a6de5ae170cb8ec1c1e87026e6c8e45", kill_on_drop: false }` [INFO] [stdout] bb8508e578ac9cbc1398d8b16dd812f64a6de5ae170cb8ec1c1e87026e6c8e45 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-83/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:e8397a04204aaf8b9f284182a6f9e27a3c2e67044d0943b4415b160bfc957681" "/opt/rustwide/cargo-home/bin/cargo" "+8262275854dc0e5381c9d42d56257d94932c5a87" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] d9bbee15f770d8d2187155a446d2c187b14d58b5d3f5e811dd2f5e1e3a76eabc [INFO] running `Command { std: "docker" "start" "-a" "d9bbee15f770d8d2187155a446d2c187b14d58b5d3f5e811dd2f5e1e3a76eabc", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.15s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-ac44755c267d9fef) [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] [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.11s [INFO] [stdout] [INFO] [stderr] Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-ac1cd5a404738e09) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] Dec 04 20:53:22.360 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.574 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.574 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.574 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.574 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.576 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.576 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.576 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.576 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.577 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.578 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.578 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.578 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.580 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.580 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.580 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.580 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.584 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.584 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.584 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.584 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.589 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.589 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.589 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.589 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.594 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.594 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.594 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.594 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.598 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.598 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.598 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.598 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.604 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.604 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.604 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.604 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.611 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.611 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.611 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.611 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.617 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.618 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.618 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.618 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.625 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.625 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.625 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.625 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.632 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.632 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.632 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.633 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.642 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.642 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.642 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.642 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.652 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.653 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.653 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.653 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.662 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.663 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.663 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.663 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.673 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.673 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.673 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.673 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.684 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.684 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.684 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.684 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.696 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.696 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.696 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.696 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.709 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.709 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.709 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.709 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.721 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.721 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.721 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.721 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.734 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.734 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.734 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.735 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.749 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.749 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.749 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.749 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.762 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.763 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.763 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.763 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.778 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.778 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.778 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.778 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.792 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.793 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.793 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.793 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.807 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.807 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.807 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.807 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.822 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.822 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.822 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.822 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.837 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.837 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.837 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.837 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.853 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.853 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.853 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.853 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.868 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.868 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.868 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.868 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.884 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.885 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.885 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.885 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.904 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.905 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.905 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.905 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.925 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.925 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.925 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.925 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.946 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.946 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.946 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.946 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.966 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.966 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.966 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.966 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.985 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.986 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.986 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:22.986 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.003 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.003 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.003 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.004 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.025 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.025 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.025 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.026 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.045 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.045 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.045 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.045 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.069 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.069 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.069 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.069 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.091 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.091 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.091 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.091 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.113 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.113 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.113 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.113 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.133 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.133 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.133 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.133 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.156 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.156 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.156 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.156 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.182 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.182 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.182 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.182 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.203 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.203 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.203 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.203 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.227 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.227 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.227 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.227 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.250 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.251 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.251 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.251 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.275 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.275 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.275 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.275 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.301 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.302 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.302 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.302 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.324 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.324 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.324 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.324 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.353 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.353 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.353 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.353 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.378 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.378 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.378 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.378 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.403 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.404 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.404 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.404 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.435 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.436 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.436 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.436 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.464 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.464 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.464 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.464 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.497 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.498 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.498 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.498 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.531 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.531 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.531 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.531 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.564 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.564 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.564 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.565 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.602 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.602 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.602 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.603 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.636 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.636 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.637 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.637 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.671 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.672 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.672 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.672 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.706 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.706 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.706 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.706 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.741 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.741 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.741 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.741 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.776 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.777 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.777 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.777 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.809 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.809 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.810 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.810 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.841 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.841 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.841 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.841 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.871 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.871 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.871 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.871 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.898 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.898 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.898 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.898 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.934 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.934 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.934 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.934 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.967 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.967 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.967 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:23.967 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.000 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.000 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.000 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.000 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.031 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.031 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.031 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.031 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.063 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.063 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.063 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.063 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.095 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.095 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.095 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.096 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.131 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.132 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.132 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.132 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.168 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.168 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.168 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.168 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.205 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.205 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.205 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.205 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.244 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.244 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.244 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.244 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.277 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.277 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.277 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.277 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.311 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.311 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.311 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.311 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.348 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.348 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.348 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.348 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.390 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.391 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.391 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.391 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.436 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.436 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.436 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.436 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.477 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.477 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.477 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.477 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.516 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.516 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.516 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.516 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.561 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.562 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.562 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.562 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.605 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.605 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.605 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.605 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.648 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.648 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.648 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.648 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.684 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.684 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.684 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.684 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.731 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.732 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.732 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.732 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.775 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.775 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.775 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.775 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.810 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.810 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.810 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.810 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.854 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.854 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.854 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.854 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.890 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.890 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.890 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.890 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.925 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.925 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.925 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.925 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.968 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.968 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.968 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:24.969 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:25.015 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:25.015 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:25.015 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Dec 04 20:53:25.015 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.68s [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" "d9bbee15f770d8d2187155a446d2c187b14d58b5d3f5e811dd2f5e1e3a76eabc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "d9bbee15f770d8d2187155a446d2c187b14d58b5d3f5e811dd2f5e1e3a76eabc", kill_on_drop: false }` [INFO] [stdout] d9bbee15f770d8d2187155a446d2c187b14d58b5d3f5e811dd2f5e1e3a76eabc