[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] 1e2fea3ae9de0dcd87ebdf0924cb4ce47e243b8b [INFO] testing a-givertzman/rust-sal-sync against try#9f93af291970322f4f1c6315ccde4d7078201159 for pr-146098-6 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync" "/workspace/builds/worker-6-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc2/source'... [INFO] [stderr] done. [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-6-tc2/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/a-givertzman/rust-sal-sync on toolchain 9f93af291970322f4f1c6315ccde4d7078201159 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+9f93af291970322f4f1c6315ccde4d7078201159" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [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" "+9f93af291970322f4f1c6315ccde4d7078201159" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+9f93af291970322f4f1c6315ccde4d7078201159" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] e34aeeffcabf8a1064b0a270d5738b1acb3004f54017b430f1a95176536b5d02 [INFO] running `Command { std: "docker" "start" "-a" "e34aeeffcabf8a1064b0a270d5738b1acb3004f54017b430f1a95176536b5d02", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "e34aeeffcabf8a1064b0a270d5738b1acb3004f54017b430f1a95176536b5d02", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e34aeeffcabf8a1064b0a270d5738b1acb3004f54017b430f1a95176536b5d02", kill_on_drop: false }` [INFO] [stdout] e34aeeffcabf8a1064b0a270d5738b1acb3004f54017b430f1a95176536b5d02 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+9f93af291970322f4f1c6315ccde4d7078201159" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7058c1838bba2f82f3c2e679ec6fbefd84b6d29909382ce9bd82048791b21fa3 [INFO] running `Command { std: "docker" "start" "-a" "7058c1838bba2f82f3c2e679ec6fbefd84b6d29909382ce9bd82048791b21fa3", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.173 [INFO] [stderr] Compiling memchr v2.7.5 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling syn v2.0.103 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling anstyle-parse v0.2.7 [INFO] [stderr] Compiling tinyvec v1.9.0 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling anstyle-query v1.1.3 [INFO] [stderr] Compiling anstyle v1.0.11 [INFO] [stderr] Compiling colorchoice v1.0.4 [INFO] [stderr] Compiling lexical-util v1.0.6 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling anstream v0.6.19 [INFO] [stderr] Compiling unicode-properties v0.1.3 [INFO] [stderr] Compiling jiff v0.2.15 [INFO] [stderr] Compiling sha2 v0.10.9 [INFO] [stderr] Compiling md-5 v0.10.6 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling unicode-normalization v0.1.24 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.9.1 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling stringprep v0.1.5 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling mio v1.0.4 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling iana-time-zone v0.1.63 [INFO] [stderr] Compiling siphasher v1.0.1 [INFO] [stderr] Compiling phf_shared v0.11.3 [INFO] [stderr] Compiling tokio v1.45.1 [INFO] [stderr] Compiling chrono v0.4.41 [INFO] [stderr] Compiling postgres-protocol v0.6.8 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling slab v0.4.10 [INFO] [stderr] Compiling phf v0.11.3 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling lexical-parse-integer v1.0.5 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling lexical-write-integer v1.0.5 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling hashbrown v0.15.4 [INFO] [stderr] Compiling virtue v0.0.18 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling whoami v1.6.0 [INFO] [stderr] Compiling debugging v0.0.1 (https://github.com/a-givertzman/rust-debuging.git?tag=0.0.1#18c3318a) [INFO] [stderr] Compiling lexical-write-float v1.0.5 [INFO] [stderr] Compiling lexical-parse-float v1.0.5 [INFO] [stderr] Compiling unty v0.0.4 [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 bincode_derive v2.0.1 [INFO] [stderr] Compiling sal-core-macros v0.1.0 (https://github.com/a-givertzman/rust-sal-core.git?tag=0.1.0#d1344384) [INFO] [stderr] Compiling lexical-core v1.0.5 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling scopeguard v0.3.3 [INFO] [stderr] Compiling unsafe-libyaml v0.2.11 [INFO] [stderr] Compiling dashmap v6.1.0 [INFO] [stderr] Compiling strum_macros v0.27.1 [INFO] [stderr] Compiling coco v0.3.4 [INFO] [stderr] Compiling lexical v7.0.4 [INFO] [stderr] Compiling hashers v1.0.1 [INFO] [stderr] Compiling crossbeam-skiplist v0.1.3 [INFO] [stderr] Compiling tokio-util v0.7.15 [INFO] [stderr] Compiling bincode v2.0.1 [INFO] [stderr] Compiling kanal v0.1.1 [INFO] [stderr] Compiling concat-string v1.0.1 [INFO] [stderr] Compiling strum v0.27.1 [INFO] [stderr] Compiling concat-in-place v1.1.0 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling sal-core v0.1.0 (https://github.com/a-givertzman/rust-sal-core.git?tag=0.1.0#d1344384) [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling indexmap v2.9.0 [INFO] [stderr] Compiling serde_yaml v0.9.34+deprecated [INFO] [stderr] Compiling postgres-types v0.2.9 [INFO] [stderr] Compiling tokio-postgres v0.7.13 [INFO] [stderr] Compiling postgres v0.19.10 [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.3.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: unnecessary parentheses around type [INFO] [stdout] --> src/sync/handles.rs:31:26 [INFO] [stdout] | [INFO] [stdout] 31 | let h: Stack)>> = Stack::new(); [INFO] [stdout] | ^ ^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] help: remove these parentheses [INFO] [stdout] | [INFO] [stdout] 31 - let h: Stack)>> = Stack::new(); [INFO] [stdout] 31 + let h: Stack >> = Stack::new(); [INFO] [stdout] | [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: value assigned to `filter` is never read [INFO] [stdout] --> src/services/entity/point/point_config.rs:89:21 [INFO] [stdout] | [INFO] [stdout] 89 | filter.factor = None [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = help: maybe it is overwritten before being read? [INFO] [stdout] = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 28.45s [INFO] running `Command { std: "docker" "inspect" "7058c1838bba2f82f3c2e679ec6fbefd84b6d29909382ce9bd82048791b21fa3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7058c1838bba2f82f3c2e679ec6fbefd84b6d29909382ce9bd82048791b21fa3", kill_on_drop: false }` [INFO] [stdout] 7058c1838bba2f82f3c2e679ec6fbefd84b6d29909382ce9bd82048791b21fa3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+9f93af291970322f4f1c6315ccde4d7078201159" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 350de1b18624586eef6d09fbdb6ab488cd2cb2eb3dcac765df5f8eac9915c7d3 [INFO] running `Command { std: "docker" "start" "-a" "350de1b18624586eef6d09fbdb6ab488cd2cb2eb3dcac765df5f8eac9915c7d3", kill_on_drop: false }` [INFO] [stdout] warning: unnecessary parentheses around type [INFO] [stdout] --> src/sync/handles.rs:31:26 [INFO] [stdout] | [INFO] [stdout] 31 | let h: Stack)>> = Stack::new(); [INFO] [stdout] | ^ ^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] help: remove these parentheses [INFO] [stdout] | [INFO] [stdout] 31 - let h: Stack)>> = Stack::new(); [INFO] [stdout] 31 + let h: Stack >> = Stack::new(); [INFO] [stdout] | [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: value assigned to `filter` is never read [INFO] [stdout] --> src/services/entity/point/point_config.rs:89:21 [INFO] [stdout] | [INFO] [stdout] 89 | filter.factor = None [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = help: maybe it is overwritten before being read? [INFO] [stdout] = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling sal-sync v0.3.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: unnecessary parentheses around type [INFO] [stdout] --> src/sync/handles.rs:31:26 [INFO] [stdout] | [INFO] [stdout] 31 | let h: Stack)>> = Stack::new(); [INFO] [stdout] | ^ ^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] help: remove these parentheses [INFO] [stdout] | [INFO] [stdout] 31 - let h: Stack)>> = Stack::new(); [INFO] [stdout] 31 + let h: Stack >> = Stack::new(); [INFO] [stdout] | [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: value assigned to `filter` is never read [INFO] [stdout] --> src/services/entity/point/point_config.rs:89:21 [INFO] [stdout] | [INFO] [stdout] 89 | filter.factor = None [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = help: maybe it is overwritten before being read? [INFO] [stdout] = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 5.95s [INFO] running `Command { std: "docker" "inspect" "350de1b18624586eef6d09fbdb6ab488cd2cb2eb3dcac765df5f8eac9915c7d3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "350de1b18624586eef6d09fbdb6ab488cd2cb2eb3dcac765df5f8eac9915c7d3", kill_on_drop: false }` [INFO] [stdout] 350de1b18624586eef6d09fbdb6ab488cd2cb2eb3dcac765df5f8eac9915c7d3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/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:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+9f93af291970322f4f1c6315ccde4d7078201159" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] dd519a62738fb7a8655c54211969b86daf7075e1d1ebb2347964a675a4800b6a [INFO] running `Command { std: "docker" "start" "-a" "dd519a62738fb7a8655c54211969b86daf7075e1d1ebb2347964a675a4800b6a", kill_on_drop: false }` [INFO] [stderr] warning: unnecessary parentheses around type [INFO] [stderr] --> src/sync/handles.rs:31:26 [INFO] [stderr] | [INFO] [stderr] 31 | let h: Stack)>> = Stack::new(); [INFO] [stderr] | ^ ^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] help: remove these parentheses [INFO] [stderr] | [INFO] [stderr] 31 - let h: Stack)>> = Stack::new(); [INFO] [stderr] 31 + let h: Stack >> = Stack::new(); [INFO] [stderr] | [INFO] [stderr] [INFO] [stderr] warning: value assigned to `filter` is never read [INFO] [stderr] --> src/services/entity/point/point_config.rs:89:21 [INFO] [stderr] | [INFO] [stderr] 89 | filter.factor = None [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = help: maybe it is overwritten before being read? [INFO] [stderr] = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: `sal-sync` (lib) generated 2 warnings (run `cargo fix --lib -p sal-sync` to apply 1 suggestion) [INFO] [stderr] warning: `sal-sync` (lib test) generated 2 warnings (2 duplicates) [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-74eb9379a9830378) [INFO] [stdout] [INFO] [stdout] running 107 tests [INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_start_step_back ... ok [INFO] [stdout] test tests::unit::math::aprox_eq_test::tests::test_f32 ... ok [INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_stot_step_back ... ok [INFO] [stdout] test tests::unit::math::aprox_eq_test::tests::test_f64 ... ok [INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_single ... ok [INFO] [stdout] test tests::unit::kernel::state::switch_state_changed_test::tests::test_state_empty_steps ... ok [INFO] [stdout] test tests::unit::kernel::state::change_notify_test::change_notify::add ... ok [INFO] [stdout] test tests::unit::kernel::state::switch_state_changed_test::tests::test_state ... ok [INFO] [stdout] test tests::unit::services::conf::conf_tree_keys_test::conf_tree::keys ... ok [INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::as_type ... ok [INFO] [stdout] test tests::unit::services::conf::diag_keywd_test::diag_keywd::from_str ... ok [INFO] [stdout] test tests::unit::services::conf::conf_tree_get_test::config_tree_get::valid ... ok [INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::valid ... ok [INFO] [stdout] test tests::unit::services::conf::diag_keywd_test::diag_keywd::variants ... ok [INFO] [stdout] test tests::unit::services::entity::cot_test::cot::contains ... ok [INFO] [stdout] test tests::unit::services::entity::cot_test::cot::as_str ... ok [INFO] [stdout] test tests::unit::services::entity::cot_test::cot::default ... ok [INFO] [stdout] test tests::unit::services::entity::name_test::name::into_string ... 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::name_test::name::join ... 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_address_test::point_config_address::deserialize_yaml ... 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_test::point_config::deserialize_json ... 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_config_test::point_config::serialize ... 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_config_type_test::point_config_type::deserialize_yaml ... 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_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::add ... ok [INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::deserialize_yaml ... 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_hlr_test::point_hlr::div ... ok [INFO] [stdout] test tests::unit::services::conf::conf_duration_test::test_create_invalid ... ok [INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new ... 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::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::mul ... 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_real ... 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::sub ... ok [INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_bool ... 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::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_test::point::cot ... 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_hlr_test::point_hlr::to_string ... 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::status ... ok [INFO] [stdout] test tests::unit::services::entity::point::point_test::point::name ... 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::type_ ... ok [INFO] [stdout] test tests::unit::services::entity::point::point_test::point::value ... 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::serialize_json ... ok [INFO] [stdout] test tests::unit::services::entity::point::point_test::point::timestamp ... ok [INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_performance_test::multi_queue::match_performance ... ignored, Performance test [INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_performance_test::multi_queue::performance ... ignored, MultiQueue Performance test [INFO] [stdout] test tests::unit::services::conf::conf_duration_test::test_create_valid ... ok [INFO] [stdout] test tests::unit::services::conf::conf_keyword_test::conf_keywd::from_str ... ok [INFO] [stdout] test tests::unit::services::future::future_test::future::then ... ok [INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_read_test::multi_queue::static_read ... ok [INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_test::multi_queue::run_scheduler ... ok [INFO] [stdout] test tests::unit::services::retain::retain_point_id::map_update_or_insert_test::map_update_or_insert::test ... 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::multi_queue::multi_queue_read_write_test::multi_queue::read_write ... ok [INFO] [stdout] test tests::unit::services::service::service_test::trait_service::basic ... 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::service::service_test::trait_service::unsubscribe - should panic ... ok [INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_test::multi_queue::run_thread ... ok [INFO] [stdout] test tests::unit::services::service::service_cycle_test::service_cycle::basic ... FAILED [INFO] [stdout] test tests::unit::services::future::future_spawn_test::future::spawn ... 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::subscription::subscriptions_test::subscriptions::new ... ok [INFO] [stdout] test tests::unit::services::conf::fn_config::fn_config_read_test::tests::test_fn_config_read_valid ... ok [INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast ... ok [INFO] [stdout] test tests::unit::services::subscription::conf_subscribe_test::conf_subscribe::new ... ok [INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_add ... 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::conf::fn_config::fn_config_point_test::tests::test_fn_config_point ... 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::invalid ... ok [INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid_options ... ok [INFO] [stdout] test tests::unit::services::conf::fn_config::fn_config_new_test::tests::test_fn_config_new_valid ... ok [INFO] [stdout] test tests::unit::thread_pool::scheduler_test::scheduler::single_capacity ... ok [INFO] [stdout] test tests::unit::thread_pool::thread_pool_test::thread_pool::spawn ... ok [INFO] [stdout] test tests::unit::thread_pool::thread_pool_test::thread_pool::single_capacity ... ok [INFO] [stdout] test tests::unit::thread_pool::scheduler_test::scheduler::spawn ... ok [INFO] [stdout] test tests::unit::services::services::services_test::services::services_scheduler ... ok [INFO] [stdout] test tests::unit::services::services::services_test::services::services_thread ... ok [INFO] [stdout] test tests::unit::sync::handles_test::handles::wait ... ok [INFO] [stdout] test tests::unit::sync::kanal_channel_test::kanal_channel::iter ... 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] [2025-11-08T16:33:06Z INFO  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] target cycle interval: 1 ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] length: 1 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] aproxEq digits: 3 [INFO] [stdout] [2025-11-08T16:33:06Z INFO  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] detecting load range... [INFO] [stdout] [2025-11-08T16:33:06Z INFO  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load range 1...2437 [INFO] [stdout] [2025-11-08T16:33:06Z INFO  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] elapsed for max load: 1.32696ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 981 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00255811 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 1.58161ms, elapsed 2.58161ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00259619 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 601 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00029481 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107047 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 494 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00024034 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107015 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1546 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00142633 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 447.27µs, elapsed 1.44727ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00146522 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2194 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.006462199 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.638469ms, elapsed 6.638469ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.006657859 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): mathElapsed: 0.006462199 != cycleElapsed 0.006657859 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1254 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000636549 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.003799899 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.003799899 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1892 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00252706 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 1.54936ms, elapsed 2.54936ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00256289 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1957 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.01039764 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 9.42862ms, elapsed 10.42862ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01044965 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2078 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00105297 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 71.57µs, elapsed 1.07157ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00108362 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 384 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00017929 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00738104 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00738104 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 235 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00012642 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106867 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2303 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0011248 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 145.58µs, elapsed 1.14558ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00115895 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1117 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00054151 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00883235 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00883235 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1905 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00696705 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.981709ms, elapsed 6.981709ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00699512 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 539 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000258721 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.002790861 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.002790861 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2259 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.001265589 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 282.26µs, elapsed 1.28226ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.001295239 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1874 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.01806974 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 17.09905ms, elapsed 18.09905ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01811874 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1049 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00057709 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00350418 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00350418 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1820 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00099842 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 10.74µs, elapsed 1.01074ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00102302 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 134 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 7.221e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01091828 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.01091828 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1579 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000855871 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00395146 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00395146 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 805 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00046696 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00108754 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 918 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000509989 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.001106069 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 990 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0005173 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00366925 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00366925 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2136 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00110187 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 115.82µs, elapsed 1.11582ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00112832 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2152 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.01421617 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 13.24133ms, elapsed 14.24133ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01425407 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 231 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00011537 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106718 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1392 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00395363 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 2.97543ms, elapsed 3.97543ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00398766 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1639 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00087887 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00639465 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00639465 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 89 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 5.755e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00108007 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 301 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00016019 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00291902 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00291902 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1672 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00090056 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.005957719 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.005957719 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1893 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0010437 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 63.54µs, elapsed 1.06354ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107571 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 50 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 2.549e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00983042 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00983042 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2379 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00630095 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.3244ms, elapsed 6.3244ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00633817 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 372 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00019983 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00461211 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00461211 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2055 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00112986 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 151.66µs, elapsed 1.15166ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0011649 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1941 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.01253574 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 11.55875ms, elapsed 12.55875ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01257144 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2043 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.001127529 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 147.56µs, elapsed 1.14756ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.001159369 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 790 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00042317 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00315292 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00315292 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1076 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00053059 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00460843 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00460843 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 899 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00045915 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00410067 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00410067 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 264 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0001468 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00113595 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1435 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00069486 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00578632 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00578632 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1678 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00082519 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107089 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 666 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00032932 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00285311 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00285311 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1075 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00052639 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106934 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1281 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00062558 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.006849009 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.006849009 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 641 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000305249 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.001070229 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2409 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.001182811 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 205.071µs, elapsed 1.205071ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0012197 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1980 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.005091679 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 4.115009ms, elapsed 5.115009ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.005132429 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 866 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00041979 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00193811 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00193811 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 806 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00039714 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.003473 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.003473 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2381 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0032085 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 2.23188ms, elapsed 3.23188ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00324725 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1356 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00069607 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00478921 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00478921 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 936 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00045095 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107013 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 517 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00027569 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00675261 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00675261 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 494 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00024005 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107147 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1917 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00098318 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 3.04µs, elapsed 1.00304ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00101669 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 122 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 6.131e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106878 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1920 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.005968759 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 4.99947ms, elapsed 5.99947ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.006014439 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1445 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000736171 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00890179 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00890179 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1819 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00453381 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 3.55322ms, elapsed 4.55322ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00456669 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 180 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00010298 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107903 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 970 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00047691 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00696423 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00696423 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1381 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00069138 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106813 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1885 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00096219 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00632833 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00632833 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1197 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00060807 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01047812 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.01047812 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2358 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00117116 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 193.75µs, elapsed 1.19375ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00120902 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1532 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.013783929 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 12.835499ms, elapsed 13.835499ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.013854539 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 770 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00038893 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.036887939 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.036887939 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1883 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00839106 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.415ms, elapsed 8.415ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00843038 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2344 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00112412 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 143.92µs, elapsed 1.14392ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0011553 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1189 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.028598859 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 27.651879ms, elapsed 28.651879ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.028670879 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1889 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00404068 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 3.06613ms, elapsed 4.06613ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00408103 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2213 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.01113489 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 10.18082ms, elapsed 11.18082ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01119579 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 148 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 7.601e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0092552 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.0092552 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1936 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00599193 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.02649ms, elapsed 6.02649ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00604239 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 55 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 2.759e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00287465 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00287465 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 349 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00017373 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00393464 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00393464 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 223 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.000111871 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106774 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1429 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0007123 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.007857959 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.007857959 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 909 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00046175 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00293841 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00293841 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2035 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00102453 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 40.44µs, elapsed 1.04044ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00105353 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1008 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00049486 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00989354 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00989354 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 519 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00025209 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00792897 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00792897 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1432 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00068957 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00293347 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00293347 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1947 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00102683 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 45.25µs, elapsed 1.04525ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0010603 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 678 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00033652 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.015882259 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.015882259 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1724 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.0008612 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00106948 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 935 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00046759 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00107048 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 110 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 5.48e-5 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0066004 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.0066004 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2283 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00111849 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 139.07µs, elapsed 1.13907ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00115506 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1980 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00098301 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.32µs, elapsed 1.00532ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00101927 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2313 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.024206709 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 23.232109ms, elapsed 24.232109ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.024247809 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1347 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00776509 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 6.80892ms, elapsed 7.80892ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.00782459 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1638 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00085945 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01466954 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.01466954 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 2062 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.023151879 [INFO] [stdout] [2025-11-08T16:33:06Z ERROR sal_sync::services::service::service_cycle] service_cycle_test/ServiceCycle.wait | exceeded 1ms by 22.191109ms, elapsed 23.191109ms [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.023205729 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1410 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00080835 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.0052418 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.0052418 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] load: 1696 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] math done in: 0.00086799 [INFO] [stdout] [2025-11-08T16:33:06Z DEBUG sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] cycle done in: 0.01357364 [INFO] [stdout] [2025-11-08T16:33:06Z WARN  sal_sync::tests::unit::services::service::service_cycle_test::service_cycle] values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.01357364 [INFO] [stdout] [INFO] [stdout] thread 'tests::unit::services::service::service_cycle_test::service_cycle::basic' (292) panicked at src/tests/unit/services/service/service_cycle_test.rs:101:13: [INFO] [stdout] to much errors (42), a few errors will be ok, but not more then 5% (20) of test cycles [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: __rustc::rust_begin_unwind [INFO] [stdout] at /rustc/9f93af291970322f4f1c6315ccde4d7078201159/library/std/src/panicking.rs:698:5 [INFO] [stdout] 1: core::panicking::panic_fmt [INFO] [stdout] at /rustc/9f93af291970322f4f1c6315ccde4d7078201159/library/core/src/panicking.rs:80: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/9f93af291970322f4f1c6315ccde4d7078201159/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 5: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/9f93af291970322f4f1c6315ccde4d7078201159/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. 103 passed; 1 failed; 3 ignored; 0 measured; 0 filtered out; finished in 4.66s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "dd519a62738fb7a8655c54211969b86daf7075e1d1ebb2347964a675a4800b6a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "dd519a62738fb7a8655c54211969b86daf7075e1d1ebb2347964a675a4800b6a", kill_on_drop: false }` [INFO] [stdout] dd519a62738fb7a8655c54211969b86daf7075e1d1ebb2347964a675a4800b6a