[INFO] fetching crate mqtt-client-wasm 0.2.2...
[INFO] testing mqtt-client-wasm-0.2.2 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate mqtt-client-wasm 0.2.2 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate mqtt-client-wasm 0.2.2
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate mqtt-client-wasm 0.2.2
[INFO] tweaked toml for crates.io crate mqtt-client-wasm 0.2.2 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate mqtt-client-wasm 0.2.2 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate mqtt-client-wasm 0.2.2 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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a22ed7d5c2f5dd44962642ae4cd631d30e0de4f807bf4db04289a0ef66056e7d
[INFO] running `Command { std: "docker" "start" "-a" "a22ed7d5c2f5dd44962642ae4cd631d30e0de4f807bf4db04289a0ef66056e7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a22ed7d5c2f5dd44962642ae4cd631d30e0de4f807bf4db04289a0ef66056e7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a22ed7d5c2f5dd44962642ae4cd631d30e0de4f807bf4db04289a0ef66056e7d", kill_on_drop: false }`
[INFO] [stdout] a22ed7d5c2f5dd44962642ae4cd631d30e0de4f807bf4db04289a0ef66056e7d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 248f8cb3de99bb9bc180adffc12426fac3d75655e3bbe60cd2975384568e69af
[INFO] running `Command { std: "docker" "start" "-a" "248f8cb3de99bb9bc180adffc12426fac3d75655e3bbe60cd2975384568e69af", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.44
[INFO] [stderr]    Compiling rustversion v1.0.22
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.108
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling bumpalo v3.19.1
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling zmij v1.0.16
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling anyhow v1.0.100
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling bytes v1.11.0
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling proc-macro-error-attr2 v2.0.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling wasm-bindgen v0.2.108
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.108
[INFO] [stderr]    Compiling darling_core v0.20.11
[INFO] [stderr]    Compiling proc-macro-error2 v2.0.1
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling num_enum_derive v0.7.5
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling getset v0.1.6
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling delegate v0.13.5
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling num_enum v0.7.5
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.108
[INFO] [stderr]    Compiling darling_macro v0.20.11
[INFO] [stderr]    Compiling darling v0.20.11
[INFO] [stderr]    Compiling derive_builder_core v0.20.2
[INFO] [stderr]    Compiling js-sys v0.3.85
[INFO] [stderr]    Compiling console_error_panic_hook v0.1.7
[INFO] [stderr]    Compiling derive_builder_macro v0.20.2
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling derive_builder v0.20.2
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling mqtt-protocol-core v0.7.7
[INFO] [stderr]    Compiling wasm-bindgen-futures v0.4.58
[INFO] [stderr]    Compiling serde-wasm-bindgen v0.6.5
[INFO] [stderr]    Compiling web-sys v0.3.85
[INFO] [stderr]    Compiling mqtt-client-wasm v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 33.90s
[INFO] running `Command { std: "docker" "inspect" "248f8cb3de99bb9bc180adffc12426fac3d75655e3bbe60cd2975384568e69af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "248f8cb3de99bb9bc180adffc12426fac3d75655e3bbe60cd2975384568e69af", kill_on_drop: false }`
[INFO] [stdout] 248f8cb3de99bb9bc180adffc12426fac3d75655e3bbe60cd2975384568e69af
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d3453103338c1280698c8ac56c9c0b31f1b5c4de632d94eee35af36248edf42d
[INFO] running `Command { std: "docker" "start" "-a" "d3453103338c1280698c8ac56c9c0b31f1b5c4de632d94eee35af36248edf42d", kill_on_drop: false }`
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling wasm-bindgen-test-macro v0.3.58
[INFO] [stderr]    Compiling wasm-bindgen-test-shared v0.2.108
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling mqtt-protocol-core v0.7.7
[INFO] [stderr]    Compiling wasm-bindgen-test v0.3.58
[INFO] [stderr]    Compiling mqtt-client-wasm v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.48s
[INFO] running `Command { std: "docker" "inspect" "d3453103338c1280698c8ac56c9c0b31f1b5c4de632d94eee35af36248edf42d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d3453103338c1280698c8ac56c9c0b31f1b5c4de632d94eee35af36248edf42d", kill_on_drop: false }`
[INFO] [stdout] d3453103338c1280698c8ac56c9c0b31f1b5c4de632d94eee35af36248edf42d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 418c71d201090fd8ed6a672a90ff68bdd4672aa373f90bee59345eef6ea14f73
[INFO] running `Command { std: "docker" "start" "-a" "418c71d201090fd8ed6a672a90ff68bdd4672aa373f90bee59345eef6ea14f73", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.21s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mqtt_client_wasm-add5241ec305b000)
[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] [stderr]      Running tests/client_tests.rs (/opt/rustwide/target/debug/deps/client_tests-27f3ae8016551a97)
[INFO] [stdout] 
[INFO] [stdout] running 49 tests
[INFO] [stdout] test test_mqtt_client_creation ... ok
[INFO] [stdout] test test_multiple_recv_timeouts_no_packet_loss ... ok
[INFO] [stdout] test test_comprehensive_mqtt_flow ... ok
[INFO] [stdout] test test_mqtt_connect_flow ... ok
[INFO] [stdout] test test_mqtt_publish_subscribe ... ok
[INFO] [stdout] test test_mqtt_packet_flow ... ok
[INFO] [stdout] test test_config_with_pingreq_interval ... ok
[INFO] [stdout] test test_multiple_packet_id_acquisitions ... ok
[INFO] [stdout] test test_disconnect_packet ... ok
[INFO] [stdout] test test_config_with_all_options ... ok
[INFO] [stdout] test test_qos2_publish ... ok
[INFO] [stdout] test test_recv_timeout_no_packet_loss ... ok
[INFO] [stdout] test test_pingresp_reception ... ok
[INFO] [stdout] test test_connect_packet_exchange ... ok
[INFO] [stdout] test test_pingreq_send ... ok
[INFO] [stdout] test test_receive_mqtt_packet ... ok
[INFO] [stdout] test test_is_connected_after_disconnect ... ok
[INFO] [stdout] test test_receive_multiple_packets ... ok
[INFO] [stdout] test test_partial_packet_reassembly ... ok
[INFO] [stdout] test test_mqtt_v50_packet_exchange ... ok
[INFO] [stdout] test test_multiple_timer_resets ... ok
[INFO] [stdout] test test_acquire_packet_id_after_close ... ok
[INFO] [stdout] test test_register_packet_id ... ok
[INFO] [stdout] test test_release_packet_id ... ok
[INFO] [stdout] test test_undelivered_packet_saved ... ok
[INFO] [stdout] test test_recv_after_websocket_close ... ok
[INFO] [stdout] test test_unsubscribe_packet ... ok
[INFO] [stdout] test test_v50_connack_user_properties ... ok
[INFO] [stdout] test test_state_transitions ... ok
[INFO] [stdout] test test_send_after_close ... ok
[INFO] [stdout] test test_v50_publish ... ok
[INFO] [stdout] test test_reject_connect_when_already_connected ... ok
[INFO] [stdout] test test_timer_reset_via_connect ... ok
[INFO] [stdout] test test_recv_timeout ... ok
[INFO] [stdout] test test_register_packet_id_after_close ... ok
[INFO] [stdout] test test_v50_publish_user_properties ... ok
[INFO] [stdout] test test_state_after_channel_close ... ok
[INFO] [stdout] test test_pingresp_recv_timeout ... ok
[INFO] [stdout] test test_v50_suback_user_properties ... ok
[INFO] [stdout] test test_timer_cancel_via_disconnect ... ok
[INFO] [stdout] test test_release_packet_id_after_close ... ok
[INFO] [stdout] test test_v50_disconnect_user_properties ... ok
[INFO] [stdout] test test_v50_timer_handling ... ok
[INFO] [stdout] test test_v50_publish_topic_name_extracted ... ok
[INFO] [stdout] test test_timer_expiration ... ok
[INFO] [stdout] test test_v50_publish_subscription_identifiers ... ok
[INFO] [stdout] test test_websocket_close_during_operation ... ok
[INFO] [stdout] test test_reconnect_after_close ... ok
[INFO] [stdout] test test_websocket_error_handling ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 49 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.45s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/wasm_tests.rs (/opt/rustwide/target/debug/deps/wasm_tests-3b1e62dc4eb44554)
[INFO] [stderr]    Doc-tests mqtt_client_wasm
[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] [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" "418c71d201090fd8ed6a672a90ff68bdd4672aa373f90bee59345eef6ea14f73", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "418c71d201090fd8ed6a672a90ff68bdd4672aa373f90bee59345eef6ea14f73", kill_on_drop: false }`
[INFO] [stdout] 418c71d201090fd8ed6a672a90ff68bdd4672aa373f90bee59345eef6ea14f73