[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 1.93.0 for beta-1.94-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/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-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/a-givertzman/rust-sal-sync on toolchain 1.93.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.93.0" "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" "+1.93.0" "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-sal-core.git`
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-testing.git`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded lexical v7.0.4
[INFO] [stderr]   Downloaded bincode_derive v2.0.1
[INFO] [stderr]   Downloaded unty v0.0.4
[INFO] [stderr]   Downloaded virtue v0.0.18
[INFO] [stderr]   Downloaded concat-string v1.0.1
[INFO] [stderr]   Downloaded phf_shared v0.11.3
[INFO] [stderr]   Downloaded concat-in-place v1.1.0
[INFO] [stderr]   Downloaded postgres-types v0.2.9
[INFO] [stderr]   Downloaded crossbeam-skiplist v0.1.3
[INFO] [stderr]   Downloaded phf v0.11.3
[INFO] [stderr]   Downloaded postgres v0.19.10
[INFO] [stderr]   Downloaded postgres-protocol v0.6.8
[INFO] [stderr]   Downloaded coco v0.3.4
[INFO] [stderr]   Downloaded tokio-postgres v0.7.13
[INFO] [stderr]   Downloaded kanal v0.1.1
[INFO] [stderr]   Downloaded bincode v2.0.1
[INFO] [stderr]   Downloaded jiff-static v0.2.15
[INFO] [stderr]   Downloaded syn v2.0.103
[INFO] [stderr]   Downloaded hashers v1.0.1
[INFO] [stderr]   Downloaded jiff v0.2.15
[INFO] [stderr]   Downloaded libc v0.2.173
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 26c6c65a6666d622bef7e44c64a903ee102b973e99ca922dcbc5eb9768beae59
[INFO] running `Command { std: "docker" "start" "-a" "26c6c65a6666d622bef7e44c64a903ee102b973e99ca922dcbc5eb9768beae59", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "26c6c65a6666d622bef7e44c64a903ee102b973e99ca922dcbc5eb9768beae59", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "26c6c65a6666d622bef7e44c64a903ee102b973e99ca922dcbc5eb9768beae59", kill_on_drop: false }`
[INFO] [stdout] 26c6c65a6666d622bef7e44c64a903ee102b973e99ca922dcbc5eb9768beae59
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2ee0a52162935e6765c76efc365af21a9b32547fbbb6f09e9b900eb724703b29
[INFO] running `Command { std: "docker" "start" "-a" "2ee0a52162935e6765c76efc365af21a9b32547fbbb6f09e9b900eb724703b29", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.173
[INFO] [stderr]    Compiling memchr v2.7.5
[INFO] [stderr]    Compiling typenum v1.18.0
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling log v0.4.27
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling lock_api v0.4.13
[INFO] [stderr]    Compiling tinyvec v1.9.0
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling syn v2.0.103
[INFO] [stderr]    Compiling anstream v0.6.19
[INFO] [stderr]    Compiling lexical-util v1.0.6
[INFO] [stderr]    Compiling cpufeatures v0.2.17
[INFO] [stderr]    Compiling jiff v0.2.15
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling unicode-properties v0.1.3
[INFO] [stderr]    Compiling ryu v1.0.20
[INFO] [stderr]    Compiling itoa v1.0.15
[INFO] [stderr]    Compiling unicode-bidi v0.3.18
[INFO] [stderr]    Compiling fallible-iterator v0.2.0
[INFO] [stderr]    Compiling iana-time-zone v0.1.63
[INFO] [stderr]    Compiling siphasher v1.0.1
[INFO] [stderr]    Compiling slab v0.4.10
[INFO] [stderr]    Compiling rustversion v1.0.21
[INFO] [stderr]    Compiling lexical-parse-integer v1.0.5
[INFO] [stderr]    Compiling lexical-write-integer v1.0.5
[INFO] [stderr]    Compiling phf_shared v0.11.3
[INFO] [stderr]    Compiling unicode-normalization v0.1.24
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling whoami v1.6.0
[INFO] [stderr]    Compiling phf v0.11.3
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling virtue v0.0.18
[INFO] [stderr]    Compiling hashbrown v0.15.4
[INFO] [stderr]    Compiling lexical-write-float v1.0.5
[INFO] [stderr]    Compiling lexical-parse-float v1.0.5
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling mio v1.0.4
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling unty v0.0.4
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling stringprep v0.1.5
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling rand v0.9.1
[INFO] [stderr]    Compiling tokio v1.45.1
[INFO] [stderr]    Compiling lexical-core v1.0.5
[INFO] [stderr]    Compiling bincode_derive v2.0.1
[INFO] [stderr]    Compiling scopeguard v0.3.3
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling coco v0.3.4
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling lexical v7.0.4
[INFO] [stderr]    Compiling crossbeam-skiplist v0.1.3
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling hashers v1.0.1
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling kanal v0.1.1
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling concat-string v1.0.1
[INFO] [stderr]    Compiling concat-in-place v1.1.0
[INFO] [stderr]    Compiling strum v0.27.1
[INFO] [stderr]    Compiling bincode v2.0.1
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling postgres-protocol v0.6.8
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling env_filter v0.1.3
[INFO] [stderr]    Compiling env_logger v0.11.8
[INFO] [stderr]    Compiling debugging v0.0.1 (https://github.com/a-givertzman/rust-debuging.git?tag=0.0.1#18c3318a)
[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 serde_derive v1.0.219
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling async-trait v0.1.88
[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 strum_macros v0.27.1
[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 futures-util v0.3.31
[INFO] [stderr]    Compiling tokio-util v0.7.15
[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<Box<(dyn WaitBox<T>)>> = 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<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = 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 45.27s
[INFO] running `Command { std: "docker" "inspect" "2ee0a52162935e6765c76efc365af21a9b32547fbbb6f09e9b900eb724703b29", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2ee0a52162935e6765c76efc365af21a9b32547fbbb6f09e9b900eb724703b29", kill_on_drop: false }`
[INFO] [stdout] 2ee0a52162935e6765c76efc365af21a9b32547fbbb6f09e9b900eb724703b29
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f2894b54622be2863c8342c36eadcfb54525833dc34771fac9d766e40acc7607
[INFO] running `Command { std: "docker" "start" "-a" "f2894b54622be2863c8342c36eadcfb54525833dc34771fac9d766e40acc7607", kill_on_drop: false }`
[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<Box<(dyn WaitBox<T>)>> = 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<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = 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] [stdout] warning: unnecessary parentheses around type
[INFO] [stdout]   --> src/sync/handles.rs:31:26
[INFO] [stdout]    |
[INFO] [stdout] 31 |         let h: Stack<Box<(dyn WaitBox<T>)>> = 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<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = 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 8.93s
[INFO] running `Command { std: "docker" "inspect" "f2894b54622be2863c8342c36eadcfb54525833dc34771fac9d766e40acc7607", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f2894b54622be2863c8342c36eadcfb54525833dc34771fac9d766e40acc7607", kill_on_drop: false }`
[INFO] [stdout] f2894b54622be2863c8342c36eadcfb54525833dc34771fac9d766e40acc7607
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] b2026e97607497e337ee455d04747fcd15de8362650a7192418d7c0822f559b8
[INFO] running `Command { std: "docker" "start" "-a" "b2026e97607497e337ee455d04747fcd15de8362650a7192418d7c0822f559b8", 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<Box<(dyn WaitBox<T>)>> = 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<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stderr] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = 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.18s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sal_sync-c1122ee37913e305)
[INFO] [stdout] 
[INFO] [stdout] running 107 tests
[INFO] [stdout] test tests::unit::math::aprox_eq_test::tests::test_f32 ... ok
[INFO] [stdout] test tests::unit::kernel::state::change_notify_test::change_notify::add ... 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_changed_test::tests::test_state_empty_steps ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_changed_test::tests::test_state ... 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_test::config_tree::as_type ... ok
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::as_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::diag_keywd_test::diag_keywd::variants ... 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::empty ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_start_step_back ... 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_yaml ... 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::conf::conf_tree_keys_test::conf_tree::keys ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::valid ... 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::deserialize_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_hlr_test::point_hlr::add ... 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_hlr_test::point_hlr::abs ... 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_hlr_test::point_hlr::bitor ... 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::to_string ... 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_config_address_test::point_config_address::serialize_json ... 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_hlr_test::point_hlr::new ... 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_string ... 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::to_int ... 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_test::point::deserialize_json ... 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::to_real ... 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::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_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_test::point::status ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::tx_id ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_single ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::bitand ... 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::entity::point::point_test::point::value ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::timestamp ... 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::serialize_json ... 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::retain::retain_point_id::map_update_or_insert_test::map_update_or_insert::test ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::type_ ... 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::service::service_test::trait_service::basic ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_duration_test::test_create_valid ... ok
[INFO] [stdout] test tests::unit::services::future::future_test::future::then ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_keyword_test::conf_keywd::from_str ... 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_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::subscription_ctiteria_test::subscription_criteria::performance ... ignored, Performance test
[INFO] [stdout] test tests::unit::services::subscription::subscription_ctiteria_test::subscription_criteria::destination ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::unsubscribe - should panic ... ok
[INFO] [stdout] test tests::unit::services::subscription::subscriptions_test::subscriptions::new ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast ... FAILED
[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::multi_queue::multi_queue_read_write_test::multi_queue::read_write ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_test::multi_queue::run_scheduler ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_read_test::multi_queue::static_read ... ok
[INFO] [stdout] test tests::unit::services::future::future_spawn_test::future::spawn ... ok
[INFO] [stdout] test tests::unit::services::service::service_cycle_test::service_cycle::basic ... FAILED
[INFO] [stdout] test tests::unit::services::conf::fn_config::fn_config_point_test::tests::test_fn_config_point ... ok
[INFO] [stdout] test tests::unit::thread_pool::thread_pool_test::thread_pool::spawn ... ok
[INFO] [stdout] test tests::unit::thread_pool::scheduler_test::scheduler::single_capacity ... ok
[INFO] [stdout] test tests::unit::services::subscription::conf_subscribe_test::conf_subscribe::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::conf::conf_duration_test::test_create_invalid ... 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::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid_options ... 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::conf::fn_config::fn_config_new_test::tests::test_fn_config_new_valid ... 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] test tests::unit::services::services::services_test::services::services_thread ... ok
[INFO] [stdout] test tests::unit::services::services::services_test::services::services_scheduler ... ok
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast stdout ----
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] multi_queue_subscribe
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue_conf[90m][0m MultiQueueConf(/multi_queue_subscribe/MultiQueue).new | self_name: /multi_queue_subscribe/MultiQueue
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue_conf[90m][0m MultiQueueConf(/multi_queue_subscribe/MultiQueue).new | 'in queue': in-queue,	max-length: 10000
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [33mWARN [0m sal_sync::services::multi_queue::multi_queue_conf[90m][0m MultiQueueConf(/multi_queue_subscribe/MultiQueue).new | Error: ConfTree.get_send_to_many | Parameter 'send-to' - is empty
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue_conf[90m][0m MultiQueueConf(/multi_queue_subscribe/MultiQueue).new | 'send-to': []
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue[90m][0m mqConf: MultiQueueConf { name: /multi_queue_subscribe/MultiQueue, rx: "in-queue", rx_max_length: 10000, send_to: [] }
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(0).new | Created, capacity: 8, size: 1, free: 0
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(1).new | Created, capacity: 8, size: 2, free: 0
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::conf::services_conf[90m][0m multi_queue_subscribe/ServicesConf.new | name: /multi_queue_subscribe/ServicesConf
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::services::conf::services_conf[90m][0m multi_queue_subscribe/ServicesConf.new | 'retain' parse error: ConfTree.parse | key 'retain' - not found in: Null
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::conf::services_conf[90m][0m multi_queue_subscribe/ServicesConf.new | Default retain: RetainConf { path: Some("assets/retain/"), point: Some(RetainPointConf { path: "id.json", api: None }) }
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.run | Starting...
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.run | Started
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(1).extend | Trying to creating 4 new workers...
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(2).new | Created, capacity: 8, size: 3, free: 1
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver0.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver1.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(3).new | Created, capacity: 8, size: 4, free: 1
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver2.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver0 (4106007254444518374)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(4).new | Created, capacity: 8, size: 5, free: 1
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver3.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver1 (8426537444312689264)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver4.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(5).new | Created, capacity: 8, size: 6, free: 1
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver5.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::thread_pool::worker[90m][0m multi_queue_subscribe/ThreadPool/Worker(1).new | Executing job...
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.run | Preparing thread - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.run | Subscriptions changes detected
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver6.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver7.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver8.run | Starting - ok
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [32mINFO [0m sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test[90m][0m multi_queue_subscribe/MockReceiver9.run | Starting - ok
[INFO] [stdout] All MockReceiver's threads - started
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver4 (3576072823899334977)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver5 (3611043952299347499)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver6 (5707646649213355019)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver7 (10019781413165730448)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver8 (13886690573072777393)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver9 (13695296039565978223)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver2 (13123390247793364406)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.subscribe | "Broadcast registered, receiver: \n\tmulti_queue_subscribe/MockReceiver3 (893894943019547048)"
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::multi_queue::multi_queue[90m][0m multi_queue_subscribe/MultiQueue.run | Subscriptions changes detected
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast' (165) panicked at src/services/services.rs:197:13:
[INFO] [stdout] multi_queue_subscribe/Services.insert | Duplicated service name '"/multi_queue_subscribe/MockSendService1"'
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0: __rustc::rust_begin_unwind
[INFO] [stdout]              at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:689:5
[INFO] [stdout]    1: core::panicking::panic_fmt
[INFO] [stdout]              at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panicking.rs:80:14
[INFO] [stdout]    2: sal_sync::services::services::Services::insert
[INFO] [stdout]              at ./src/services/services.rs:197:13
[INFO] [stdout]    3: sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast
[INFO] [stdout]              at ./src/tests/unit/services/multi_queue/multi_queue_subscribe_test.rs:114:22
[INFO] [stdout]    4: sal_sync::tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast::{{closure}}
[INFO] [stdout]              at ./src/tests/unit/services/multi_queue/multi_queue_subscribe_test.rs:42:29
[INFO] [stdout]    5: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    6: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/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] ---- tests::unit::services::service::service_cycle_test::service_cycle::basic stdout ----
[INFO] [stdout] 
[INFO] [stdout] test ServiceCycle
[INFO] [stdout] 
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m target cycle interval: 1 ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m length: 1
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m aproxEq digits: 3
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m detecting load range...
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load range 1...2524
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m elapsed for max load: 1.22172ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 946
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00046247
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001080369
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 466
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000244689
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001091499
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1617
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00129841
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 326.94µs, elapsed 1.32694ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00134545
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1532
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00087502
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107813
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1302
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00063153
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010777
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1122
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00058341
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107965
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1069
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000535409
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.002081369
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [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.002081369
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2008
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00106664
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 86.67µs, elapsed 1.08667ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010996
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1907
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001004159
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 20.729µs, elapsed 1.020729ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001033459
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 812
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00042726
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00377214
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [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.00377214
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1427
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00077409
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.025952285
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [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.025952285
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2185
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00123113
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 258.369µs, elapsed 1.258369ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001275419
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1543
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.008866169
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.893009ms, elapsed 8.893009ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008908039
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1172
[INFO] [stdout] [90m[[0m2026-01-27T04:03:07Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0006682
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 14.741067ms, elapsed 15.741067ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015764627
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.015764627
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1756
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00088457
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.004917069
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.004917069
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1604
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00082874
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003950669
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.003950669
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 354
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00018395
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005947149
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.005947149
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2110
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00108507
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 95.39µs, elapsed 1.09539ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00110706
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2470
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.042223952
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 41.288842ms, elapsed 42.288842ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.042305102
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1419
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000721199
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003588599
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.003588599
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 183
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 8.638e-5
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016863437
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.016863437
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2417
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001281519
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 302.039µs, elapsed 1.302039ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00131667
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2300
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.008302178
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.330868ms, elapsed 8.330868ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008348198
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1094
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00063046
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.032243874
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.032243874
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1489
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000822299
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001902659
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.001902659
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 461
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00025474
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107583
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2271
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00124772
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 272.75µs, elapsed 1.27275ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00129801
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1768
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.012962417
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 12.022537ms, elapsed 13.022537ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.013042407
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1864
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00102587
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 50.52µs, elapsed 1.05052ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106383
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 220
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00012317
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016377287
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.016377287
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 196
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00010878
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005909559
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.005909559
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2258
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001189409
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 213.299µs, elapsed 1.213299ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00122982
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 308
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000154211
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016651977
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.016651977
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2481
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00123386
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 261.08µs, elapsed 1.26108ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00127877
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 208
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00010512
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011600588
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.011600588
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 185
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00010134
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005892019
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.005892019
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 356
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0002117
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107664
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2039
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001151539
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 173.49µs, elapsed 1.17349ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001193389
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2223
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00127453
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 291.75µs, elapsed 1.29175ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00130548
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1653
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.015889657
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 14.930677ms, elapsed 15.930677ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015949427
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1676
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.034928053
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 33.989593ms, elapsed 34.989593ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.035009663
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1240
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00064723
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.013193618
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.013193618
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 586
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000316459
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010246948
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.010246948
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 653
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00036968
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006618488
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.006618488
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1926
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.014568198
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 13.627308ms, elapsed 14.627308ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.014647718
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1482
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00079264
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.020228646
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.020228646
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1313
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000650509
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.007098668
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.007098668
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 246
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00012281
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00196208
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.00196208
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 942
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00044868
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.009699068
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.009699068
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1058
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00055277
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005916859
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.005916859
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2134
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00107475
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 110.61µs, elapsed 1.11061ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00112887
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2029
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.025056385
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 24.114735ms, elapsed 25.114735ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.025141715
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 187
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 8.984e-5
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010314268
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.010314268
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 482
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00026935
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00110754
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1177
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000612289
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016070777
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.016070777
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2002
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00111135
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 126.579µs, elapsed 1.126579ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00114363
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 844
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00042197
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.025720786
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.025720786
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 297
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000158969
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015895866
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.015895866
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1954
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00098749
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 4.42µs, elapsed 1.00442ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00101996
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1779
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00084096
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.017878227
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.017878227
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 298
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00015213
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.007944618
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.007944618
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1519
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00084845
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.019935876
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.019935876
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1663
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00085434
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015937607
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.015937607
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 634
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00032457
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.0010751
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 298
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0001506
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011791368
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.011791368
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 200
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0001023
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006906988
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.006906988
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2138
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.019128597
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 18.161417ms, elapsed 19.161417ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.019179087
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 827
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00046234
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.021745286
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.021745286
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1370
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00067426
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.024965716
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.024965716
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2214
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.042253192
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 41.279502ms, elapsed 42.279502ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.042293862
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1137
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00057696
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.019977076
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.019977076
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2111
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.038137282
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 37.163533ms, elapsed 38.163533ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.038178082
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2475
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00119152
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 211.75µs, elapsed 1.21175ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00122558
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 886
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.067536847
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 66.590067ms, elapsed 67.590067ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.067606877
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2087
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.005387659
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 47.484181ms, elapsed 48.484181ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.048517741
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): mathElapsed: 0.005387659 != cycleElapsed 0.048517741
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1482
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00147949
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 504.83µs, elapsed 1.50483ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00151891
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): mathElapsed: 0.00147949 != cycleElapsed 0.00151891
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 490
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023775
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.009377028
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.009377028
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1360
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00069696
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.017945416
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.017945416
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1837
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.005995719
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.022269ms, elapsed 6.022269ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006036709
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 317
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00016092
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006096959
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.006096959
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 767
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00037868
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010692178
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.010692178
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1354
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00067135
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.026334265
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.026334265
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1672
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00088952
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.002519839
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.002519839
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 221
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000100249
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.021952335
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.021952335
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 142
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 7.831e-5
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.017187897
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.017187897
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1114
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00062536
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107305
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2404
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.040777252
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 39.836872ms, elapsed 40.836872ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.040855932
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1741
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00092836
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.017340717
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [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.017340717
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1269
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00069223
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107921
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1378
[INFO] [stdout] [90m[[0m2026-01-27T04:03:08Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00073469
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.041107232
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.041107232
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1586
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00079828
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005950089
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.005950089
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 724
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00039575
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107297
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 306
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0001697
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005716389
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.005716389
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 356
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0002005
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005991039
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.005991039
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1781
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.011974708
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 11.007847ms, elapsed 12.007847ms
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.012025848
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1526
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00082958
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00589161
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.00589161
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 512
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000312899
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003932979
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.003932979
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1483
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000798969
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011925377
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.011925377
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1423
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00076076
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.023914046
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.023914046
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1736
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00096247
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.004938909
[INFO] [stdout] [90m[[0m2026-01-27T04:03:09Z [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.004938909
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::unit::services::service::service_cycle_test::service_cycle::basic' (186) panicked at src/tests/unit/services/service/service_cycle_test.rs:101:13:
[INFO] [stdout] to much errors (57), 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/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:689:5
[INFO] [stdout]    1: core::panicking::panic_fmt
[INFO] [stdout]              at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/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/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    5: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/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::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast
[INFO] [stdout]     tests::unit::services::service::service_cycle_test::service_cycle::basic
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 102 passed; 2 failed; 3 ignored; 0 measured; 0 filtered out; finished in 4.88s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "b2026e97607497e337ee455d04747fcd15de8362650a7192418d7c0822f559b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b2026e97607497e337ee455d04747fcd15de8362650a7192418d7c0822f559b8", kill_on_drop: false }`
[INFO] [stdout] b2026e97607497e337ee455d04747fcd15de8362650a7192418d7c0822f559b8
