[INFO] fetching crate solana-metrics 1.4.20... [INFO] testing solana-metrics-1.4.20 against beta-2021-01-01 for beta-1.50-1 [INFO] extracting crate solana-metrics 1.4.20 into /workspace/builds/worker-6/source [INFO] validating manifest of crates.io crate solana-metrics 1.4.20 on toolchain beta-2021-01-01 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "read-manifest" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] started tweaking crates.io crate solana-metrics 1.4.20 [INFO] finished tweaking crates.io crate solana-metrics 1.4.20 [INFO] tweaked toml for crates.io crate solana-metrics 1.4.20 written to /workspace/builds/worker-6/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2021-01-01" "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" "+beta-2021-01-01" "fetch" "--locked" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] b13dd021bcf7fa6fda78864c9c95b62ba562872d163252c4a87ea02129a35896 [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" "b13dd021bcf7fa6fda78864c9c95b62ba562872d163252c4a87ea02129a35896", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "b13dd021bcf7fa6fda78864c9c95b62ba562872d163252c4a87ea02129a35896", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b13dd021bcf7fa6fda78864c9c95b62ba562872d163252c4a87ea02129a35896", kill_on_drop: false }` [INFO] [stdout] b13dd021bcf7fa6fda78864c9c95b62ba562872d163252c4a87ea02129a35896 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c44352a747e8cfe0151592f381215d1e9e3831798760589d8e8d1045e592b215 [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" "c44352a747e8cfe0151592f381215d1e9e3831798760589d8e8d1045e592b215", kill_on_drop: false }` [INFO] [stderr] Compiling humantime v1.3.0 [INFO] [stderr] Compiling solana-logger v1.5.1 [INFO] [stderr] Compiling solana-frozen-abi v1.5.1 [INFO] [stderr] Compiling env_logger v0.7.1 [INFO] [stderr] Compiling solana-program v1.5.1 [INFO] [stderr] Compiling solana-sdk v1.5.1 [INFO] [stderr] Compiling solana-metrics v1.4.20 (/opt/rustwide/workdir) [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:168:14 [INFO] [stdout] | [INFO] [stdout] 168 | .compare_and_swap(0, Self::default_log_rate(), Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(deprecated)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicU64::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:170:14 [INFO] [stdout] | [INFO] [stdout] 170 | .compare_and_swap(0, Self::default_metrics_rate(), Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:193:14 [INFO] [stdout] | [INFO] [stdout] 193 | .compare_and_swap(lastlog, counts, Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 3 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 16.33s [INFO] running `Command { std: "docker" "inspect" "c44352a747e8cfe0151592f381215d1e9e3831798760589d8e8d1045e592b215", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c44352a747e8cfe0151592f381215d1e9e3831798760589d8e8d1045e592b215", kill_on_drop: false }` [INFO] [stdout] c44352a747e8cfe0151592f381215d1e9e3831798760589d8e8d1045e592b215 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f493fc895711f43f9406f946268be2de85c64063832dfea149f22960b6d36917 [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" "f493fc895711f43f9406f946268be2de85c64063832dfea149f22960b6d36917", kill_on_drop: false }` [INFO] [stderr] Compiling serial_test_derive v0.4.0 [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:168:14 [INFO] [stdout] | [INFO] [stdout] 168 | .compare_and_swap(0, Self::default_log_rate(), Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(deprecated)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicU64::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:170:14 [INFO] [stdout] | [INFO] [stdout] 170 | .compare_and_swap(0, Self::default_metrics_rate(), Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:193:14 [INFO] [stdout] | [INFO] [stdout] 193 | .compare_and_swap(lastlog, counts, Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 3 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling serial_test v0.4.0 [INFO] [stderr] Compiling solana-metrics v1.4.20 (/opt/rustwide/workdir) [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:168:14 [INFO] [stdout] | [INFO] [stdout] 168 | .compare_and_swap(0, Self::default_log_rate(), Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(deprecated)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicU64::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:170:14 [INFO] [stdout] | [INFO] [stdout] 170 | .compare_and_swap(0, Self::default_metrics_rate(), Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stdout] --> src/counter.rs:193:14 [INFO] [stdout] | [INFO] [stdout] 193 | .compare_and_swap(lastlog, counts, Ordering::Relaxed); [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: 3 warnings emitted [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 12.57s [INFO] running `Command { std: "docker" "inspect" "f493fc895711f43f9406f946268be2de85c64063832dfea149f22960b6d36917", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f493fc895711f43f9406f946268be2de85c64063832dfea149f22960b6d36917", kill_on_drop: false }` [INFO] [stdout] f493fc895711f43f9406f946268be2de85c64063832dfea149f22960b6d36917 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "rustops/crates-build-env@sha256:b8eeb62f26c39b23691079a6cb6de528d8e88b4d71a8f6b1d30f8ba7d9c16de9" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2021-01-01" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 18e74f641346cb064a981f6c346da7aab55a4ee729b0c7275f5c13b64f480be0 [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" "18e74f641346cb064a981f6c346da7aab55a4ee729b0c7275f5c13b64f480be0", kill_on_drop: false }` [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/counter.rs:168:14 [INFO] [stderr] | [INFO] [stderr] 168 | .compare_and_swap(0, Self::default_log_rate(), Ordering::Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicU64::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/counter.rs:170:14 [INFO] [stderr] | [INFO] [stderr] 170 | .compare_and_swap(0, Self::default_metrics_rate(), Ordering::Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/counter.rs:193:14 [INFO] [stderr] | [INFO] [stderr] 193 | .compare_and_swap(lastlog, counts, Ordering::Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: 3 warnings emitted [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/counter.rs:168:14 [INFO] [stderr] | [INFO] [stderr] 168 | .compare_and_swap(0, Self::default_log_rate(), Ordering::Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(deprecated)]` on by default [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicU64::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/counter.rs:170:14 [INFO] [stderr] | [INFO] [stderr] 170 | .compare_and_swap(0, Self::default_metrics_rate(), Ordering::Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: use of deprecated associated function `std::sync::atomic::AtomicUsize::compare_and_swap`: Use `compare_exchange` or `compare_exchange_weak` instead [INFO] [stderr] --> src/counter.rs:193:14 [INFO] [stderr] | [INFO] [stderr] 193 | .compare_and_swap(lastlog, counts, Ordering::Relaxed); [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: 3 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.23s [INFO] [stderr] Running /opt/rustwide/target/debug/deps/solana_metrics-dd6439ca37039d21 [INFO] [stdout] [INFO] [stdout] running 16 tests [INFO] [stdout] test counter::tests::test_inc_new_counter ... ok [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: point 1 [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics::test] Writing 2 points (2 total) [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=0i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=1i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=2i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=3i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=4i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=5i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics::test] Writing 2 points (2 total) [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: live_submit_test true=true random_bool=false random_int=77i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=0i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=1i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=6i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=7i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=8i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=2i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=9i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=10i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=11i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=12i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=13i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=14i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=15i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=3i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=4i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=5i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=6i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=7i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=8i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=9i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=10i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=11i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=12i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=13i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=14i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=15i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=16i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=17i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=18i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=19i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=20i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=21i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=22i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=23i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=24i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=25i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=26i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=27i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=16i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=17i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=18i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=19i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=20i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=21i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=22i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=23i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=24i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=25i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=26i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=27i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=28i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=29i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=30i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=31i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=32i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=33i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: point 1 [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=28i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=29i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=30i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=34i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=35i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=36i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=37i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=38i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=39i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=40i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=41i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics::test] Writing 43 points (43 total) [INFO] [stdout] test datapoint::test::test_datapoint ... ok [INFO] [stdout] test counter::tests::test_lograte ... ok [INFO] [stdout] test counter::tests::test_metricsrate_env ... ok [INFO] [stdout] test counter::tests::test_lograte_env ... ok [INFO] [stdout] test metrics::test::test_flush_before_drop ... ok [INFO] [stdout] test metrics::test::test_live_submit ... ok [INFO] [stdout] test metrics::test::test_submit_counter_increment ... ok [INFO] [stdout] test counter::tests::test_metricsrate ... ok [INFO] [stdout] test metrics::test::test_submit ... ok [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics::test] Writing 21 points (21 total) [INFO] [stdout] test metrics::test::test_submit_counter ... ok [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] metrics disabled: SOLANA_METRICS_CONFIG: environment variable not found [INFO] [stdout] test counter::tests::test_counter ... ok [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=2i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics::test] Writing 11 points (11 total) [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=3i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=4i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=5i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=6i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=12i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=13i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=7i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=14i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=15i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=16i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=1i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=17i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=31i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=32i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=18i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=19i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=33i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=34i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=21i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=22i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=23i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=24i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=25i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=28i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=27i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=30i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=0i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=31i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=32i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=35i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=36i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=37i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=38i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=39i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=40i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=41i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=42i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=33i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=34i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=43i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=44i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=35i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=36i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=37i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=38i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=45i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=46i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=47i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=48i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=39i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=40i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=49i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=50i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=51i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=52i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=41i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=11i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=26i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=29i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=53i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=54i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=55i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=56i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=20i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=8i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=9i [INFO] [stdout] test metrics::test::test_submit_bucketed_counter ... ok [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=10i [INFO] [stdout] test metrics::test::test_multithread_submit ... ok [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics::test] Writing 43 points (43 total) [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=57i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=58i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=59i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=60i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=61i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=62i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=63i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=64i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=65i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=66i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=67i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=68i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=69i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=70i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=71i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=72i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=73i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=74i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=75i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=76i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=77i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=78i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=79i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=80i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=81i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=82i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=83i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=84i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=85i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=86i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=87i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=88i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=89i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=90i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=91i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=92i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=93i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=94i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=95i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=96i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=97i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=98i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=99i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=100i [INFO] [stderr] [2021-01-05T12:45:31Z INFO solana_metrics::metrics] datapoint: measurement i=101i [INFO] [stderr] [2021-01-05T12:45:32Z INFO solana_metrics::metrics::test] Writing 2 points (2 total) [INFO] [stderr] [2021-01-05T12:45:32Z WARN solana_metrics::metrics] max submission rate of 100 datapoints per second exceeded. only the [INFO] [stderr] first 100 of 102 points will be submitted [INFO] [stderr] [2021-01-05T12:45:32Z INFO solana_metrics::metrics::test] Writing 100 points (100 total) [INFO] [stdout] test metrics::test::test_submit_exceed_max_rate ... ok [INFO] [stdout] test metrics::test::test_submit_with_delay ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.01s [INFO] [stdout] [INFO] [stderr] Doc-tests solana_metrics [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" "18e74f641346cb064a981f6c346da7aab55a4ee729b0c7275f5c13b64f480be0", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "18e74f641346cb064a981f6c346da7aab55a4ee729b0c7275f5c13b64f480be0", kill_on_drop: false }` [INFO] [stdout] 18e74f641346cb064a981f6c346da7aab55a4ee729b0c7275f5c13b64f480be0