[INFO] fetching crate embedded-mqttc 1.0.0... [INFO] testing embedded-mqttc-1.0.0 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1 [INFO] extracting crate embedded-mqttc 1.0.0 into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate embedded-mqttc 1.0.0 [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate embedded-mqttc 1.0.0 [INFO] tweaked toml for crates.io crate embedded-mqttc 1.0.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate embedded-mqttc 1.0.0 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 embedded-mqttc 1.0.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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] c15c6fa8ee118c554567dca5b0f16440995501671008a733021207a31e787c23 [INFO] running `Command { std: "docker" "start" "-a" "c15c6fa8ee118c554567dca5b0f16440995501671008a733021207a31e787c23", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "c15c6fa8ee118c554567dca5b0f16440995501671008a733021207a31e787c23", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c15c6fa8ee118c554567dca5b0f16440995501671008a733021207a31e787c23", kill_on_drop: false }` [INFO] [stdout] c15c6fa8ee118c554567dca5b0f16440995501671008a733021207a31e787c23 [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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7552fa1a8c1b5101f40aacef6cac5fd0b690d3584d234428478a2675ccfad98f [INFO] running `Command { std: "docker" "start" "-a" "7552fa1a8c1b5101f40aacef6cac5fd0b690d3584d234428478a2675ccfad98f", kill_on_drop: false }` [INFO] [stderr] Compiling defmt-macros v1.0.1 [INFO] [stderr] Compiling defmt v1.0.1 [INFO] [stderr] Compiling nb v1.1.0 [INFO] [stderr] Compiling embedded-io-async v0.6.1 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling heapless v0.8.0 [INFO] [stderr] Compiling embassy-time-driver v0.2.1 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling embedded-hal-async v1.0.0 [INFO] [stderr] Compiling critical-section v1.2.0 [INFO] [stderr] Compiling embassy-sync v0.6.2 [INFO] [stderr] Compiling syn v2.0.109 [INFO] [stderr] Compiling proc-macro-error-attr2 v2.0.0 [INFO] [stderr] Compiling void v1.0.2 [INFO] [stderr] Compiling embassy-futures v0.1.2 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling hash32 v0.3.1 [INFO] [stderr] Compiling nb v0.1.3 [INFO] [stderr] Compiling embedded-nal v0.9.0 [INFO] [stderr] Compiling embedded-hal v0.2.7 [INFO] [stderr] Compiling mqttrs2 v0.1.0 [INFO] [stderr] Compiling embassy-time v0.4.0 [INFO] [stderr] Compiling proc-macro-error2 v2.0.1 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling defmt-parser v1.0.0 [INFO] [stderr] Compiling defmt v0.3.100 [INFO] [stderr] Compiling embedded-io v0.6.1 [INFO] [stderr] Compiling embedded-nal-async v0.8.0 [INFO] [stderr] Compiling embedded-mqttc v1.0.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.54s [INFO] running `Command { std: "docker" "inspect" "7552fa1a8c1b5101f40aacef6cac5fd0b690d3584d234428478a2675ccfad98f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7552fa1a8c1b5101f40aacef6cac5fd0b690d3584d234428478a2675ccfad98f", kill_on_drop: false }` [INFO] [stdout] 7552fa1a8c1b5101f40aacef6cac5fd0b690d3584d234428478a2675ccfad98f [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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 8c91a848ae12dff46d3ec787416ccfb5425291f0d91c3b9231d07c136728e2d8 [INFO] running `Command { std: "docker" "start" "-a" "8c91a848ae12dff46d3ec787416ccfb5425291f0d91c3b9231d07c136728e2d8", kill_on_drop: false }` [INFO] [stderr] Compiling cc v1.2.44 [INFO] [stderr] Compiling rustls-pki-types v1.13.0 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling hashbrown v0.16.0 [INFO] [stderr] Compiling embassy-executor v0.7.0 [INFO] [stderr] Compiling syn v2.0.109 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling winnow v0.7.13 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling embassy-time-driver v0.2.1 [INFO] [stderr] Compiling embassy-time-queue-utils v0.1.0 [INFO] [stderr] Compiling toml_datetime v0.7.3 [INFO] [stderr] Compiling rustls v0.22.4 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling uuid v1.18.1 [INFO] [stderr] Compiling dns-lookup v3.0.1 [INFO] [stderr] Compiling flume v0.11.1 [INFO] [stderr] Compiling rustls-pemfile v2.2.0 [INFO] [stderr] Compiling rustls-native-certs v0.7.3 [INFO] [stderr] Compiling indexmap v2.12.0 [INFO] [stderr] Compiling ring v0.17.14 [INFO] [stderr] Compiling toml_parser v1.0.4 [INFO] [stderr] Compiling toml_edit v0.23.7 [INFO] [stderr] Compiling proc-macro-crate v3.4.0 [INFO] [stderr] Compiling ntest_test_cases v0.9.3 [INFO] [stderr] Compiling ntest_timeout v0.9.3 [INFO] [stderr] Compiling ntest v0.9.3 [INFO] [stderr] Compiling proc-macro-error2 v2.0.1 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling test-log-macros v0.2.18 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling rustls-webpki v0.102.8 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling defmt-parser v1.0.0 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling defmt-macros v1.0.1 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling embassy-executor-macros v0.6.2 [INFO] [stderr] Compiling defmt v1.0.1 [INFO] [stderr] Compiling test-log v0.2.18 [INFO] [stderr] Compiling defmt v0.3.100 [INFO] [stderr] Compiling embedded-io v0.6.1 [INFO] [stderr] Compiling embassy-time v0.4.0 [INFO] [stderr] Compiling embedded-io-async v0.6.1 [INFO] [stderr] Compiling embedded-nal-async v0.8.0 [INFO] [stderr] Compiling embassy-sync v0.6.2 [INFO] [stderr] Compiling tokio-rustls v0.25.0 [INFO] [stderr] Compiling tokio-util v0.7.17 [INFO] [stderr] Compiling embedded-mqttc v1.0.0 (/opt/rustwide/workdir) [INFO] [stderr] Compiling rumqttc v0.24.0 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 27.23s [INFO] running `Command { std: "docker" "inspect" "8c91a848ae12dff46d3ec787416ccfb5425291f0d91c3b9231d07c136728e2d8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8c91a848ae12dff46d3ec787416ccfb5425291f0d91c3b9231d07c136728e2d8", kill_on_drop: false }` [INFO] [stdout] 8c91a848ae12dff46d3ec787416ccfb5425291f0d91c3b9231d07c136728e2d8 [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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 28ae5a0b13348f0c07ce13a019f4b1956d9139a98773e52eb7a2debf1bf80fb9 [INFO] running `Command { std: "docker" "start" "-a" "28ae5a0b13348f0c07ce13a019f4b1956d9139a98773e52eb7a2debf1bf80fb9", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.23s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/embedded_mqttc-0378df9eed0e46f4) [INFO] [stdout] [INFO] [stdout] running 31 tests [INFO] [stdout] test buffer::tests::test_buffer_cell_string_parsing ... ok [INFO] [stdout] test buffer::tests::test_write_to_end ... ok [INFO] [stdout] test state::connection::tests::test_connect ... ok [INFO] [stdout] test state::connection::tests::test_run_io_nonblocking_read_write ... ok [INFO] [stdout] test state::connection::tests::test_run_io_read ... ok [INFO] [stdout] test state::connection::tests::test_run_io_read_write ... ok [INFO] [stdout] test state::receives2::tests::test_qos_0_receive ... ok [INFO] [stdout] test state::publish2::tests::test_qos_1_publish ... ok [INFO] [stdout] test state::receives2::tests::test_qos_1_receive ... ok [INFO] [stdout] test state::publish2::tests::test_qos_0_publish ... ok [INFO] [stdout] test state::connection::tests::test_disconnect ... ok [INFO] [stdout] test state::receives2::tests::test_no_dup_in_qos_2 ... ok [INFO] [stdout] test buffer::tests::test_buffer_write_all ... ok [INFO] [stdout] test state::publish2::tests::test_qos_2_publish ... ok [INFO] [stdout] test state::receives2::tests::test_qos_2_receive ... ok [INFO] [stdout] test state::sub2::tests::test_unsubscribe ... ok [INFO] [stdout] test state::receives2::tests::test_send_pubcomp_to_pubrel ... ok [INFO] [stdout] test state::request::tests::test_disconnect_multi_notify ... ok [INFO] [stdout] test state::request::tests::test_disconnect_notify ... ok [INFO] [stdout] test state::receives2::tests::test_send_pubrec_publish ... ok [INFO] [stdout] test state::request::tests::test_multi_notify ... ok [INFO] [stdout] test state::request::tests::test_notify ... ok [INFO] [stdout] test state::sub2::tests::test_parallel_subscribe ... ok [INFO] [stdout] test testutils::test::test_poll_once ... ok [INFO] [stdout] test state::pid::tests::test_pid_reuse ... ok [INFO] [stdout] test state::sub2::tests::test_subscribe ... ok [INFO] [stdout] test time::tests::test_wait_static ... ok [INFO] [stdout] test time::tests::test_now ... ok [INFO] [stdout] test time::tests::test_now_static ... ok [INFO] [stdout] test state::pid::tests::test_next_pid ... ok [INFO] [stdout] test state::pid::tests::test_concurrent_access ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.69s [INFO] [stdout] [INFO] [stderr] Running tests/test_publish_fake.rs (/opt/rustwide/target/debug/deps/test_publish_fake-61ab7a9d259203fb) [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] [stderr] Running tests/test_with_broker.rs (/opt/rustwide/target/debug/deps/test_with_broker-f603db239e702501) [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test test_auto_subscribe ... ignored, broker test skipped [INFO] [stdout] test test_broker_publish ... ignored, broker test skipped [INFO] [stdout] test test_broker_publish_qos0 ... ignored, broker test skipped [INFO] [stdout] test test_broker_publish_qos1 ... ignored, broker test skipped [INFO] [stdout] test test_broker_publish_qos2 ... ignored, broker test skipped [INFO] [stdout] test test_broker_subscribe ... ignored, broker test skipped [INFO] [stdout] test test_last_will ... ignored, broker test skipped [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Doc-tests embedded_mqttc [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" "28ae5a0b13348f0c07ce13a019f4b1956d9139a98773e52eb7a2debf1bf80fb9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "28ae5a0b13348f0c07ce13a019f4b1956d9139a98773e52eb7a2debf1bf80fb9", kill_on_drop: false }` [INFO] [stdout] 28ae5a0b13348f0c07ce13a019f4b1956d9139a98773e52eb7a2debf1bf80fb9