[INFO] fetching crate sonos-sdk-event-manager 0.4.0...
[INFO] testing sonos-sdk-event-manager-0.4.0 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-2
[INFO] extracting crate sonos-sdk-event-manager 0.4.0 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate sonos-sdk-event-manager 0.4.0
[INFO] finished tweaking crates.io crate sonos-sdk-event-manager 0.4.0
[INFO] tweaked toml for crates.io crate sonos-sdk-event-manager 0.4.0 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sonos-sdk-event-manager 0.4.0 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sonos-sdk-event-manager 0.4.0 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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded sonos-sdk-soap-client v0.4.0
[INFO] [stderr]   Downloaded tokio-tungstenite v0.21.0
[INFO] [stderr]   Downloaded xmltree v0.10.3
[INFO] [stderr]   Downloaded sonos-sdk-discovery v0.4.0
[INFO] [stderr]   Downloaded sonos-sdk-callback-server v0.4.0
[INFO] [stderr]   Downloaded sonos-sdk-stream v0.4.0
[INFO] [stderr]   Downloaded sonos-api v0.4.0
[INFO] [stderr]   Downloaded quick-xml v0.31.0
[INFO] [stderr]   Downloaded warp v0.3.7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 88ad127b8a5b8f98b2ed10859fc533016716c6a66f4ebc0c8a240f6c800bfc5e
[INFO] running `Command { std: "docker" "start" "-a" "88ad127b8a5b8f98b2ed10859fc533016716c6a66f4ebc0c8a240f6c800bfc5e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "88ad127b8a5b8f98b2ed10859fc533016716c6a66f4ebc0c8a240f6c800bfc5e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "88ad127b8a5b8f98b2ed10859fc533016716c6a66f4ebc0c8a240f6c800bfc5e", kill_on_drop: false }`
[INFO] [stdout] 88ad127b8a5b8f98b2ed10859fc533016716c6a66f4ebc0c8a240f6c800bfc5e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bb0cb5e5d0c41003c9e6ba45a7b4b43101e52a0cef67e637ae51309e60f8bf1f
[INFO] running `Command { std: "docker" "start" "-a" "bb0cb5e5d0c41003c9e6ba45a7b4b43101e52a0cef67e637ae51309e60f8bf1f", kill_on_drop: false }`
[INFO] [stderr]    Compiling tracing-core v0.1.35
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling rustls-pki-types v1.13.1
[INFO] [stderr]    Compiling http-body v0.4.6
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling serde_json v1.0.145
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling rustls v0.23.35
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling rustls-pemfile v1.0.4
[INFO] [stderr]    Compiling multer v2.1.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling utf-8 v0.7.6
[INFO] [stderr]    Compiling data-encoding v2.9.0
[INFO] [stderr]    Compiling openssl v0.10.75
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling tokio v1.48.0
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling webpki-roots v1.0.4
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling flate2 v1.1.5
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling headers-core v0.2.0
[INFO] [stderr]    Compiling xml-rs v0.8.28
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling headers v0.3.9
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling url v2.5.7
[INFO] [stderr]    Compiling quick-xml v0.31.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling uuid v1.19.0
[INFO] [stderr]    Compiling tracing v0.1.43
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling tungstenite v0.21.0
[INFO] [stderr]    Compiling xmltree v0.10.3
[INFO] [stderr]    Compiling dashmap v5.5.3
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling native-tls v0.2.14
[INFO] [stderr]    Compiling rustls-webpki v0.103.8
[INFO] [stderr]    Compiling tokio-util v0.7.17
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling tokio-tungstenite v0.21.0
[INFO] [stderr]    Compiling h2 v0.3.27
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling hyper v0.14.32
[INFO] [stderr]    Compiling sonos-sdk-soap-client v0.4.0
[INFO] [stderr]    Compiling hyper-tls v0.5.0
[INFO] [stderr]    Compiling warp v0.3.7
[INFO] [stderr]    Compiling reqwest v0.11.27
[INFO] [stderr]    Compiling sonos-sdk-discovery v0.4.0
[INFO] [stderr]    Compiling sonos-api v0.4.0
[INFO] [stderr]    Compiling sonos-sdk-callback-server v0.4.0
[INFO] [stderr]    Compiling sonos-sdk-stream v0.4.0
[INFO] [stderr]    Compiling sonos-sdk-event-manager v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 39.27s
[INFO] running `Command { std: "docker" "inspect" "bb0cb5e5d0c41003c9e6ba45a7b4b43101e52a0cef67e637ae51309e60f8bf1f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bb0cb5e5d0c41003c9e6ba45a7b4b43101e52a0cef67e637ae51309e60f8bf1f", kill_on_drop: false }`
[INFO] [stdout] bb0cb5e5d0c41003c9e6ba45a7b4b43101e52a0cef67e637ae51309e60f8bf1f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ca21912fb48c9d9aee23c91456a96a53cba119b151d5329b4a248452cb68b263
[INFO] running `Command { std: "docker" "start" "-a" "ca21912fb48c9d9aee23c91456a96a53cba119b151d5329b4a248452cb68b263", kill_on_drop: false }`
[INFO] [stderr]    Compiling sonos-sdk-event-manager v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.61s
[INFO] running `Command { std: "docker" "inspect" "ca21912fb48c9d9aee23c91456a96a53cba119b151d5329b4a248452cb68b263", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ca21912fb48c9d9aee23c91456a96a53cba119b151d5329b4a248452cb68b263", kill_on_drop: false }`
[INFO] [stdout] ca21912fb48c9d9aee23c91456a96a53cba119b151d5329b4a248452cb68b263
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 22895ab294a0b315e1ec7e34f385e20e5bee42fbd523322c89e3bcb664ef3d35
[INFO] running `Command { std: "docker" "start" "-a" "22895ab294a0b315e1ec7e34f385e20e5bee42fbd523322c89e3bcb664ef3d35", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.26s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sonos_event_manager-e14467837a9df820)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test iter::tests::test_try_iter_empty ... ok
[INFO] [stdout] test iter::tests::test_clone ... ok
[INFO] [stdout] test worker::tests::test_command_debug ... ok
[INFO] [stdout] test manager::tests::test_grace_period_cancelled_by_reacquire ... ok
[INFO] [stdout] test iter::tests::test_try_recv_empty ... ok
[INFO] [stdout] test manager::tests::test_reference_counting ... ok
[INFO] [stdout] test manager::tests::test_device_management ... ok
[INFO] [stdout] test manager::tests::test_acquire_release_watch_ref_counting ... ok
[INFO] [stdout] test manager::tests::test_watch_registry_integration ... ok
[INFO] [stdout] test manager::tests::test_shutdown_drains_pending_grace_timers ... ok
[INFO] [stdout] test manager::tests::test_multiple_services_independent_grace_periods ... ok
[INFO] [stdout] test manager::tests::test_guard_drop_with_disconnected_worker ... ok
[INFO] [stdout] test manager::tests::test_stats ... ok
[INFO] [stdout] test iter::tests::test_recv_timeout_empty ... ok
[INFO] [stderr]    Doc-tests sonos_event_manager
[INFO] [stdout] test manager::tests::test_grace_period_fires_after_timeout ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test src/lib.rs - (line 25) ... ignored
[INFO] [stdout] test src/manager.rs - manager::SonosEventManager (line 109) ... ignored
[INFO] [stdout] test src/manager.rs - manager::SonosEventManager::iter (line 471) ... ignored
[INFO] [stdout] test src/lib.rs - prelude (line 87) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 3 ignored; 0 measured; 0 filtered out; finished in 0.41s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "22895ab294a0b315e1ec7e34f385e20e5bee42fbd523322c89e3bcb664ef3d35", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "22895ab294a0b315e1ec7e34f385e20e5bee42fbd523322c89e3bcb664ef3d35", kill_on_drop: false }`
[INFO] [stdout] 22895ab294a0b315e1ec7e34f385e20e5bee42fbd523322c89e3bcb664ef3d35