[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 1.60.0 for beta-1.61-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 1.60.0 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "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-2/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.60.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded term v0.7.0 [INFO] [stderr] Downloaded crc16 v0.4.0 [INFO] [stderr] Downloaded crossbeam v0.2.12 [INFO] [stderr] Downloaded arc-swap v1.5.0 [INFO] [stderr] Downloaded slog-stdlog v3.0.5 [INFO] [stderr] Downloaded slog v2.7.0 [INFO] [stderr] Downloaded slog-term v2.9.0 [INFO] [stderr] Downloaded slog-scope v4.4.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 5ee9d7646624f67dadd84614c447642330ac863d48e02f6f3e6b701b7161d941 [INFO] running `Command { std: "docker" "start" "-a" "5ee9d7646624f67dadd84614c447642330ac863d48e02f6f3e6b701b7161d941", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "5ee9d7646624f67dadd84614c447642330ac863d48e02f6f3e6b701b7161d941", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5ee9d7646624f67dadd84614c447642330ac863d48e02f6f3e6b701b7161d941", kill_on_drop: false }` [INFO] [stdout] 5ee9d7646624f67dadd84614c447642330ac863d48e02f6f3e6b701b7161d941 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 542d00e2ebe564c825b8691165a6a8883d8b24ece56b370c5616f5e44f2af34b [INFO] running `Command { std: "docker" "start" "-a" "542d00e2ebe564c825b8691165a6a8883d8b24ece56b370c5616f5e44f2af34b", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling libc v0.2.123 [INFO] [stderr] Compiling log v0.4.16 [INFO] [stderr] Compiling cfg-if v1.0.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 scopeguard v1.1.0 [INFO] [stderr] Compiling slab v0.4.6 [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 proc-macro2 v1.0.37 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling syn v1.0.91 [INFO] [stderr] Compiling slog v2.7.0 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling crc16 v0.4.0 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling arc-swap v1.5.0 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling crossbeam v0.2.12 [INFO] [stderr] Compiling semver v0.9.0 [INFO] [stderr] Compiling lock_api v0.3.4 [INFO] [stderr] Compiling autocfg v0.1.8 [INFO] [stderr] Compiling smallvec v0.6.14 [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 num-integer v0.1.44 [INFO] [stderr] Compiling memoffset v0.5.6 [INFO] [stderr] Compiling crossbeam-epoch v0.8.2 [INFO] [stderr] Compiling num-bigint v0.2.6 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling num-rational v0.2.4 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling num-complex v0.2.4 [INFO] [stderr] Compiling num-iter v0.1.42 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling aho-corasick v0.7.18 [INFO] [stderr] Compiling rustc_version v0.2.3 [INFO] [stderr] Compiling slog-scope v4.4.0 [INFO] [stderr] Compiling slog-stdlog v3.0.5 [INFO] [stderr] Compiling iovec v0.1.4 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling net2 v0.2.37 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling quote v1.0.18 [INFO] [stderr] Compiling tokio-sync v0.1.8 [INFO] [stderr] Compiling parking_lot_core v0.6.2 [INFO] [stderr] Compiling parking_lot v0.9.0 [INFO] [stderr] Compiling bytes v0.4.12 [INFO] [stderr] Compiling mio v0.6.23 [INFO] [stderr] Compiling tokio-io v0.1.13 [INFO] [stderr] Compiling regex v1.5.5 [INFO] [stderr] Compiling tokio-codec v0.1.2 [INFO] [stderr] Compiling tokio-executor v0.1.10 [INFO] [stderr] Compiling crossbeam-queue v0.2.3 [INFO] [stderr] Compiling mio-uds v0.6.8 [INFO] [stderr] Compiling tokio-reactor v0.1.12 [INFO] [stderr] Compiling tokio-current-thread v0.1.7 [INFO] [stderr] Compiling tokio-timer v0.2.13 [INFO] [stderr] Compiling crossbeam-deque v0.7.4 [INFO] [stderr] Compiling tokio-threadpool v0.1.18 [INFO] [stderr] Compiling tokio-uds v0.2.7 [INFO] [stderr] Compiling tokio-tcp v0.1.4 [INFO] [stderr] Compiling tokio-udp v0.1.6 [INFO] [stderr] Compiling env_logger v0.6.2 [INFO] [stderr] Compiling quickcheck v0.8.5 [INFO] [stderr] Compiling tokio-fs v0.1.7 [INFO] [stderr] Compiling num-derive v0.3.3 [INFO] [stderr] Compiling tokio v0.1.22 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling num v0.2.1 [INFO] [stderr] Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 14.28s [INFO] running `Command { std: "docker" "inspect" "542d00e2ebe564c825b8691165a6a8883d8b24ece56b370c5616f5e44f2af34b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "542d00e2ebe564c825b8691165a6a8883d8b24ece56b370c5616f5e44f2af34b", kill_on_drop: false }` [INFO] [stdout] 542d00e2ebe564c825b8691165a6a8883d8b24ece56b370c5616f5e44f2af34b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5342203974db085987d2e964519bba5d8b2ada5f4dd9e85fed5532097adcc7d7 [INFO] running `Command { std: "docker" "start" "-a" "5342203974db085987d2e964519bba5d8b2ada5f4dd9e85fed5532097adcc7d7", kill_on_drop: false }` [INFO] [stderr] Compiling num_threads v0.1.5 [INFO] [stderr] Compiling once_cell v1.10.0 [INFO] [stderr] Compiling time-macros v0.2.4 [INFO] [stderr] Compiling unicode-width v0.1.9 [INFO] [stderr] Compiling strsim v0.8.0 [INFO] [stderr] Compiling vec_map v0.8.2 [INFO] [stderr] Compiling ansi_term v0.12.1 [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 textwrap v0.11.0 [INFO] [stderr] Compiling thread_local v1.1.4 [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 time v0.3.9 [INFO] [stderr] Compiling slog-term v2.9.0 [INFO] [stderr] Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 6.16s [INFO] running `Command { std: "docker" "inspect" "5342203974db085987d2e964519bba5d8b2ada5f4dd9e85fed5532097adcc7d7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5342203974db085987d2e964519bba5d8b2ada5f4dd9e85fed5532097adcc7d7", kill_on_drop: false }` [INFO] [stdout] 5342203974db085987d2e964519bba5d8b2ada5f4dd9e85fed5532097adcc7d7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+1.60.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] ef2906018c542de234c5dfad4d88870326730aad5bc332108f7b4c66ffa7841e [INFO] running `Command { std: "docker" "start" "-a" "ef2906018c542de234c5dfad4d88870326730aad5bc332108f7b4c66ffa7841e", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-9be4257d8ca9deac) [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] [stderr] Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-88c7829a3067184f) [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.90s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] Apr 15 15:15:36.665 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.761 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.761 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.761 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.761 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.766 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.766 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.766 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.766 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.778 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.778 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.778 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.778 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.782 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.782 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.782 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.783 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.787 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.787 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.787 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.787 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.794 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.794 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.794 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.794 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.800 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.800 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.800 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.800 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.808 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.808 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.808 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.808 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.813 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.814 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.814 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.814 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.818 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.818 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.818 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.818 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.823 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.823 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.823 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.823 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.828 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.828 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.828 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.828 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.833 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.833 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.833 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.833 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.839 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.839 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.839 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.839 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.844 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.844 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.845 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.845 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.851 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.851 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.851 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.851 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.858 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.858 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.858 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.858 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.866 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.866 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.866 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.866 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.873 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.873 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.873 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.874 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.882 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.882 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.882 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.882 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.895 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.895 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.895 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.895 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.904 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.904 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.904 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.904 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.913 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.913 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.913 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.914 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.924 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.924 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.924 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.924 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.934 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.934 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.934 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.935 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.950 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.950 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.950 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.950 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.961 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.961 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.961 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.961 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.976 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.976 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.976 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.976 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.988 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.988 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.988 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:36.988 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.000 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.000 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.000 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.000 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.012 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.012 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.012 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.012 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.026 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.026 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.026 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.026 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.039 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.040 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.040 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.040 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.054 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.054 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.054 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.054 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.068 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.068 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.068 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.068 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.082 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.082 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.082 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.082 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.096 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.097 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.097 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.097 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.112 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.112 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.112 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.112 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.128 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.128 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.128 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.128 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.145 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.145 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.145 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.145 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.162 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.162 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.162 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.162 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.180 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.181 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.181 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.181 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.199 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.199 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.199 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.199 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.217 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.217 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.217 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.217 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.236 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.236 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.236 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.236 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.255 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.256 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.256 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.256 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.276 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.276 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.276 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.276 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.296 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.296 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.296 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.296 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.316 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.316 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.316 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.316 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.338 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.338 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.338 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.338 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.359 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.359 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.359 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.359 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.380 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.380 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.380 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.380 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.402 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.402 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.402 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.402 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.425 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.425 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.425 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.425 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.448 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.448 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.448 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.448 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.472 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.472 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.472 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.472 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.495 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.495 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.495 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.495 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.519 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.519 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.519 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.519 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.543 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.543 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.543 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.543 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.568 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.568 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.568 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.568 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.593 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.593 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.593 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.593 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.619 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.619 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.619 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.619 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.645 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.645 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.645 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.645 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.671 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.672 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.672 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.672 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.697 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.697 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.697 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.697 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.724 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.724 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.724 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.724 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.751 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.751 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.751 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.751 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.778 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.778 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.778 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.778 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.806 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.806 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.806 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.806 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.834 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.834 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.834 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.834 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.863 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.863 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.863 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.863 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.893 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.893 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.893 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.893 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.923 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.923 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.923 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.923 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.954 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.954 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.954 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.954 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.986 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.986 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.986 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:37.986 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.018 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.018 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.018 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.018 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.049 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.049 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.049 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.049 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.082 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.082 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.082 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.082 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.114 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.114 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.114 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.114 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.150 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.150 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.150 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.150 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.194 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.194 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.194 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.194 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.228 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.228 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.228 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.228 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.264 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.264 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.264 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.264 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.299 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.299 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.299 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.299 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.335 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.335 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.335 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.335 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.371 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.371 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.371 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.371 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.407 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.407 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.407 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.407 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.443 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.443 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.443 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.443 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.480 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.480 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.480 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.480 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.517 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.517 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.517 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.517 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.555 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.555 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.555 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.555 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.593 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.593 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.593 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.593 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.631 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.631 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.631 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.631 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.670 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.670 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.670 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.670 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.709 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.709 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.709 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.709 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.756 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.756 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.756 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.756 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.796 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.796 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.796 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.796 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.837 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.837 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.837 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.837 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.878 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.878 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.878 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Apr 15 15:15:38.878 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.23s [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" "ef2906018c542de234c5dfad4d88870326730aad5bc332108f7b4c66ffa7841e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ef2906018c542de234c5dfad4d88870326730aad5bc332108f7b4c66ffa7841e", kill_on_drop: false }` [INFO] [stdout] ef2906018c542de234c5dfad4d88870326730aad5bc332108f7b4c66ffa7841e