[INFO] cloning repository https://github.com/a-givertzman/rust-sal-sync
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/a-givertzman/rust-sal-sync" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d3deab1bfe7bfb790f311cadb825e11c79bbe2a3
[INFO] testing a-givertzman/rust-sal-sync against nightly-2025-01-18 for randomize-layout-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] validating manifest of git repo https://github.com/a-givertzman/rust-sal-sync on toolchain nightly-2025-01-18
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+nightly-2025-01-18" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking git repo https://github.com/a-givertzman/rust-sal-sync
[INFO] finished tweaking git repo https://github.com/a-givertzman/rust-sal-sync
[INFO] tweaked toml for git repo https://github.com/a-givertzman/rust-sal-sync written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] crate git repo https://github.com/a-givertzman/rust-sal-sync already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+nightly-2025-01-18" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-api-tools.git`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-debuging.git`
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-testing.git`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded lexical-util v1.0.3
[INFO] [stderr]   Downloaded concat-string v1.0.1
[INFO] [stderr]   Downloaded lexical-core v1.0.2
[INFO] [stderr]   Downloaded lexical-write-float v1.0.2
[INFO] [stderr]   Downloaded lexical-parse-integer v1.0.2
[INFO] [stderr]   Downloaded lexical-parse-float v1.0.2
[INFO] [stderr]   Downloaded lexical-write-integer v1.0.2
[INFO] [stderr]   Downloaded lexical v7.0.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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:819108c5571ea2e421544af4d832f968d213c87fbe3707a016657f43d2f853bc" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2025-01-18" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] aec62e0614c1efb4612b92115902312496187e748b4a6ef4e18fb39ddeb4f09a
[INFO] running `Command { std: "docker" "start" "-a" "aec62e0614c1efb4612b92115902312496187e748b4a6ef4e18fb39ddeb4f09a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "aec62e0614c1efb4612b92115902312496187e748b4a6ef4e18fb39ddeb4f09a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "aec62e0614c1efb4612b92115902312496187e748b4a6ef4e18fb39ddeb4f09a", kill_on_drop: false }`
[INFO] [stdout] aec62e0614c1efb4612b92115902312496187e748b4a6ef4e18fb39ddeb4f09a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:819108c5571ea2e421544af4d832f968d213c87fbe3707a016657f43d2f853bc" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2025-01-18" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a3241c75e1931505a957b5ea863a35f84ac9f0b9a2ebe0b862acf4e60cc311f1
[INFO] running `Command { std: "docker" "start" "-a" "a3241c75e1931505a957b5ea863a35f84ac9f0b9a2ebe0b862acf4e60cc311f1", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.89
[INFO] [stderr]    Compiling libc v0.2.162
[INFO] [stderr]    Compiling autocfg v1.4.0
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling memchr v2.7.4
[INFO] [stderr]    Compiling typenum v1.17.0
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling serde v1.0.215
[INFO] [stderr]    Compiling log v0.4.22
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling tinyvec_macros v0.1.1
[INFO] [stderr]    Compiling static_assertions v1.1.0
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling bytes v1.8.0
[INFO] [stderr]    Compiling ryu v1.0.18
[INFO] [stderr]    Compiling lexical-util v1.0.3
[INFO] [stderr]    Compiling tinyvec v1.8.0
[INFO] [stderr]    Compiling anstyle-parse v0.2.6
[INFO] [stderr]    Compiling cpufeatures v0.2.15
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling anstyle-query v1.1.2
[INFO] [stderr]    Compiling colorchoice v1.0.3
[INFO] [stderr]    Compiling unicode-bidi v0.3.17
[INFO] [stderr]    Compiling serde_json v1.0.132
[INFO] [stderr]    Compiling itoa v1.0.11
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling parking_lot_core v0.9.10
[INFO] [stderr]    Compiling pin-project-lite v0.2.15
[INFO] [stderr]    Compiling anstyle v1.0.10
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling unicode-properties v0.1.3
[INFO] [stderr]    Compiling iana-time-zone v0.1.61
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling slab v0.4.9
[INFO] [stderr]    Compiling lock_api v0.4.12
[INFO] [stderr]    Compiling fallible-iterator v0.2.0
[INFO] [stderr]    Compiling anstream v0.6.18
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling siphasher v0.3.11
[INFO] [stderr]    Compiling smallvec v1.13.2
[INFO] [stderr]    Compiling unicode-normalization v0.1.24
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling getrandom v0.2.15
[INFO] [stderr]    Compiling socket2 v0.5.7
[INFO] [stderr]    Compiling mio v1.0.2
[INFO] [stderr]    Compiling chrono v0.4.38
[INFO] [stderr]    Compiling quote v1.0.37
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling phf_shared v0.11.2
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling lexical-parse-integer v1.0.2
[INFO] [stderr]    Compiling parking_lot v0.12.3
[INFO] [stderr]    Compiling phf v0.11.2
[INFO] [stderr]    Compiling lexical-write-integer v1.0.2
[INFO] [stderr]    Compiling syn v2.0.87
[INFO] [stderr]    Compiling stringprep v0.1.5
[INFO] [stderr]    Compiling hashbrown v0.15.1
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling percent-encoding v2.3.1
[INFO] [stderr]    Compiling whoami v1.5.2
[INFO] [stderr]    Compiling lexical-write-float v1.0.2
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling tokio v1.41.1
[INFO] [stderr]    Compiling lexical-parse-float v1.0.2
[INFO] [stderr]    Compiling hashers v1.0.1
[INFO] [stderr]    Compiling concat-in-place v1.1.0
[INFO] [stderr]    Compiling concat-string v1.0.1
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling lexical-core v1.0.2
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling lexical v7.0.2
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling zerocopy-derive v0.7.35
[INFO] [stderr]    Compiling serde_derive v1.0.215
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling async-trait v0.1.83
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling zerocopy v0.7.35
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling env_filter v0.1.2
[INFO] [stderr]    Compiling env_logger v0.11.5
[INFO] [stderr]    Compiling debugging v0.0.1 (https://github.com/a-givertzman/rust-debuging.git?tag=0.0.1#18c3318a)
[INFO] [stderr]    Compiling ppv-lite86 v0.2.20
[INFO] [stderr]    Compiling tokio-util v0.7.12
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling postgres-protocol v0.6.7
[INFO] [stderr]    Compiling testing v0.0.2 (https://github.com/a-givertzman/rust-testing.git?tag=0.0.2#b810ec56)
[INFO] [stderr]    Compiling testing v0.1.1 (https://github.com/a-givertzman/rust-testing.git?tag=0.1.2#f18b3370)
[INFO] [stderr]    Compiling indexmap v2.6.0
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling postgres-types v0.2.8
[INFO] [stderr]    Compiling tokio-postgres v0.7.12
[INFO] [stderr]    Compiling postgres v0.19.9
[INFO] [stderr]    Compiling api-tools v0.0.6 (https://github.com/a-givertzman/rust-api-tools.git?tag=0.1.1#e7c72798)
[INFO] [stderr]    Compiling sal-sync v0.0.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 37.13s
[INFO] running `Command { std: "docker" "inspect" "a3241c75e1931505a957b5ea863a35f84ac9f0b9a2ebe0b862acf4e60cc311f1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a3241c75e1931505a957b5ea863a35f84ac9f0b9a2ebe0b862acf4e60cc311f1", kill_on_drop: false }`
[INFO] [stdout] a3241c75e1931505a957b5ea863a35f84ac9f0b9a2ebe0b862acf4e60cc311f1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:819108c5571ea2e421544af4d832f968d213c87fbe3707a016657f43d2f853bc" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2025-01-18" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] b99b05d07eea6fea74b315e488be958b6275d68caad891fe246c1bbf9f35da0e
[INFO] running `Command { std: "docker" "start" "-a" "b99b05d07eea6fea74b315e488be958b6275d68caad891fe246c1bbf9f35da0e", kill_on_drop: false }`
[INFO] [stderr]    Compiling sal-sync v0.0.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 4.49s
[INFO] running `Command { std: "docker" "inspect" "b99b05d07eea6fea74b315e488be958b6275d68caad891fe246c1bbf9f35da0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b99b05d07eea6fea74b315e488be958b6275d68caad891fe246c1bbf9f35da0e", kill_on_drop: false }`
[INFO] [stdout] b99b05d07eea6fea74b315e488be958b6275d68caad891fe246c1bbf9f35da0e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:819108c5571ea2e421544af4d832f968d213c87fbe3707a016657f43d2f853bc" "/opt/rustwide/cargo-home/bin/cargo" "+nightly-2025-01-18" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 0099b8dc21c0fc4714466afb04a9c07448481aebf04a62ca05f38be087332c37
[INFO] running `Command { std: "docker" "start" "-a" "0099b8dc21c0fc4714466afb04a9c07448481aebf04a62ca05f38be087332c37", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sal_sync-3565be1fc8234c50)
[INFO] [stdout] 
[INFO] [stdout] running 74 tests
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::as_str ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::as_type ... ok
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::contains ... ok
[INFO] [stdout] test tests::unit::services::entity::name_test::name::join ... ok
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::default ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::serialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::serialize ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::add ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::serialize ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::empty ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::to_string ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::abs ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::bitand ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new ... ok
[INFO] [stdout] test tests::unit::services::entity::name_test::name::into_string ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::bitor ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::serialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_double ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_int ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_real ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::sub ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::pow ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_string ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::mul ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::serialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_bool ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::valid ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_double ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_int ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::div ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::timestamp ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::status ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_tx_id_test::point_tx_id::test_add ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::cot ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::dest ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::name ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_real ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::tx_id ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::value ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::type_ ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_bool ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_string ... ok
[INFO] [stdout] test tests::unit::services::service::link_name_test::link_name::queue ... ok
[INFO] [stdout] test tests::unit::services::service::link_name_test::link_name::service ... ok
[INFO] [stdout] test tests::unit::services::service::link_name_test::link_name::split ... ok
[INFO] [stdout] test tests::unit::services::subscription::subscription_ctiteria_test::subscription_criteria::destination ... ok
[INFO] [stdout] test tests::unit::services::subscription::subscription_ctiteria_test::subscription_criteria::performance ... ignored, Performance test
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::basic ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_add ... ok
[INFO] [stdout] test tests::unit::services::service::service_handles_test::service_handles::into_iter ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_bit_and ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_bit_or ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_mul ... ok
[INFO] [stdout] test tests::unit::services::types::type_of_test::type_of::basic ... ok
[INFO] [stdout] test tests::unit::services::service::service_handles_test::service_handles::wait ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::future::future_test::future::then ... ok
[INFO] [stdout] test tests::unit::services::subscription::subscriptions_test::subscriptions::new ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::unsubscribe - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::extend_subscription - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::get_link - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::gi - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::subscribe - should panic ... ok
[INFO] [stdout] test tests::unit::services::subscription::conf_subscribe_test::conf_subscribe::new ... ok
[INFO] [stdout] test tests::unit::services::service::service_cycle_test::service_cycle::basic ... FAILED
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::invalid ... ok
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid ... ok
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid_options ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::unit::services::service::service_cycle_test::service_cycle::basic stdout ----
[INFO] [stdout] 
[INFO] [stdout] test ServiceCycle
[INFO] [stdout] 
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m target cycle interval: 1 ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m length: 1
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m aproxEq digits: 3
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m detecting load range...
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load range 1...2354
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m elapsed for max load: 1.27345ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1844
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00099073
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 11.67µs, elapsed 1.01167ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00103505
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 937
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00050403
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 1.83098ms, elapsed 2.83098ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.002854349
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.002854349
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 201
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00010358
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001078949
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1721
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00089819
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00363483
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00363483
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 839
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00044476
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010781
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1454
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00075755
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010778
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 111
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 5.528e-5
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107905
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 609
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00032114
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107932
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1504
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00079293
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010801
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1044
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00056318
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107892
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 248
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00012727
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001078369
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2109
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00112516
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 143.929µs, elapsed 1.143929ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00115888
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1062
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00050762
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.002912639
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.002912639
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2008
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00108073
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 99.35µs, elapsed 1.09935ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00111428
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2108
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0011347
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 152.75µs, elapsed 1.15275ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00116658
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 493
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00026828
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00361431
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00361431
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 624
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00032437
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107487
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1566
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000805
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107613
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 499
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00025393
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107657
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 837
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00043048
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00264122
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00264122
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 842
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000433251
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107376
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2085
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00111351
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 134.74µs, elapsed 1.13474ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0011509
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 545
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00028637
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00186865
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00186865
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1510
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00081524
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010846599
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.010846599
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1904
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00102115
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 41.54µs, elapsed 1.04154ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00105694
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2062
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00106777
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 84.25µs, elapsed 1.08425ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00109699
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1426
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00075122
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.012684539
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.012684539
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 440
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00022397
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00294192
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00294192
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1246
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00065143
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00595962
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00595962
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 691
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00036498
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001074389
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1703
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001004591
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 21.98µs, elapsed 1.02198ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00103654
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2184
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00108998
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 100.469µs, elapsed 1.100469ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001112431
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 313
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00015119
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.01263269
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.01263269
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1115
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000597909
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.004957419
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.004957419
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1719
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00084987
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107918
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1477
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00073105
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00784794
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00784794
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1800
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000998389
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 8.62µs, elapsed 1.00862ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001021559
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 198
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00010181
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106988
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 452
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023504
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107499
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 191
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 9.761e-5
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010786
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1365
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00073389
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.013615389
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.013615389
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1056
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00050922
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107558
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1960
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00104056
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 59.83µs, elapsed 1.05983ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107493
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 25
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 1.335e-5
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107496
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 441
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00022892
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107491
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 910
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00048387
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008533129
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.008533129
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2081
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00113501
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 150.59µs, elapsed 1.15059ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00116463
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1817
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00093382
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00391208
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00391208
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1055
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00055516
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005761579
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.005761579
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1855
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00101046
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 23.02µs, elapsed 1.02302ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00103666
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2349
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00123178
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 244.749µs, elapsed 1.244749ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00125632
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1195
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00064004
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00963466
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00963466
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1420
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000749749
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003940729
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.003940729
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1545
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00082581
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00596136
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00596136
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2247
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00121061
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 229.03µs, elapsed 1.22903ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00124611
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 309
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00017011
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107693
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1438
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00075284
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.007588929
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.007588929
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2285
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00124215
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 263.33µs, elapsed 1.26333ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00128022
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1051
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0005726
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00363882
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00363882
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 528
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00029718
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00695715
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00695715
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 456
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000249829
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003824769
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.003824769
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1601
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00086334
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106946
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 258
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00013862
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107457
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 27
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 1.433e-5
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107425
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 462
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023772
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00464925
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00464925
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1818
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00098422
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001070729
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1496
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000757751
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00386108
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00386108
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1304
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00069971
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00490806
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00490806
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 511
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00026397
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107481
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2022
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00114178
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 157.82µs, elapsed 1.15782ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00117342
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 569
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0003085
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006638339
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.006638339
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1648
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00085449
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00394195
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00394195
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1777
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00095527
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00294214
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00294214
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2073
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00106972
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 83.08µs, elapsed 1.08308ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010963
[INFO] [stdout] [90m[[0m2025-01-20T09:17:23Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1825
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00099775
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 13.68µs, elapsed 1.01368ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00102753
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1748
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0099249
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 8.947049ms, elapsed 9.947049ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00996151
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 472
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023955
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107442
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 475
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00025262
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005719659
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.005719659
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1764
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00096608
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107091
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1512
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00079957
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106881
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1414
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00074819
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00176948
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00176948
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2153
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00113063
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 141.09µs, elapsed 1.14109ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00115555
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1062
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00056109
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008786529
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.008786529
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2142
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00109986
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 116.15µs, elapsed 1.11615ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00112988
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 779
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00039533
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00880501
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00880501
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1013
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00053363
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107022
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2126
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001144329
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 160.37µs, elapsed 1.16037ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001176049
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2102
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001184069
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 198.331µs, elapsed 1.198331ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00121244
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1464
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.006810509
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.8524ms, elapsed 6.8524ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006868009
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 931
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0035293
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 2.55078ms, elapsed 3.55078ms
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00356545
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 692
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00036671
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001082619
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 271
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00013254
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00578341
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00578341
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 818
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00043105
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107043
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1611
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00078179
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107815
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1464
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00070935
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00474055
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00474055
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1183
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00063015
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006928979
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.006928979
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1621
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00086877
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106938
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 358
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0001803
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107238
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 296
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00014911
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106871
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 939
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00046305
[INFO] [stdout] [90m[[0m2025-01-20T09:17:24Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107451
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::unit::services::service::service_cycle_test::service_cycle::basic' panicked at src/tests/unit/services/service/service_cycle_test.rs:101:13:
[INFO] [stdout] to much errors (37), a few errors will be ok, but not more then 5% (20) of test cycles
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0: rust_begin_unwind
[INFO] [stdout]              at /rustc/6067b36314ab5eb2eb47cecc464545ba58e1ad24/library/std/src/panicking.rs:695:5
[INFO] [stdout]    1: core::panicking::panic_fmt
[INFO] [stdout]              at /rustc/6067b36314ab5eb2eb47cecc464545ba58e1ad24/library/core/src/panicking.rs:75:14
[INFO] [stdout]    2: sal_sync::tests::unit::services::service::service_cycle_test::service_cycle::basic
[INFO] [stdout]              at ./src/tests/unit/services/service/service_cycle_test.rs:101:13
[INFO] [stdout]    3: sal_sync::tests::unit::services::service::service_cycle_test::service_cycle::basic::{{closure}}
[INFO] [stdout]              at ./src/tests/unit/services/service/service_cycle_test.rs:27:15
[INFO] [stdout]    4: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/6067b36314ab5eb2eb47cecc464545ba58e1ad24/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    5: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/6067b36314ab5eb2eb47cecc464545ba58e1ad24/library/core/src/ops/function.rs:250:5
[INFO] [stdout] note: Some details are omitted, run with `RUST_BACKTRACE=full` for a verbose backtrace.
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::unit::services::service::service_cycle_test::service_cycle::basic
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 72 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out; finished in 1.03s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "0099b8dc21c0fc4714466afb04a9c07448481aebf04a62ca05f38be087332c37", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0099b8dc21c0fc4714466afb04a9c07448481aebf04a62ca05f38be087332c37", kill_on_drop: false }`
[INFO] [stdout] 0099b8dc21c0fc4714466afb04a9c07448481aebf04a62ca05f38be087332c37
