[INFO] fetching crate traq-ws-bot 0.1.2... [INFO] testing traq-ws-bot-0.1.2 against master#cdb45c87e2cd43495379f7e867e3cc15dcee9f93 for pr-145838-1 [INFO] extracting crate traq-ws-bot 0.1.2 into /workspace/builds/worker-7-tc1/source [INFO] started tweaking crates.io crate traq-ws-bot 0.1.2 [INFO] finished tweaking crates.io crate traq-ws-bot 0.1.2 [INFO] tweaked toml for crates.io crate traq-ws-bot 0.1.2 written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate traq-ws-bot 0.1.2 on toolchain cdb45c87e2cd43495379f7e867e3cc15dcee9f93 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 202 packages to latest compatible versions [INFO] [stderr] Adding http v0.2.12 (available: v1.3.1) [INFO] [stderr] Adding reqwest v0.11.27 (available: v0.12.23) [INFO] [stderr] Adding tokio-tungstenite v0.17.2 (available: v0.27.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] cb076ccf6fd0ef4d6fee7606090ebb9c6e0858b54d04b9e05536b2967efc524a [INFO] running `Command { std: "docker" "start" "-a" "cb076ccf6fd0ef4d6fee7606090ebb9c6e0858b54d04b9e05536b2967efc524a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "cb076ccf6fd0ef4d6fee7606090ebb9c6e0858b54d04b9e05536b2967efc524a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "cb076ccf6fd0ef4d6fee7606090ebb9c6e0858b54d04b9e05536b2967efc524a", kill_on_drop: false }` [INFO] [stdout] cb076ccf6fd0ef4d6fee7606090ebb9c6e0858b54d04b9e05536b2967efc524a [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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] e1f0a8b49e6335e742b5da5777127c0df11f881fb53a597eb54fc1cbeca20347 [INFO] running `Command { std: "docker" "start" "-a" "e1f0a8b49e6335e742b5da5777127c0df11f881fb53a597eb54fc1cbeca20347", kill_on_drop: false }` [INFO] [stderr] Compiling tokio v1.47.1 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling rustls v0.20.9 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling rustls v0.21.12 [INFO] [stderr] Compiling sha-1 v0.10.1 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling tungstenite v0.17.3 [INFO] [stderr] Compiling tokio-util v0.7.16 [INFO] [stderr] Compiling tokio-rustls v0.24.1 [INFO] [stderr] Compiling tokio-rustls v0.23.4 [INFO] [stderr] Compiling tokio-tungstenite v0.17.2 [INFO] [stderr] Compiling h2 v0.3.27 [INFO] [stderr] Compiling hyper v0.14.32 [INFO] [stderr] Compiling hyper-rustls v0.24.2 [INFO] [stderr] Compiling reqwest v0.11.27 [INFO] [stderr] Compiling traq-ws-bot v0.1.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 21.96s [INFO] running `Command { std: "docker" "inspect" "e1f0a8b49e6335e742b5da5777127c0df11f881fb53a597eb54fc1cbeca20347", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e1f0a8b49e6335e742b5da5777127c0df11f881fb53a597eb54fc1cbeca20347", kill_on_drop: false }` [INFO] [stdout] e1f0a8b49e6335e742b5da5777127c0df11f881fb53a597eb54fc1cbeca20347 [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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6ff9b2b9a9f2cd8b74a384b8143f7f28e72e05b3f371bc9704579c461147480b [INFO] running `Command { std: "docker" "start" "-a" "6ff9b2b9a9f2cd8b74a384b8143f7f28e72e05b3f371bc9704579c461147480b", kill_on_drop: false }` [INFO] [stderr] Compiling traq-ws-bot v0.1.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 2.07s [INFO] running `Command { std: "docker" "inspect" "6ff9b2b9a9f2cd8b74a384b8143f7f28e72e05b3f371bc9704579c461147480b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6ff9b2b9a9f2cd8b74a384b8143f7f28e72e05b3f371bc9704579c461147480b", kill_on_drop: false }` [INFO] [stdout] 6ff9b2b9a9f2cd8b74a384b8143f7f28e72e05b3f371bc9704579c461147480b [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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+cdb45c87e2cd43495379f7e867e3cc15dcee9f93" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 22dbe2e531db8e38eebcc0ee8ba11236728c727a2393e5f55861030c5671aebb [INFO] running `Command { std: "docker" "start" "-a" "22dbe2e531db8e38eebcc0ee8ba11236728c727a2393e5f55861030c5671aebb", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.20s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/traq_ws_bot-c9ca1a861103a9a7) [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] Doc-tests traq_ws_bot [INFO] [stdout] [INFO] [stdout] running 42 tests [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_topic_changed_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::build (line 350) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_deleted_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_bot_message_stamps_updated (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_updated (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_topic_changed (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_bot_message_stamps_updated_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_deleted (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_updated_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_error (line 483) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_channel_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_direct_message_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::insert_resource (line 525) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_event (line 423) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_event (line 435) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_stamp_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_updated (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_stamp_created (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_updated_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_deleted (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_ping (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_created_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_left_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_message_deleted_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_ping_with_resource (line 465) ... ok [INFO] [stdout] test src/utils.rs - utils::RateLimiter::acquire (line 73) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_added_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_added (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_error_with_resource (line 503) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_removed (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_tag_removed_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_user_created (line 464) ... ok [INFO] [stdout] test src/utils.rs - utils::RateLimiter (line 45) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_user_created_with_resource (line 465) ... ok [INFO] [stdout] test src/utils.rs - utils::RateLimiter::try_acquire (line 97) ... FAILED [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_left (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_joined_with_resource (line 465) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBotBuilder::on_joined (line 464) ... ok [INFO] [stdout] test src/bot/mod.rs - bot::TraqBot::start (line 117) has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "22dbe2e531db8e38eebcc0ee8ba11236728c727a2393e5f55861030c5671aebb", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "22dbe2e531db8e38eebcc0ee8ba11236728c727a2393e5f55861030c5671aebb", kill_on_drop: false }` [INFO] [stdout] 22dbe2e531db8e38eebcc0ee8ba11236728c727a2393e5f55861030c5671aebb