[INFO] updating cached 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" "-c" "remote.origin.fetch=refs/heads/*:refs/heads/*" "fetch" "origin" "--force" "--prune", kill_on_drop: false }` [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 1ddf01bba94c010d5627bf0eb0ff260b8085a460 [INFO] testing joyent/rust-fast against master#8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e for pr-74409 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-4/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-4/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "read-manifest" "--manifest-path" "Cargo.toml", 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-4/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 2bf0757798f00a7b5500dc7267358ddb36fad4fdc49e839d98229964803817af [INFO] running `Command { std: "docker" "start" "-a" "2bf0757798f00a7b5500dc7267358ddb36fad4fdc49e839d98229964803817af", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2bf0757798f00a7b5500dc7267358ddb36fad4fdc49e839d98229964803817af", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2bf0757798f00a7b5500dc7267358ddb36fad4fdc49e839d98229964803817af", kill_on_drop: false }` [INFO] [stdout] 2bf0757798f00a7b5500dc7267358ddb36fad4fdc49e839d98229964803817af [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 85159ee4caacb1b30234eec1a85f86748c1b69888cde065d5d5767a2dda32463 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "85159ee4caacb1b30234eec1a85f86748c1b69888cde065d5d5767a2dda32463", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.0.0 [INFO] [stderr] Compiling cfg-if v0.1.10 [INFO] [stderr] Compiling libc v0.2.73 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling log v0.4.11 [INFO] [stderr] Compiling maybe-uninit v2.0.0 [INFO] [stderr] Compiling futures v0.1.29 [INFO] [stderr] Compiling semver-parser v0.7.0 [INFO] [stderr] Compiling byteorder v1.3.4 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling autocfg v0.1.7 [INFO] [stderr] Compiling slab v0.4.2 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling proc-macro2 v1.0.19 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling memchr v2.3.3 [INFO] [stderr] Compiling unicode-xid v0.2.1 [INFO] [stderr] Compiling serde v1.0.114 [INFO] [stderr] Compiling slog v2.5.2 [INFO] [stderr] Compiling syn v1.0.35 [INFO] [stderr] Compiling ryu v1.0.5 [INFO] [stderr] Compiling regex-syntax v0.6.18 [INFO] [stderr] Compiling serde_derive v1.0.114 [INFO] [stderr] Compiling arc-swap v0.4.7 [INFO] [stderr] Compiling crc16 v0.4.0 [INFO] [stderr] Compiling serde_json v1.0.57 [INFO] [stderr] Compiling crossbeam v0.2.12 [INFO] [stderr] Compiling itoa v0.4.6 [INFO] [stderr] Compiling lock_api v0.3.4 [INFO] [stderr] Compiling thread_local v1.0.1 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling semver v0.9.0 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling crossbeam-utils v0.7.2 [INFO] [stderr] Compiling num-traits v0.2.12 [INFO] [stderr] Compiling num-integer v0.1.43 [INFO] [stderr] Compiling memoffset v0.5.5 [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.41 [INFO] [stderr] Compiling num-complex v0.2.4 [INFO] [stderr] Compiling rustc_version v0.2.3 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling smallvec v0.6.13 [INFO] [stderr] Compiling aho-corasick v0.7.13 [INFO] [stderr] Compiling slog-scope v4.3.0 [INFO] [stderr] Compiling parking_lot_core v0.6.2 [INFO] [stderr] Compiling parking_lot v0.9.0 [INFO] [stderr] Compiling quote v1.0.7 [INFO] [stderr] Compiling slog-stdlog v3.0.5 [INFO] [stderr] Compiling iovec v0.1.4 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling net2 v0.2.34 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling tokio-sync v0.1.8 [INFO] [stderr] Compiling bytes v0.4.12 [INFO] [stderr] Compiling tokio-executor v0.1.10 [INFO] [stderr] Compiling crossbeam-queue v0.2.3 [INFO] [stderr] Compiling mio v0.6.22 [INFO] [stderr] Compiling tokio-timer v0.2.13 [INFO] [stderr] Compiling tokio-current-thread v0.1.7 [INFO] [stderr] Compiling crossbeam-deque v0.7.3 [INFO] [stderr] Compiling tokio-io v0.1.13 [INFO] [stderr] Compiling tokio-threadpool v0.1.18 [INFO] [stderr] Compiling regex v1.3.9 [INFO] [stderr] Compiling tokio-codec v0.1.2 [INFO] [stderr] Compiling tokio-reactor v0.1.12 [INFO] [stderr] Compiling mio-uds v0.6.8 [INFO] [stderr] Compiling tokio-fs v0.1.7 [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 v0.2.1 [INFO] [stderr] Compiling num-derive v0.3.0 [INFO] [stderr] Compiling chrono v0.4.13 [INFO] [stderr] Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 39s [INFO] running `Command { std: "docker" "inspect" "85159ee4caacb1b30234eec1a85f86748c1b69888cde065d5d5767a2dda32463", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "85159ee4caacb1b30234eec1a85f86748c1b69888cde065d5d5767a2dda32463", kill_on_drop: false }` [INFO] [stdout] 85159ee4caacb1b30234eec1a85f86748c1b69888cde065d5d5767a2dda32463 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6e31a2e409427d80268996036fbe2dbbad6add609622b9fb88173b25f1f5855b [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "6e31a2e409427d80268996036fbe2dbbad6add609622b9fb88173b25f1f5855b", kill_on_drop: false }` [INFO] [stderr] Compiling bitflags v1.2.1 [INFO] [stderr] Compiling unicode-width v0.1.8 [INFO] [stderr] Compiling vec_map v0.8.2 [INFO] [stderr] Compiling ansi_term v0.11.0 [INFO] [stderr] Compiling strsim v0.8.0 [INFO] [stderr] Compiling dirs-sys v0.3.5 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling dirs v2.0.2 [INFO] [stderr] Compiling term v0.6.1 [INFO] [stderr] Compiling clap v2.33.1 [INFO] [stderr] Compiling slog-term v2.6.0 [INFO] [stderr] Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 25.12s [INFO] running `Command { std: "docker" "inspect" "6e31a2e409427d80268996036fbe2dbbad6add609622b9fb88173b25f1f5855b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6e31a2e409427d80268996036fbe2dbbad6add609622b9fb88173b25f1f5855b", kill_on_drop: false }` [INFO] [stdout] 6e31a2e409427d80268996036fbe2dbbad6add609622b9fb88173b25f1f5855b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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" "MAP_USER_ID=0" "-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" "--network" "none" "rustops/crates-build-env@sha256:38e1b949b06cde1964af550a6e548bd9b2b2ea170003ae122f8fc0886596cc07" "/opt/rustwide/cargo-home/bin/cargo" "+8534be72fc3b9c5f2f2dc4e4ee7b651a008e9a3e" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 002ea7d6051eec5ff3b425a8f47b0e3c5adea2499dd0a7565794e396ce8816d6 [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] running `Command { std: "docker" "start" "-a" "002ea7d6051eec5ff3b425a8f47b0e3c5adea2499dd0a7565794e396ce8816d6", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.24s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/fast_rpc-75841f3004c907d0 [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 [INFO] [stdout] [INFO] [stderr] Running /opt/rustwide/target/debug/deps/client_server_test-91d957d4face0188 [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] Jul 26 20:00:14.399 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.404 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.404 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.404 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.404 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.411 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.412 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.412 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.412 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.416 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.416 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.417 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.417 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.428 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.428 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.428 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.428 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.434 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.434 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.434 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.434 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.448 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.448 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.449 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.449 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.453 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.453 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.454 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.454 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.459 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.459 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.460 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.460 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.464 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.464 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.465 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.465 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.470 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.470 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.470 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.470 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.479 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.479 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.479 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.480 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.486 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.486 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.486 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.487 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.493 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.493 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.493 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.493 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.505 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.505 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.506 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.506 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.513 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.513 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.513 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.513 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.522 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.522 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.522 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.522 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.543 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.543 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.544 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.544 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.561 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.562 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.562 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.562 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.571 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.571 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.572 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.572 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.590 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.590 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.590 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.590 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.599 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.600 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.600 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.600 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.610 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.610 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.610 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.610 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.621 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.621 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.621 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.621 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.633 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.633 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.633 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.633 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.644 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.645 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.645 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.645 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.657 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.657 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.658 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.658 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.670 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.671 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.671 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.672 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.685 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.685 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.685 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.686 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.700 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.700 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.700 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.701 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.715 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.715 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.715 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.715 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.730 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.730 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.730 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.730 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.745 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.745 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.745 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.745 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.763 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.763 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.763 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.763 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.779 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.779 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.779 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.780 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.799 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.800 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.800 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.801 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.821 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.821 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.821 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.821 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.845 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.845 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.845 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.845 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.862 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.862 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.862 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.862 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.884 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.884 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.884 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.884 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.902 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.902 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.902 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.902 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.925 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.925 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.925 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.926 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.949 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.949 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.949 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.949 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.969 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.969 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.969 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.969 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.993 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.993 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.993 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:14.994 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.014 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.014 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.014 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.015 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.036 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.036 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.036 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.036 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.057 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.063 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.063 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.063 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.085 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.086 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.086 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.086 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.116 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.117 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.117 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.117 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.139 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.139 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.140 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.140 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.163 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.163 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.164 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.164 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.188 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.188 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.188 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.188 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.211 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.211 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.212 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.212 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.236 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.237 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.237 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.237 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.261 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.261 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.261 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.261 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.288 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.288 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.288 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.288 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.313 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.313 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.314 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.314 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.340 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.340 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.340 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.341 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.367 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.367 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.368 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.368 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.396 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.396 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.396 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.396 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.424 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.424 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.424 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.425 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.464 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.464 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.464 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.464 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.494 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.494 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.494 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.494 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.525 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.525 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.525 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.525 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.558 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.558 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.558 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.558 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.593 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.593 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.593 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.593 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.631 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.631 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.631 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.631 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.671 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.671 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.671 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.672 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.709 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.709 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.709 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.709 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.745 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.745 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.745 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.746 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.779 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.780 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.780 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.781 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.813 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.813 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.813 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.813 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.847 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.847 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.847 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.847 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.883 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.883 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.883 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.883 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.932 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.933 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.933 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.933 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.966 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.967 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.967 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:15.967 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.009 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.009 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.009 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.009 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.047 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.048 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.048 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.048 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.091 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.091 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.091 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.091 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.130 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.130 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.130 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.130 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.167 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.167 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.167 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.167 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.204 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.204 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.204 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.204 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.245 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.246 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.246 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.246 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.289 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.289 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.289 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.290 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.328 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.328 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.329 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.329 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.367 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.368 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.368 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.368 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.407 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.407 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.408 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.408 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.447 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.448 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.448 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.448 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.488 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.488 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.488 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.488 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.531 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.531 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.532 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.532 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.573 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.573 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.573 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.573 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.615 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.616 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.616 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.616 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.658 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.658 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.658 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.658 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.700 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.700 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.700 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.700 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.743 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.744 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.744 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.744 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.792 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.793 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.793 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.793 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.842 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.842 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.842 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.842 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.907 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.907 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.907 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.907 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.966 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.966 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.966 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Jul 26 20:00:16.966 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 [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 [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "002ea7d6051eec5ff3b425a8f47b0e3c5adea2499dd0a7565794e396ce8816d6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "002ea7d6051eec5ff3b425a8f47b0e3c5adea2499dd0a7565794e396ce8816d6", kill_on_drop: false }` [INFO] [stdout] 002ea7d6051eec5ff3b425a8f47b0e3c5adea2499dd0a7565794e396ce8816d6