[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.59.0 for beta-1.60-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoyent%2Frust-fast" "/workspace/builds/worker-23/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-23/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/joyent/rust-fast on toolchain 1.59.0 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.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-23/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.59.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.59.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded slog-term v2.9.0 [INFO] [stderr] Downloaded crc16 v0.4.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 684b5bc6898eb0af04ad5a6acaee1611e32777bb65d14a24a5877a52359ab5d6 [INFO] running `Command { std: "docker" "start" "-a" "684b5bc6898eb0af04ad5a6acaee1611e32777bb65d14a24a5877a52359ab5d6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "684b5bc6898eb0af04ad5a6acaee1611e32777bb65d14a24a5877a52359ab5d6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "684b5bc6898eb0af04ad5a6acaee1611e32777bb65d14a24a5877a52359ab5d6", kill_on_drop: false }` [INFO] [stdout] 684b5bc6898eb0af04ad5a6acaee1611e32777bb65d14a24a5877a52359ab5d6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] dcc8b7e93c2dad9f7608bca9ea2ad664c06dc1fcc9687caec8596c1ba72245d6 [INFO] running `Command { std: "docker" "start" "-a" "dcc8b7e93c2dad9f7608bca9ea2ad664c06dc1fcc9687caec8596c1ba72245d6", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.119 [INFO] [stderr] Compiling maybe-uninit v2.0.0 [INFO] [stderr] Compiling futures v0.1.31 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling syn v1.0.86 [INFO] [stderr] Compiling slog v2.7.0 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling crc16 v0.4.0 [INFO] [stderr] Compiling arc-swap v1.5.0 [INFO] [stderr] Compiling crossbeam v0.2.12 [INFO] [stderr] Compiling crossbeam-utils v0.7.2 [INFO] [stderr] Compiling autocfg v0.1.8 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling memoffset v0.5.6 [INFO] [stderr] Compiling crossbeam-epoch v0.8.2 [INFO] [stderr] Compiling num-bigint v0.2.6 [INFO] [stderr] Compiling num-complex v0.2.4 [INFO] [stderr] Compiling num-iter v0.1.42 [INFO] [stderr] Compiling num-rational v0.2.4 [INFO] [stderr] Compiling lock_api v0.3.4 [INFO] [stderr] Compiling parking_lot_core v0.6.2 [INFO] [stderr] Compiling parking_lot v0.9.0 [INFO] [stderr] Compiling aho-corasick v0.7.18 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling smallvec v0.6.14 [INFO] [stderr] Compiling iovec v0.1.4 [INFO] [stderr] Compiling net2 v0.2.37 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling slog-scope v4.4.0 [INFO] [stderr] Compiling bytes v0.4.12 [INFO] [stderr] Compiling slog-stdlog v3.0.5 [INFO] [stderr] Compiling mio v0.6.23 [INFO] [stderr] Compiling crossbeam-queue v0.2.3 [INFO] [stderr] Compiling regex v1.5.4 [INFO] [stderr] Compiling tokio-executor v0.1.10 [INFO] [stderr] Compiling tokio-io v0.1.13 [INFO] [stderr] Compiling tokio-sync v0.1.8 [INFO] [stderr] Compiling crossbeam-deque v0.7.4 [INFO] [stderr] Compiling tokio-timer v0.2.13 [INFO] [stderr] Compiling tokio-current-thread v0.1.7 [INFO] [stderr] Compiling tokio-threadpool v0.1.18 [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-uds v0.2.7 [INFO] [stderr] Compiling tokio-udp v0.1.6 [INFO] [stderr] Compiling tokio-tcp v0.1.4 [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 v0.2.1 [INFO] [stderr] Compiling tokio v0.1.22 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling num-derive v0.3.3 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling fast-rpc v0.3.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 19.64s [INFO] running `Command { std: "docker" "inspect" "dcc8b7e93c2dad9f7608bca9ea2ad664c06dc1fcc9687caec8596c1ba72245d6", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "dcc8b7e93c2dad9f7608bca9ea2ad664c06dc1fcc9687caec8596c1ba72245d6", kill_on_drop: false }` [INFO] [stdout] dcc8b7e93c2dad9f7608bca9ea2ad664c06dc1fcc9687caec8596c1ba72245d6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 4d2398f098e2c5d1723a0d7252625e12f62c8890bfce5ca3714d39281a06c608 [INFO] running `Command { std: "docker" "start" "-a" "4d2398f098e2c5d1723a0d7252625e12f62c8890bfce5ca3714d39281a06c608", kill_on_drop: false }` [INFO] [stderr] Compiling num_threads v0.1.3 [INFO] [stderr] Compiling time-macros v0.2.3 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling term v0.7.0 [INFO] [stderr] Compiling time v0.3.7 [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 5.47s [INFO] running `Command { std: "docker" "inspect" "4d2398f098e2c5d1723a0d7252625e12f62c8890bfce5ca3714d39281a06c608", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4d2398f098e2c5d1723a0d7252625e12f62c8890bfce5ca3714d39281a06c608", kill_on_drop: false }` [INFO] [stdout] 4d2398f098e2c5d1723a0d7252625e12f62c8890bfce5ca3714d39281a06c608 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-23/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+1.59.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 72a372d507203bc997aa9f239498a1f8706e0748e3dfc08a638eed94413260c2 [INFO] running `Command { std: "docker" "start" "-a" "72a372d507203bc997aa9f239498a1f8706e0748e3dfc08a638eed94413260c2", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.10s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/fast_rpc-4ddcce0975aab4c7) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test protocol::test::prop_fast_message_roundtrip ... ok [INFO] [stdout] test protocol::test::prop_fast_message_bundling ... ok [INFO] [stdout] test protocol::test::prop_fast_message_decoding ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.81s [INFO] [stdout] [INFO] [stderr] Running tests/client_server_test.rs (/opt/rustwide/target/debug/deps/client_server_test-2f54c88799d811aa) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] Feb 28 11:41:26.857 INFO listening for fast requests, address: 127.0.0.1:56652, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.966 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.966 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.966 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.966 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.967 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.967 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.967 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.967 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.969 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.969 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.969 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.969 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.971 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.971 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.971 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.971 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.973 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.973 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.973 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.973 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.976 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.976 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.976 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.976 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.979 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.980 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.980 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.980 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.983 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.983 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.983 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.983 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.987 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.987 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.987 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.987 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.990 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.990 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.990 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.990 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.993 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.993 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.993 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.993 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.998 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.998 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.998 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:26.998 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.003 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.004 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.004 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.004 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.009 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.009 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.009 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.009 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.015 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.016 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.016 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.016 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.021 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.021 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.022 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.022 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.028 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.028 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.028 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.028 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.035 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.036 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.036 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.036 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.044 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.044 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.044 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.044 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.052 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.052 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.052 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.052 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.061 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.062 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.062 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.062 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.070 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.070 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.070 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.071 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.080 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.080 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.080 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.080 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.089 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.089 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.089 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.089 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.100 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.100 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.100 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.100 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.110 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.110 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.110 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.110 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.121 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.121 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.121 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.121 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.132 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.132 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.132 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.132 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.143 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.143 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.143 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.143 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.155 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.155 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.155 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.155 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.167 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.167 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.167 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.167 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.180 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.180 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.180 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.180 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.193 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.193 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.193 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.193 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.207 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.207 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.207 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.207 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.221 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.221 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.221 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.221 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.235 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.235 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.235 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.235 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.249 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.249 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.249 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.249 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.264 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.264 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.264 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.264 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.280 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.280 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.280 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.280 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.296 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.297 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.297 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.297 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.313 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.314 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.314 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.314 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.331 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.331 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.331 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.331 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.349 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.349 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.349 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.349 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.367 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.367 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.367 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.367 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.385 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.385 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.385 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.385 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.404 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.404 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.404 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.404 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.423 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.423 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.423 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.423 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.442 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.442 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.442 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.442 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.462 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.462 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.462 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.462 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.482 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.482 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.482 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.482 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.502 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.502 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.502 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.502 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.523 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.523 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.523 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.523 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.544 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.544 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.544 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.544 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.566 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.566 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.566 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.566 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.587 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.588 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.588 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.588 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.610 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.610 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.610 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.610 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.633 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.634 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.634 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.634 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.657 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.657 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.657 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.657 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.681 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.681 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.681 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.681 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.705 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.706 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.706 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.706 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.730 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.730 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.730 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.730 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.755 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.755 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.755 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.755 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.780 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.780 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.780 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.780 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.806 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.806 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.806 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.806 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.832 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.832 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.832 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.832 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.859 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.859 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.859 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.859 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.887 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.887 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.887 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.887 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.914 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.914 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.914 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.914 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.938 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.938 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.938 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.938 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.964 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.964 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.965 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.965 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.993 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.993 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.993 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:27.993 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.022 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.022 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.022 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.022 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.052 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.052 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.052 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.052 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.080 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.080 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.080 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.080 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.109 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.109 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.109 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.109 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.132 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.132 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.132 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.132 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.154 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.154 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.154 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.155 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.187 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.187 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.187 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.187 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.219 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.219 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.219 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.220 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.251 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.251 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.251 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.252 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.283 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.283 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.283 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.283 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.315 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.315 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.315 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.315 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.347 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.347 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.347 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.347 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.377 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.377 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.377 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.377 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.408 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.408 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.408 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.408 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.440 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.440 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.440 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.440 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.473 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.473 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.473 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.474 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.508 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.508 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.508 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.508 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.543 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.543 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.543 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.543 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.578 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.578 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.578 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.578 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.615 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.615 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.615 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.615 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.651 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.651 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.651 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.651 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.688 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.688 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.688 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.688 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.725 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.725 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.725 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.725 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.760 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.760 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.760 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.760 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.793 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.793 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.793 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.793 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.831 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.831 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.831 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.831 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.866 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.866 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.866 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.866 DEBG generated response, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.895 DEBG processing fast message, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.895 DEBG responding to 1 messages, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.895 DEBG handling echo function request, build-id: 0.1.0 [INFO] [stdout] Feb 28 11:41:28.895 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.05s [INFO] [stdout] [INFO] [stdout] Feb 28 11:41:28.907 DEBG transmitted response to client, build-id: 0.1.0 [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" "72a372d507203bc997aa9f239498a1f8706e0748e3dfc08a638eed94413260c2", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "72a372d507203bc997aa9f239498a1f8706e0748e3dfc08a638eed94413260c2", kill_on_drop: false }` [INFO] [stdout] 72a372d507203bc997aa9f239498a1f8706e0748e3dfc08a638eed94413260c2