[INFO] fetching crate kaspa-notify 0.0.4...
[INFO] testing kaspa-notify-0.0.4 against master#ff2c56344c764af598ad33027e9c7a48881808ef for pr-118825
[INFO] extracting crate kaspa-notify 0.0.4 into /workspace/builds/worker-5-tc1/source
[INFO] validating manifest of crates.io crate kaspa-notify 0.0.4 on toolchain ff2c56344c764af598ad33027e9c7a48881808ef
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate kaspa-notify 0.0.4
[INFO] finished tweaking crates.io crate kaspa-notify 0.0.4
[INFO] tweaked toml for crates.io crate kaspa-notify 0.0.4 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded secp256k1 v0.24.3
[INFO] [stderr]   Downloaded kaspa-addresses v0.0.4
[INFO] [stderr]   Downloaded kaspa-merkle v0.0.4
[INFO] [stderr]   Downloaded kaspa-txscript-errors v0.0.4
[INFO] [stderr]   Downloaded intertrait-macros v0.2.2
[INFO] [stderr]   Downloaded linkme-impl v0.2.10
[INFO] [stderr]   Downloaded kaspa-muhash v0.0.4
[INFO] [stderr]   Downloaded kaspa-consensus-core v0.0.4
[INFO] [stderr]   Downloaded intertrait v0.2.2
[INFO] [stderr]   Downloaded kaspa-math v0.0.4
[INFO] [stderr]   Downloaded kaspa-core v0.0.4
[INFO] [stderr]   Downloaded linkme v0.2.10
[INFO] [stderr]   Downloaded secp256k1-sys v0.6.1
[INFO] [stderr]   Downloaded kaspa-txscript v0.0.4
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8792c8067e977d90adbb0987871f310e3ac094f81447ee6c9f5cbf234badab9a
[INFO] running `Command { std: "docker" "start" "-a" "8792c8067e977d90adbb0987871f310e3ac094f81447ee6c9f5cbf234badab9a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8792c8067e977d90adbb0987871f310e3ac094f81447ee6c9f5cbf234badab9a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8792c8067e977d90adbb0987871f310e3ac094f81447ee6c9f5cbf234badab9a", kill_on_drop: false }`
[INFO] [stdout] 8792c8067e977d90adbb0987871f310e3ac094f81447ee6c9f5cbf234badab9a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1f177af96ce2ed521c3922897e8fbb8809f85e0dd5a5a8c239e09c54daacf709
[INFO] running `Command { std: "docker" "start" "-a" "1f177af96ce2ed521c3922897e8fbb8809f85e0dd5a5a8c239e09c54daacf709", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.151
[INFO] [stderr]    Compiling serde v1.0.193
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling futures-core v0.3.29
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.89
[INFO] [stderr]    Compiling log v0.4.20
[INFO] [stderr]    Compiling bumpalo v3.14.0
[INFO] [stderr]    Compiling parking v2.2.0
[INFO] [stderr]    Compiling ryu v1.0.16
[INFO] [stderr]    Compiling futures-io v0.3.29
[INFO] [stderr]    Compiling serde_json v1.0.108
[INFO] [stderr]    Compiling memchr v2.6.4
[INFO] [stderr]    Compiling syn v2.0.41
[INFO] [stderr]    Compiling concurrent-queue v2.4.0
[INFO] [stderr]    Compiling itoa v1.0.10
[INFO] [stderr]    Compiling wasm-bindgen v0.2.89
[INFO] [stderr]    Compiling value-bag v1.4.2
[INFO] [stderr]    Compiling typenum v1.17.0
[INFO] [stderr]    Compiling event-listener v4.0.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling futures-task v0.3.29
[INFO] [stderr]    Compiling futures-channel v0.3.29
[INFO] [stderr]    Compiling rustix v0.38.28
[INFO] [stderr]    Compiling tracing-core v0.1.32
[INFO] [stderr]    Compiling event-listener v2.5.3
[INFO] [stderr]    Compiling futures-lite v2.1.0
[INFO] [stderr]    Compiling event-listener-strategy v0.4.0
[INFO] [stderr]    Compiling linux-raw-sys v0.4.12
[INFO] [stderr]    Compiling futures-sink v0.3.29
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-util v0.3.29
[INFO] [stderr]    Compiling async-lock v3.2.0
[INFO] [stderr]    Compiling io-lifetimes v1.0.11
[INFO] [stderr]    Compiling async-channel v2.1.1
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling polling v2.8.0
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling rustix v0.37.27
[INFO] [stderr]    Compiling async-task v4.5.0
[INFO] [stderr]    Compiling tracing v0.1.40
[INFO] [stderr]    Compiling piper v0.2.1
[INFO] [stderr]    Compiling aho-corasick v1.1.2
[INFO] [stderr]    Compiling async-io v1.13.0
[INFO] [stderr]    Compiling fastrand v1.9.0
[INFO] [stderr]    Compiling waker-fn v1.1.1
[INFO] [stderr]    Compiling linux-raw-sys v0.3.8
[INFO] [stderr]    Compiling regex-syntax v0.8.2
[INFO] [stderr]    Compiling unicode-segmentation v1.10.1
[INFO] [stderr]    Compiling futures-lite v1.13.0
[INFO] [stderr]    Compiling async-lock v2.8.0
[INFO] [stderr]    Compiling cpufeatures v0.2.11
[INFO] [stderr]    Compiling workflow-async-trait v0.1.68
[INFO] [stderr]    Compiling termcolor v1.4.0
[INFO] [stderr]    Compiling blocking v1.5.1
[INFO] [stderr]    Compiling async-executor v1.8.0
[INFO] [stderr]    Compiling convert_case v0.6.0
[INFO] [stderr]    Compiling triggered v0.1.2
[INFO] [stderr]    Compiling bytes v1.5.0
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling unicode-width v0.1.11
[INFO] [stderr]    Compiling async-channel v1.9.0
[INFO] [stderr]    Compiling downcast v0.11.0
[INFO] [stderr]    Compiling either v1.9.0
[INFO] [stderr]    Compiling instant v0.1.12
[INFO] [stderr]    Compiling num-traits v0.2.17
[INFO] [stderr]    Compiling rlimit v0.10.1
[INFO] [stderr]    Compiling bs58 v0.5.0
[INFO] [stderr]    Compiling lock_api v0.4.11
[INFO] [stderr]    Compiling indexmap v1.9.3
[INFO] [stderr]    Compiling faster-hex v0.6.1
[INFO] [stderr]    Compiling itertools v0.11.0
[INFO] [stderr]    Compiling ipnet v2.9.0
[INFO] [stderr]    Compiling crc32fast v1.3.2
[INFO] [stderr]    Compiling parking_lot_core v0.9.9
[INFO] [stderr]    Compiling getrandom v0.2.11
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling ahash v0.7.7
[INFO] [stderr]    Compiling socket2 v0.4.10
[INFO] [stderr]    Compiling num_cpus v1.16.0
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling hexplay v0.3.0
[INFO] [stderr]    Compiling regex-automata v0.4.3
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling console v0.15.7
[INFO] [stderr]    Compiling dirs v5.0.1
[INFO] [stderr]    Compiling kaspa-hashes v0.0.4
[INFO] [stderr]    Compiling secp256k1-sys v0.6.1
[INFO] [stderr]    Compiling base64 v0.13.1
[INFO] [stderr]    Compiling arrayvec v0.7.4
[INFO] [stderr]    Compiling linked-hash-map v0.5.6
[INFO] [stderr]    Compiling hashbrown v0.12.3
[INFO] [stderr]    Compiling anyhow v1.0.75
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling destructure_traitobject v0.2.0
[INFO] [stderr]    Compiling arrayref v0.3.7
[INFO] [stderr]    Compiling constant_time_eq v0.3.0
[INFO] [stderr]    Compiling yaml-rust v0.4.5
[INFO] [stderr]    Compiling blake2b_simd v1.0.2
[INFO] [stderr]    Compiling malachite-base v0.4.4
[INFO] [stderr]    Compiling unsafe-any-ors v1.0.0
[INFO] [stderr]    Compiling wasm-bindgen-backend v0.2.89
[INFO] [stderr]    Compiling toml v0.5.11
[INFO] [stderr]    Compiling miniz_oxide v0.7.1
[INFO] [stderr]    Compiling uuid v0.8.2
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling iana-time-zone v0.1.58
[INFO] [stderr]    Compiling chrono v0.4.31
[INFO] [stderr]    Compiling typemap-ors v1.0.0
[INFO] [stderr]    Compiling borsh-schema-derive-internal v0.9.3
[INFO] [stderr]    Compiling borsh-derive-internal v0.9.3
[INFO] [stderr]    Compiling flate2 v1.0.28
[INFO] [stderr]    Compiling nix v0.27.1
[INFO] [stderr]    Compiling thread-id v4.2.1
[INFO] [stderr]    Compiling log-mdc v0.1.0
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling proc-macro-crate v0.1.5
[INFO] [stderr]    Compiling arc-swap v1.6.0
[INFO] [stderr]    Compiling async-trait v0.1.74
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.89
[INFO] [stderr]    Compiling regex v1.10.2
[INFO] [stderr]    Compiling paste v1.0.14
[INFO] [stderr]    Compiling hashbrown v0.14.3
[INFO] [stderr]    Compiling equivalent v1.0.1
[INFO] [stderr]    Compiling convert_case v0.4.0
[INFO] [stderr]    Compiling indexmap v2.1.0
[INFO] [stderr]    Compiling serde_derive v1.0.193
[INFO] [stderr]    Compiling thiserror-impl v1.0.50
[INFO] [stderr]    Compiling futures-macro v0.3.29
[INFO] [stderr]    Compiling parse-variants-derive v1.0.0
[INFO] [stderr]    Compiling tokio-macros v2.2.0
[INFO] [stderr]    Compiling ctrlc v3.4.1
[INFO] [stderr]    Compiling polling v3.3.1
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.89
[INFO] [stderr]    Compiling borsh-derive v0.9.3
[INFO] [stderr]    Compiling async-attributes v1.1.2
[INFO] [stderr]    Compiling async-io v2.2.2
[INFO] [stderr]    Compiling parse-variants v1.0.1
[INFO] [stderr]    Compiling workflow-macro-tools v0.8.1
[INFO] [stderr]    Compiling linkme-impl v0.2.10
[INFO] [stderr]    Compiling embed-doc-image v0.1.4
[INFO] [stderr]    Compiling derivative v2.2.0
[INFO] [stderr]    Compiling workflow-core-macros v0.8.1
[INFO] [stderr]    Compiling intertrait-macros v0.2.2
[INFO] [stderr]    Compiling derive_more v0.99.17
[INFO] [stderr]    Compiling tokio v1.35.0
[INFO] [stderr]    Compiling borsh v0.9.3
[INFO] [stderr]    Compiling thiserror v1.0.50
[INFO] [stderr]    Compiling async-global-executor v2.4.1
[INFO] [stderr]    Compiling js-sys v0.3.66
[INFO] [stderr]    Compiling linkme v0.2.10
[INFO] [stderr]    Compiling intertrait v0.2.2
[INFO] [stderr]    Compiling futures-executor v0.3.29
[INFO] [stderr]    Compiling futures v0.3.29
[INFO] [stderr]    Compiling workflow-wasm-macros v0.8.1
[INFO] [stderr]    Compiling smallvec v1.11.2
[INFO] [stderr]    Compiling faster-hex v0.8.1
[INFO] [stderr]    Compiling uuid v1.6.1
[INFO] [stderr]    Compiling serde-value v0.7.0
[INFO] [stderr]    Compiling serde_yaml v0.8.26
[INFO] [stderr]    Compiling secp256k1 v0.24.3
[INFO] [stderr]    Compiling malachite-nz v0.4.4
[INFO] [stderr]    Compiling kaspa-utils v0.0.4
[INFO] [stderr]    Compiling parking_lot v0.12.1
[INFO] [stderr]    Compiling kv-log-macro v1.0.7
[INFO] [stderr]    Compiling kaspa-txscript-errors v0.0.4
[INFO] [stderr]    Compiling log4rs v1.2.0
[INFO] [stderr]    Compiling async-std v1.12.0
[INFO] [stderr]    Compiling workflow-log v0.8.1
[INFO] [stderr]    Compiling kaspa-core v0.0.4
[INFO] [stderr]    Compiling web-sys v0.3.66
[INFO] [stderr]    Compiling serde-wasm-bindgen v0.6.3
[INFO] [stderr]    Compiling wasm-bindgen-futures v0.4.39
[INFO] [stderr]    Compiling workflow-panic-hook v0.8.1
[INFO] [stderr]    Compiling workflow-core v0.8.1
[INFO] [stderr]    Compiling workflow-wasm v0.8.1
[INFO] [stderr]    Compiling kaspa-math v0.0.4
[INFO] [stderr]    Compiling kaspa-addresses v0.0.4
[INFO] [stderr]    Compiling kaspa-merkle v0.0.4
[INFO] [stderr]    Compiling kaspa-muhash v0.0.4
[INFO] [stderr]    Compiling kaspa-consensus-core v0.0.4
[INFO] [stderr]    Compiling kaspa-txscript v0.0.4
[INFO] [stderr]    Compiling kaspa-notify v0.0.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 04s
[INFO] running `Command { std: "docker" "inspect" "1f177af96ce2ed521c3922897e8fbb8809f85e0dd5a5a8c239e09c54daacf709", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1f177af96ce2ed521c3922897e8fbb8809f85e0dd5a5a8c239e09c54daacf709", kill_on_drop: false }`
[INFO] [stdout] 1f177af96ce2ed521c3922897e8fbb8809f85e0dd5a5a8c239e09c54daacf709
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5def0e43283b5038b6b54ccab7bd190963f1d0e20bd79e683350dc50ed0f407d
[INFO] running `Command { std: "docker" "start" "-a" "5def0e43283b5038b6b54ccab7bd190963f1d0e20bd79e683350dc50ed0f407d", kill_on_drop: false }`
[INFO] [stderr]    Compiling kaspa-notify v0.0.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 6.69s
[INFO] running `Command { std: "docker" "inspect" "5def0e43283b5038b6b54ccab7bd190963f1d0e20bd79e683350dc50ed0f407d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5def0e43283b5038b6b54ccab7bd190963f1d0e20bd79e683350dc50ed0f407d", kill_on_drop: false }`
[INFO] [stdout] 5def0e43283b5038b6b54ccab7bd190963f1d0e20bd79e683350dc50ed0f407d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+ff2c56344c764af598ad33027e9c7a48881808ef" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6e44a3d2a367d1c7ade810b733c2e46203fb1af74ffd0ff0655fd411c34066c7
[INFO] running `Command { std: "docker" "start" "-a" "6e44a3d2a367d1c7ade810b733c2e46203fb1af74ffd0ff0655fd411c34066c7", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.20s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kaspa_notify-74978a61b0b26b13)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] 2023-12-13 18:49:31.876+00:00 [TRACE] [Notifier test] registering listener 436771909381653735
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Notifier test] registering listener 15257813597847859341
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Notifier test] starting
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Subscriber test] starting subscription receiving task
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Broadcaster-test] Starting notification broadcasting task
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Notifier test] started
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Execute test step #0: do nothing
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Sending notification #0
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Receiving sync message #0 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Collector test] collecting task starting
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Execute test step #1: L0+ on
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Notifier test] start sending to listener 436771909381653735 notifications of scope VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true })
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Notifier test] start notifying to 436771909381653735 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true })
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Notifier test] start notifying to 436771909381653735 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) involves mutations [Mutation { command: Start, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) }]
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Receiving sync message #1 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Sending notification #1
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Receiving sync message #1 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #1 to listener 436771909381653735
[INFO] [stdout] 2023-12-13 18:49:31.884+00:00 [TRACE] Execute test step #2: L0+ & L1- on
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start sending to listener 15257813597847859341 notifications of scope VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start notifying to 15257813597847859341 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start notifying to 15257813597847859341 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) involves mutations [Mutation { command: Start, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) }]
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #2 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Sending notification #2
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #2 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #2 to listener 436771909381653735
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #2 to listener 15257813597847859341
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Execute test step #3: L0- & L1+ on
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start sending to listener 436771909381653735 notifications of scope VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start notifying to 436771909381653735 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start notifying to 436771909381653735 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) involves mutations [Mutation { command: Start, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) }, Mutation { command: Stop, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) }]
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #3 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start sending to listener 15257813597847859341 notifications of scope VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start notifying to 15257813597847859341 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] start notifying to 15257813597847859341 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) involves mutations [Mutation { command: Stop, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) }, Mutation { command: Start, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) }]
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #3 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Sending notification #3
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #3 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #3 to listener 15257813597847859341
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #3 to listener 436771909381653735
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Execute test step #4: L1+ on
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] stop sending to listener 436771909381653735 notifications of scope VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] stop notifying to 436771909381653735 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false })
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] stop notifying to 436771909381653735 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) involves mutations [Mutation { command: Stop, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: false }) }]
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #4 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Sending notification #4
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Receiving sync message #4 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #4 to listener 15257813597847859341
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Execute test step #5: all off
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.885+00:00 [TRACE] [Notifier test] stop sending to listener 15257813597847859341 notifications of scope VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true })
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Notifier test] stop notifying to 15257813597847859341 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true })
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Notifier test] stop notifying to 15257813597847859341 about VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) involves mutations [Mutation { command: Stop, scope: VirtualChainChanged(VirtualChainChangedScope { include_accepted_transaction_ids: true }) }]
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] Receiving sync message #5 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] Sending notification #5
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] Receiving sync message #5 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Notifier test] joining
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Notifier test] stopping collectors
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Collector test] joining
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Collector test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Collector test] collecting task ended
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Collector test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Notifier test] stopped collectors
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Notifier test] stopping broadcasters
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Broadcaster-test] joining
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Broadcaster-test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Broadcaster-test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Notifier test] stopping subscribers
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Subscriber test] joining
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Subscriber test] subscription stream ended
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Subscriber test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Notifier test] closing listeners
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Notifier test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Collector test] collecting task starting
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Collector test] joining
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Collector test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [TRACE] [Collector test] collecting task ended
[INFO] [stdout] 2023-12-13 18:49:31.886+00:00 [DEBUG] [Collector test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] [Broadcaster-test] Starting notification broadcasting task
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] apply_overall_subscription: BlockAdded(BlockAddedNotification { data: 1 }), OverallSubscription { event_type: BlockAdded, active: true }
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #1 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] apply_overall_subscription: BlockAdded(BlockAddedNotification { data: 2 }), OverallSubscription { event_type: BlockAdded, active: true }
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #2 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #2 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] apply_overall_subscription: BlockAdded(BlockAddedNotification { data: 3 }), OverallSubscription { event_type: BlockAdded, active: true }
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #3 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [TRACE] [Broadcaster-test] joining
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [DEBUG] [Broadcaster-test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.887+00:00 [DEBUG] [Broadcaster-test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.895+00:00 [TRACE] [Notifier test] registering listener 11866930189604367462
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] registering listener 5351890152480121975
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] starting
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Subscriber test] starting subscription receiving task
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Broadcaster-test] Starting notification broadcasting task
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] started
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Execute test step #0: do nothing
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Sending notification #0
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Receiving sync message #0 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Collector test] collecting task starting
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Execute test step #1: L0 on
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] start sending to listener 11866930189604367462 notifications of scope BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] start notifying to 11866930189604367462 about BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] start notifying to 11866930189604367462 about BlockAdded(BlockAddedScope) involves mutations [Mutation { command: Start, scope: BlockAdded(BlockAddedScope) }]
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Receiving sync message #1 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Sending notification #1
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Receiving sync message #1 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] apply_overall_subscription: BlockAdded(BlockAddedNotification { data: 1 }), OverallSubscription { event_type: BlockAdded, active: true }
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #1 to listener 11866930189604367462
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Execute test step #2: L0 & L1 on
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] start sending to listener 5351890152480121975 notifications of scope BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] start notifying to 5351890152480121975 about BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] start notifying to 5351890152480121975 about BlockAdded(BlockAddedScope) involves mutations [Mutation { command: Start, scope: BlockAdded(BlockAddedScope) }]
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Receiving sync message #2 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Sending notification #2
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Receiving sync message #2 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] apply_overall_subscription: BlockAdded(BlockAddedNotification { data: 2 }), OverallSubscription { event_type: BlockAdded, active: true }
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #2 to listener 11866930189604367462
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #2 to listener 5351890152480121975
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Execute test step #3: L1 on
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] stop sending to listener 11866930189604367462 notifications of scope BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] stop notifying to 11866930189604367462 about BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] [Notifier test] stop notifying to 11866930189604367462 about BlockAdded(BlockAddedScope) involves mutations [Mutation { command: Stop, scope: BlockAdded(BlockAddedScope) }]
[INFO] [stdout] 2023-12-13 18:49:31.896+00:00 [TRACE] Receiving sync message #3 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Sending notification #3
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Receiving sync message #3 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] apply_overall_subscription: BlockAdded(BlockAddedNotification { data: 3 }), OverallSubscription { event_type: BlockAdded, active: true }
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Broadcaster-test] sent notification BlockAdded #3 to listener 5351890152480121975
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Execute test step #4: all off
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Notifier test] stop sending to listener 5351890152480121975 notifications of scope BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Notifier test] stop notifying to 5351890152480121975 about BlockAdded(BlockAddedScope)
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Notifier test] stop notifying to 5351890152480121975 about BlockAdded(BlockAddedScope) involves mutations [Mutation { command: Stop, scope: BlockAdded(BlockAddedScope) }]
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Receiving sync message #4 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Sending notification #4
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] Receiving sync message #4 after notifying
[INFO] [stdout] test subscription::array::tests::test_array_builder ... ok
[INFO] [stdout] test notifier::tests::test_virtual_chain_changed ... ok
[INFO] [stdout] test collector::tests::test_collector_from ... ok
[INFO] [stdout] test broadcaster::tests::test_overall ... ok
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Notifier test] joining
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Notifier test] stopping collectors
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Collector test] joining
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Collector test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Collector test] collecting task ended
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Collector test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Notifier test] stopped collectors
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Notifier test] stopping broadcasters
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Broadcaster-test] joining
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Broadcaster-test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Broadcaster-test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Notifier test] stopping subscribers
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [TRACE] [Subscriber test] joining
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Subscriber test] subscription stream ended
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Subscriber test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Notifier test] closing listeners
[INFO] [stdout] 2023-12-13 18:49:31.897+00:00 [DEBUG] [Notifier test] terminated
[INFO] [stdout] test notifier::tests::test_overall ... ok
[INFO] [stdout] test subscription::single::tests::test_overall_mutation ... ok
[INFO] [stdout] test subscription::single::tests::test_virtual_chain_changed_mutation ... ok
[INFO] [stdout] test subscription::single::tests::test_subscription_hash ... ok
[INFO] [stdout] 2023-12-13 18:49:31.899+00:00 [TRACE] [Broadcaster-test] Starting notification broadcasting task
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #1 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #2 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #3 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #3 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #4 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #4 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #4 to listener 2
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #5 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #5 to listener 2
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #5 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.900+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #6 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #6 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #7 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #7 to listener 2
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #8 to listener 2
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [TRACE] [Broadcaster-test] joining
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [DEBUG] [Broadcaster-test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.901+00:00 [DEBUG] [Broadcaster-test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.905+00:00 [TRACE] [Notifier test] registering listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.905+00:00 [TRACE] [Notifier test] registering listener 10389686331431044223
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] Starting notification broadcasting task
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #1 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #2 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #2 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #3 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #3 to listener 0
[INFO] [stdout] 2023-12-13 18:49:31.906+00:00 [TRACE] [Broadcaster-test] sent notification VirtualChainChanged #4 to listener 1
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Broadcaster-test] joining
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [DEBUG] [Broadcaster-test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [DEBUG] [Broadcaster-test] terminated
[INFO] [stdout] test broadcaster::tests::test_utxos_changed ... ok
[INFO] [stdout] test subscription::single::tests::test_utxos_changed_mutation ... ok
[INFO] [stdout] test broadcaster::tests::test_virtual_chain_changed ... ok
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Notifier test] registering listener 5699358082037617863
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Notifier test] starting
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Subscriber test] starting subscription receiving task
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Broadcaster-test] Starting notification broadcasting task
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Notifier test] started
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] Execute test step #0: do nothing
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] Sending notification #0
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] Receiving sync message #0 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.907+00:00 [TRACE] [Collector test] collecting task starting
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Execute test step #1: L0[0] <= N[0]
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start sending to listener 15543041759478434528 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] })
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] })
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] }) involves mutations [Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Receiving sync message #1 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Sending notification #1
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Receiving sync message #1 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #1 to listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Execute test step #2: L0[0] <= N[0,1,2]
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start sending to listener 15543041759478434528 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] })
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] })
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] }) is ignored (no mutation)
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Receiving sync message #2 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Sending notification #2
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Receiving sync message #2 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #2 to listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Execute test step #3: L0[0], L1[1] <= N[0,1,2]
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start sending to listener 10389686331431044223 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] [Notifier test] start notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) involves mutations [Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Receiving sync message #3 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.908+00:00 [TRACE] Sending notification #3
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Receiving sync message #3 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #3 to listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #3 to listener 10389686331431044223
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Execute test step #4: L0[0], L1[1], L2[2] <= N[0,1,2]
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Mutation #2
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start sending to listener 5699358082037617863 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] })
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] })
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] }) involves mutations [Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Receiving sync message #4 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Sending notification #4
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Receiving sync message #4 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #4 to listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #4 to listener 10389686331431044223
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #4 to listener 5699358082037617863
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Execute test step #5: L0[0, 2], L1[*], L2[1, 2] <= N[0,1,2]
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start sending to listener 15543041759478434528 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] })
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] })
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] }) involves mutations [Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Receiving sync message #5 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start sending to listener 10389686331431044223 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.909+00:00 [TRACE] [Notifier test] start notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Notifier test] start notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [] }) involves mutations [Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) }, Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Receiving sync message #5 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Mutation #2
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Notifier test] start sending to listener 5699358082037617863 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Notifier test] start notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Notifier test] start notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) involves mutations [Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Receiving sync message #5 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Sending notification #5
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Receiving sync message #5 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #5 to listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #5 to listener 10389686331431044223
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #5 to listener 5699358082037617863
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Execute test step #6: L0[0, 2], L1[*], L2[1, 2] <= N[0]
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Sending notification #6
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] Receiving sync message #6 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #6 to listener 15543041759478434528
[INFO] [stdout] 2023-12-13 18:49:31.910+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #6 to listener 10389686331431044223
[INFO] [stdout] 2023-12-13 18:49:31.912+00:00 [TRACE] Execute test step #7: L0[2], L1[1], L2[*] <= N[0, 1]
[INFO] [stdout] 2023-12-13 18:49:31.912+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.912+00:00 [TRACE] [Notifier test] stop sending to listener 15543041759478434528 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] })
[INFO] [stdout] 2023-12-13 18:49:31.912+00:00 [TRACE] [Notifier test] stop notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] })
[INFO] [stdout] 2023-12-13 18:49:31.912+00:00 [TRACE] [Notifier test] stop notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] }) involves mutations [Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.913+00:00 [TRACE] Receiving sync message #7 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.913+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.913+00:00 [TRACE] [Notifier test] start sending to listener 10389686331431044223 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.914+00:00 [TRACE] [Notifier test] start notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.914+00:00 [TRACE] [Notifier test] start notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) involves mutations [Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) }, Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.915+00:00 [TRACE] Receiving sync message #7 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.915+00:00 [TRACE] Mutation #2
[INFO] [stdout] 2023-12-13 18:49:31.916+00:00 [TRACE] [Notifier test] start sending to listener 5699358082037617863 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.916+00:00 [TRACE] [Notifier test] start notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.916+00:00 [TRACE] [Notifier test] start notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [] }) involves mutations [Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }, Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) }, Mutation { command: Start, scope: UtxosChanged(UtxosChangedScope { addresses: [] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.916+00:00 [TRACE] Receiving sync message #7 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.917+00:00 [TRACE] Sending notification #7
[INFO] [stdout] 2023-12-13 18:49:31.917+00:00 [TRACE] Receiving sync message #7 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.918+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #7 to listener 10389686331431044223
[INFO] [stdout] 2023-12-13 18:49:31.918+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #7 to listener 5699358082037617863
[INFO] [stdout] 2023-12-13 18:49:31.918+00:00 [TRACE] Execute test step #8: L2[*] <= N[0, 1, 2]
[INFO] [stdout] 2023-12-13 18:49:31.918+00:00 [TRACE] Mutation #0
[INFO] [stdout] 2023-12-13 18:49:31.919+00:00 [TRACE] [Notifier test] stop sending to listener 15543041759478434528 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.919+00:00 [TRACE] [Notifier test] stop notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.919+00:00 [TRACE] [Notifier test] stop notifying to 15543041759478434528 about UtxosChanged(UtxosChangedScope { addresses: [] }) involves mutations [Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.919+00:00 [TRACE] Receiving sync message #8 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Mutation #1
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop sending to listener 10389686331431044223 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop notifying to 10389686331431044223 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) involves mutations [Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Receiving sync message #8 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Mutation #2
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop sending to listener 5699358082037617863 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] })
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [Address { prefix: Mainnet, version: PubKey, payload: [1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1] }] }) is ignored (no mutation)
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Receiving sync message #8 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Sending notification #8
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Receiving sync message #8 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Broadcaster-test] sent notification UtxosChanged #8 to listener 5699358082037617863
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Execute test step #9: all off
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Mutation #2
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop sending to listener 5699358082037617863 notifications of scope UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [] })
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] stop notifying to 5699358082037617863 about UtxosChanged(UtxosChangedScope { addresses: [] }) involves mutations [Mutation { command: Stop, scope: UtxosChanged(UtxosChangedScope { addresses: [] }) }]
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Receiving sync message #9 after subscribing
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Sending notification #9
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] Receiving sync message #9 after notifying
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Notifier test] joining
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [DEBUG] [Notifier test] stopping collectors
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Collector test] joining
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [DEBUG] [Collector test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [TRACE] [Collector test] collecting task ended
[INFO] [stdout] 2023-12-13 18:49:31.920+00:00 [DEBUG] [Collector test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Notifier test] stopped collectors
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Notifier test] stopping broadcasters
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [TRACE] [Broadcaster-test] joining
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Broadcaster-test] notification stream ended
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Broadcaster-test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Notifier test] stopping subscribers
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [TRACE] [Subscriber test] joining
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Subscriber test] subscription stream ended
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Subscriber test] terminated
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Notifier test] closing listeners
[INFO] [stdout] 2023-12-13 18:49:31.921+00:00 [DEBUG] [Notifier test] terminated
[INFO] [stdout] test notifier::tests::test_utxos_changed ... ok
[INFO] [stdout] test subscription::compounded::tests::test_overall_compounding ... ok
[INFO] [stdout] test subscription::compounded::tests::test_utxos_changed_compounding ... ok
[INFO] [stdout] test subscription::compounded::tests::test_virtual_chain_changed_compounding ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests kaspa-notify
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6e44a3d2a367d1c7ade810b733c2e46203fb1af74ffd0ff0655fd411c34066c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6e44a3d2a367d1c7ade810b733c2e46203fb1af74ffd0ff0655fd411c34066c7", kill_on_drop: false }`
[INFO] [stdout] 6e44a3d2a367d1c7ade810b733c2e46203fb1af74ffd0ff0655fd411c34066c7
[INFO] testing kaspa-notify-0.0.4 against try#5e4273472e1b2f223618a9b3def795f6224f241d for pr-118825
[INFO] extracting crate kaspa-notify 0.0.4 into /workspace/builds/worker-5-tc2/source
[INFO] validating manifest of crates.io crate kaspa-notify 0.0.4 on toolchain 5e4273472e1b2f223618a9b3def795f6224f241d
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate kaspa-notify 0.0.4
[INFO] finished tweaking crates.io crate kaspa-notify 0.0.4
[INFO] tweaked toml for crates.io crate kaspa-notify 0.0.4 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f35eb93dce0ce113a2c55921bf33fdfe0c56ffbe9427cb6fb2c589fe1cb3aba7
[INFO] running `Command { std: "docker" "start" "-a" "f35eb93dce0ce113a2c55921bf33fdfe0c56ffbe9427cb6fb2c589fe1cb3aba7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f35eb93dce0ce113a2c55921bf33fdfe0c56ffbe9427cb6fb2c589fe1cb3aba7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f35eb93dce0ce113a2c55921bf33fdfe0c56ffbe9427cb6fb2c589fe1cb3aba7", kill_on_drop: false }`
[INFO] [stdout] f35eb93dce0ce113a2c55921bf33fdfe0c56ffbe9427cb6fb2c589fe1cb3aba7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+5e4273472e1b2f223618a9b3def795f6224f241d" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7d8ed0c4dde54894efb3a834539728745d8c9b715a0d378ac5b6c9a719f89133
[INFO] running `Command { std: "docker" "start" "-a" "7d8ed0c4dde54894efb3a834539728745d8c9b715a0d378ac5b6c9a719f89133", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.151
[INFO] [stderr]    Compiling serde v1.0.193
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling futures-core v0.3.29
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.89
[INFO] [stderr]    Compiling log v0.4.20
[INFO] [stderr]    Compiling bumpalo v3.14.0
[INFO] [stderr]    Compiling parking v2.2.0
[INFO] [stderr]    Compiling ryu v1.0.16
[INFO] [stderr]    Compiling futures-io v0.3.29
[INFO] [stderr]    Compiling memchr v2.6.4
[INFO] [stderr]    Compiling serde_json v1.0.108
[INFO] [stderr]    Compiling syn v2.0.41
[INFO] [stderr]    Compiling concurrent-queue v2.4.0
[INFO] [stderr]    Compiling itoa v1.0.10
[INFO] [stderr]    Compiling wasm-bindgen v0.2.89
[INFO] [stderr]    Compiling value-bag v1.4.2
[INFO] [stderr]    Compiling typenum v1.17.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling event-listener v4.0.0
[INFO] [stderr]    Compiling futures-channel v0.3.29
[INFO] [stderr]    Compiling futures-task v0.3.29
[INFO] [stderr]    Compiling tracing-core v0.1.32
[INFO] [stderr]    Compiling rustix v0.38.28
[INFO] [stderr]    Compiling futures-lite v2.1.0
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-sink v0.3.29
[INFO] [stderr]    Compiling event-listener v2.5.3
[INFO] [stderr]    Compiling event-listener-strategy v0.4.0
[INFO] [stderr]    Compiling futures-util v0.3.29
[INFO] [stderr]    Compiling io-lifetimes v1.0.11
[INFO] [stderr]    Compiling linux-raw-sys v0.4.12
[INFO] [stderr]    Compiling async-lock v3.2.0
[INFO] [stderr]    Compiling tracing v0.1.40
[INFO] [stderr]    Compiling async-channel v2.1.1
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling polling v2.8.0
[INFO] [stderr]    Compiling async-task v4.5.0
[INFO] [stderr]    Compiling rustix v0.37.27
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling piper v0.2.1
[INFO] [stderr]    Compiling aho-corasick v1.1.2
[INFO] [stderr]    Compiling async-io v1.13.0
[INFO] [stderr]    Compiling regex-syntax v0.8.2
[INFO] [stderr]    Compiling fastrand v1.9.0
[INFO] [stderr]    Compiling unicode-segmentation v1.10.1
[INFO] [stderr]    Compiling linux-raw-sys v0.3.8
[INFO] [stderr]    Compiling waker-fn v1.1.1
[INFO] [stderr]    Compiling futures-lite v1.13.0
[INFO] [stderr]    Compiling blocking v1.5.1
[INFO] [stderr]    Compiling async-executor v1.8.0
[INFO] [stderr]    Compiling convert_case v0.6.0
[INFO] [stderr]    Compiling async-lock v2.8.0
[INFO] [stderr]    Compiling cpufeatures v0.2.11
[INFO] [stderr]    Compiling termcolor v1.4.0
[INFO] [stderr]    Compiling unicode-width v0.1.11
[INFO] [stderr]    Compiling triggered v0.1.2
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling workflow-async-trait v0.1.68
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling bytes v1.5.0
[INFO] [stderr]    Compiling async-channel v1.9.0
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling either v1.9.0
[INFO] [stderr]    Compiling downcast v0.11.0
[INFO] [stderr]    Compiling instant v0.1.12
[INFO] [stderr]    Compiling getrandom v0.2.11
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling socket2 v0.4.10
[INFO] [stderr]    Compiling num_cpus v1.16.0
[INFO] [stderr]    Compiling hexplay v0.3.0
[INFO] [stderr]    Compiling sha2 v0.10.8
[INFO] [stderr]    Compiling console v0.15.7
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling ahash v0.7.7
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling hashbrown v0.11.2
[INFO] [stderr]    Compiling dirs v5.0.1
[INFO] [stderr]    Compiling itertools v0.11.0
[INFO] [stderr]    Compiling num-traits v0.2.17
[INFO] [stderr]    Compiling rlimit v0.10.1
[INFO] [stderr]    Compiling bs58 v0.5.0
[INFO] [stderr]    Compiling lock_api v0.4.11
[INFO] [stderr]    Compiling indexmap v1.9.3
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.9
[INFO] [stderr]    Compiling faster-hex v0.6.1
[INFO] [stderr]    Compiling ipnet v2.9.0
[INFO] [stderr]    Compiling crc32fast v1.3.2
[INFO] [stderr]    Compiling regex-automata v0.4.3
[INFO] [stderr]    Compiling kaspa-hashes v0.0.4
[INFO] [stderr]    Compiling secp256k1-sys v0.6.1
[INFO] [stderr]    Compiling wasm-bindgen-backend v0.2.89
[INFO] [stderr]    Compiling constant_time_eq v0.3.0
[INFO] [stderr]    Compiling base64 v0.13.1
[INFO] [stderr]    Compiling hashbrown v0.12.3
[INFO] [stderr]    Compiling destructure_traitobject v0.2.0
[INFO] [stderr]    Compiling arrayref v0.3.7
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling linked-hash-map v0.5.6
[INFO] [stderr]    Compiling arrayvec v0.7.4
[INFO] [stderr]    Compiling anyhow v1.0.75
[INFO] [stderr]    Compiling yaml-rust v0.4.5
[INFO] [stderr]    Compiling toml v0.5.11
[INFO] [stderr]    Compiling malachite-base v0.4.4
[INFO] [stderr]    Compiling blake2b_simd v1.0.2
[INFO] [stderr]    Compiling unsafe-any-ors v1.0.0
[INFO] [stderr]    Compiling miniz_oxide v0.7.1
[INFO] [stderr]    Compiling uuid v0.8.2
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling iana-time-zone v0.1.58
[INFO] [stderr]    Compiling typemap-ors v1.0.0
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.89
[INFO] [stderr]    Compiling chrono v0.4.31
[INFO] [stderr]    Compiling flate2 v1.0.28
[INFO] [stderr]    Compiling proc-macro-crate v0.1.5
[INFO] [stderr]    Compiling borsh-schema-derive-internal v0.9.3
[INFO] [stderr]    Compiling borsh-derive-internal v0.9.3
[INFO] [stderr]    Compiling nix v0.27.1
[INFO] [stderr]    Compiling regex v1.10.2
[INFO] [stderr]    Compiling thread-id v4.2.1
[INFO] [stderr]    Compiling async-trait v0.1.74
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling polling v3.3.1
[INFO] [stderr] error: could not compile `syn` (lib)
[INFO] [stderr] 
[INFO] [stderr] Caused by:
[INFO] [stderr]   process didn't exit successfully: `/opt/rustwide/rustup-home/toolchains/5e4273472e1b2f223618a9b3def795f6224f241d/bin/rustc --crate-name syn --edition=2018 /opt/rustwide/cargo-home/registry/src/index.crates.io-6f17d22bba15001f/syn-1.0.109/src/lib.rs --error-format=json --json=diagnostic-rendered-ansi,artifacts,future-incompat --crate-type lib --emit=dep-info,metadata,link -C embed-bitcode=no --cfg 'feature="clone-impls"' --cfg 'feature="default"' --cfg 'feature="derive"' --cfg 'feature="extra-traits"' --cfg 'feature="fold"' --cfg 'feature="full"' --cfg 'feature="parsing"' --cfg 'feature="printing"' --cfg 'feature="proc-macro"' --cfg 'feature="quote"' --cfg 'feature="visit"' --cfg 'feature="visit-mut"' -C metadata=75c1524737b7034f -C extra-filename=-75c1524737b7034f --out-dir /opt/rustwide/target/debug/deps -L dependency=/opt/rustwide/target/debug/deps --extern proc_macro2=/opt/rustwide/target/debug/deps/libproc_macro2-90f63ccd04acb0cd.rmeta --extern quote=/opt/rustwide/target/debug/deps/libquote-e42a5656a0600e1d.rmeta --extern unicode_ident=/opt/rustwide/target/debug/deps/libunicode_ident-f8d62a079c79a15a.rmeta --cap-lints allow --cap-lints=forbid` (signal: 9, SIGKILL: kill)
[INFO] [stderr] warning: build failed, waiting for other jobs to finish...
[INFO] running `Command { std: "docker" "inspect" "7d8ed0c4dde54894efb3a834539728745d8c9b715a0d378ac5b6c9a719f89133", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d8ed0c4dde54894efb3a834539728745d8c9b715a0d378ac5b6c9a719f89133", kill_on_drop: false }`
[INFO] [stdout] 7d8ed0c4dde54894efb3a834539728745d8c9b715a0d378ac5b6c9a719f89133