[INFO] fetching crate gitlab-runner 0.3.0-rc1... [INFO] testing gitlab-runner-0.3.0-rc1 against try#b83b707f97d809763b7861afa7638871f3339a33 for pr-145838-1 [INFO] extracting crate gitlab-runner 0.3.0-rc1 into /workspace/builds/worker-4-tc2/source [INFO] started tweaking crates.io crate gitlab-runner 0.3.0-rc1 [INFO] removed 0 missing examples [INFO] removed 0 missing tests [INFO] finished tweaking crates.io crate gitlab-runner 0.3.0-rc1 [INFO] tweaked toml for crates.io crate gitlab-runner 0.3.0-rc1 written to /workspace/builds/worker-4-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate gitlab-runner 0.3.0-rc1 on toolchain b83b707f97d809763b7861afa7638871f3339a33 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate gitlab-runner 0.3.0-rc1 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" "+b83b707f97d809763b7861afa7638871f3339a33" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 02ab9b7e4d56a815e3024563d3af591565c277da2141cb53a7044851f976d46c [INFO] running `Command { std: "docker" "start" "-a" "02ab9b7e4d56a815e3024563d3af591565c277da2141cb53a7044851f976d46c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "02ab9b7e4d56a815e3024563d3af591565c277da2141cb53a7044851f976d46c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "02ab9b7e4d56a815e3024563d3af591565c277da2141cb53a7044851f976d46c", kill_on_drop: false }` [INFO] [stdout] 02ab9b7e4d56a815e3024563d3af591565c277da2141cb53a7044851f976d46c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f6cb45ecb17f348ae75233a318eb5e669c6dbc086c6769f271b48fd19fd285d9 [INFO] running `Command { std: "docker" "start" "-a" "f6cb45ecb17f348ae75233a318eb5e669c6dbc086c6769f271b48fd19fd285d9", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.94 [INFO] [stderr] Compiling libc v0.2.171 [INFO] [stderr] Compiling shlex v1.3.0 [INFO] [stderr] Compiling pkg-config v0.3.32 [INFO] [stderr] Compiling smallvec v1.14.0 [INFO] [stderr] Compiling once_cell v1.21.1 [INFO] [stderr] Compiling typenum v1.18.0 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling parking_lot_core v0.9.10 [INFO] [stderr] Compiling rustversion v1.0.20 [INFO] [stderr] Compiling generic-array v0.14.7 [INFO] [stderr] Compiling lock_api v0.4.12 [INFO] [stderr] Compiling stable_deref_trait v1.2.0 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling slab v0.4.9 [INFO] [stderr] Compiling strsim v0.11.1 [INFO] [stderr] Compiling ident_case v1.0.1 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling litemap v0.7.5 [INFO] [stderr] Compiling writeable v0.5.5 [INFO] [stderr] Compiling tracing-core v0.1.33 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling log v0.4.26 [INFO] [stderr] Compiling futures-io v0.3.31 [INFO] [stderr] Compiling getrandom v0.3.2 [INFO] [stderr] Compiling http v1.3.1 [INFO] [stderr] Compiling icu_locid_transform_data v1.5.0 [INFO] [stderr] Compiling powerfmt v0.2.0 [INFO] [stderr] Compiling hashbrown v0.15.2 [INFO] [stderr] Compiling anyhow v1.0.97 [INFO] [stderr] Compiling bitflags v2.9.0 [INFO] [stderr] Compiling num-conv v0.1.0 [INFO] [stderr] Compiling zerocopy v0.8.23 [INFO] [stderr] Compiling icu_properties_data v1.5.0 [INFO] [stderr] Compiling httparse v1.10.1 [INFO] [stderr] Compiling time-core v0.1.4 [INFO] [stderr] Compiling deranged v0.4.0 [INFO] [stderr] Compiling subtle v2.6.1 [INFO] [stderr] Compiling openssl v0.10.73 [INFO] [stderr] Compiling quote v1.0.40 [INFO] [stderr] Compiling utf16_iter v1.0.5 [INFO] [stderr] Compiling icu_normalizer_data v1.5.0 [INFO] [stderr] Compiling write16 v1.0.0 [INFO] [stderr] Compiling atomic-waker v1.1.2 [INFO] [stderr] Compiling try-lock v0.2.5 [INFO] [stderr] Compiling syn v2.0.100 [INFO] [stderr] Compiling utf8_iter v1.0.4 [INFO] [stderr] Compiling native-tls v0.2.14 [INFO] [stderr] Compiling want v0.3.1 [INFO] [stderr] Compiling zstd-safe v7.2.3 [INFO] [stderr] Compiling cpufeatures v0.2.17 [INFO] [stderr] Compiling vergen-lib v0.1.6 [INFO] [stderr] Compiling vergen v9.0.6 [INFO] [stderr] Compiling vergen-gitcl v1.0.8 [INFO] [stderr] Compiling unicase v2.8.1 [INFO] [stderr] Compiling openssl-probe v0.1.6 [INFO] [stderr] Compiling num_threads v0.1.7 [INFO] [stderr] Compiling percent-encoding v2.3.1 [INFO] [stderr] Compiling tower-service v0.3.3 [INFO] [stderr] Compiling crc32fast v1.4.2 [INFO] [stderr] Compiling rustix v1.0.3 [INFO] [stderr] Compiling mime_guess v2.0.5 [INFO] [stderr] Compiling ryu v1.0.20 [INFO] [stderr] Compiling zlib-rs v0.5.1 [INFO] [stderr] Compiling adler2 v2.0.0 [INFO] [stderr] Compiling form_urlencoded v1.2.1 [INFO] [stderr] Compiling miniz_oxide v0.8.5 [INFO] [stderr] Compiling sync_wrapper v1.0.2 [INFO] [stderr] Compiling indexmap v2.8.0 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling bumpalo v3.17.0 [INFO] [stderr] Compiling simd-adler32 v0.3.7 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling lockfree-object-pool v0.1.6 [INFO] [stderr] Compiling tower-layer v0.3.3 [INFO] [stderr] Compiling thiserror v2.0.12 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling rustls-pki-types v1.11.0 [INFO] [stderr] Compiling overload v0.1.1 [INFO] [stderr] Compiling linux-raw-sys v0.9.3 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling nu-ansi-term v0.46.0 [INFO] [stderr] Compiling zopfli v0.8.1 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling time v0.3.40 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling thread_local v1.1.8 [INFO] [stderr] Compiling encoding_rs v0.8.35 [INFO] [stderr] Compiling jobserver v0.1.32 [INFO] [stderr] Compiling mio v1.0.3 [INFO] [stderr] Compiling cc v1.2.16 [INFO] [stderr] Compiling signal-hook-registry v1.4.2 [INFO] [stderr] Compiling socket2 v0.5.8 [INFO] [stderr] Compiling crypto-common v0.1.6 [INFO] [stderr] Compiling parking_lot v0.12.3 [INFO] [stderr] Compiling block-buffer v0.10.4 [INFO] [stderr] Compiling digest v0.10.7 [INFO] [stderr] Compiling inout v0.1.4 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling sha1 v0.10.6 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling pbkdf2 v0.12.2 [INFO] [stderr] Compiling rustls-pemfile v2.2.0 [INFO] [stderr] Compiling base64 v0.22.1 [INFO] [stderr] Compiling cipher v0.4.4 [INFO] [stderr] Compiling ipnet v2.11.0 [INFO] [stderr] Compiling deflate64 v0.1.9 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling aes v0.8.4 [INFO] [stderr] Compiling constant_time_eq v0.3.1 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling sha2 v0.10.8 [INFO] [stderr] Compiling glob v0.3.2 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling rand v0.9.0 [INFO] [stderr] Compiling openssl-sys v0.9.109 [INFO] [stderr] Compiling zstd-sys v2.0.14+zstd.1.5.7 [INFO] [stderr] Compiling bzip2-sys v0.1.13+1.0.8 [INFO] [stderr] Compiling liblzma-sys v0.4.4 [INFO] [stderr] Compiling tempfile v3.19.0 [INFO] [stderr] Compiling bzip2 v0.5.2 [INFO] [stderr] Compiling libz-rs-sys v0.5.1 [INFO] [stderr] Compiling flate2 v1.1.2 [INFO] [stderr] Compiling synstructure v0.13.1 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling liblzma v0.4.1 [INFO] [stderr] Compiling zerofrom-derive v0.1.6 [INFO] [stderr] Compiling yoke-derive v0.7.5 [INFO] [stderr] Compiling zerovec-derive v0.10.3 [INFO] [stderr] Compiling displaydoc v0.2.5 [INFO] [stderr] Compiling tokio-macros v2.5.0 [INFO] [stderr] Compiling icu_provider_macros v1.5.0 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling tracing-attributes v0.1.28 [INFO] [stderr] Compiling openssl-macros v0.1.1 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling zeroize_derive v1.4.2 [INFO] [stderr] Compiling pin-project-internal v1.1.10 [INFO] [stderr] Compiling thiserror-impl v2.0.12 [INFO] [stderr] Compiling async-trait v0.1.88 [INFO] [stderr] Compiling zeroize v1.8.1 [INFO] [stderr] Compiling tokio v1.44.2 [INFO] [stderr] Compiling pin-project v1.1.10 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling zerofrom v0.1.6 [INFO] [stderr] Compiling yoke v0.7.5 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling zerovec v0.10.4 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling zstd v0.13.3 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling zip v4.1.0 [INFO] [stderr] Compiling tinystr v0.7.6 [INFO] [stderr] Compiling icu_collections v1.5.0 [INFO] [stderr] Compiling icu_locid v1.5.0 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling icu_provider v1.5.0 [INFO] [stderr] Compiling gitlab-runner v0.3.0-rc1 (/opt/rustwide/workdir) [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: not within a suitable 'git' worktree! [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_SHA set to default [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_DIRTY set to default [INFO] [stderr] Compiling icu_locid_transform v1.5.0 [INFO] [stderr] Compiling icu_properties v1.5.1 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling icu_normalizer v1.5.0 [INFO] [stderr] Compiling tokio-util v0.7.14 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling tokio-retry2 v0.5.7 [INFO] [stderr] Compiling idna_adapter v1.2.0 [INFO] [stderr] Compiling idna v1.0.3 [INFO] [stderr] Compiling h2 v0.4.8 [INFO] [stderr] Compiling url v2.5.4 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.10 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling reqwest v0.12.15 [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:60:17 [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:65:21 [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:316:21 [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:321:22 [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:326:25 [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 57.42s [INFO] running `Command { std: "docker" "inspect" "f6cb45ecb17f348ae75233a318eb5e669c6dbc086c6769f271b48fd19fd285d9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f6cb45ecb17f348ae75233a318eb5e669c6dbc086c6769f271b48fd19fd285d9", kill_on_drop: false }` [INFO] [stdout] f6cb45ecb17f348ae75233a318eb5e669c6dbc086c6769f271b48fd19fd285d9 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 317f6c0b23dbd17e271fcec1e8754c339cdef3cc69ffd00dff6a06c4cf7e3fbc [INFO] running `Command { std: "docker" "start" "-a" "317f6c0b23dbd17e271fcec1e8754c339cdef3cc69ffd00dff6a06c4cf7e3fbc", kill_on_drop: false }` [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling tokio v1.44.2 [INFO] [stderr] Compiling anyhow v1.0.97 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling regex-syntax v0.8.5 [INFO] [stderr] Compiling regex-syntax v0.6.29 [INFO] [stderr] Compiling anstyle-parse v0.2.6 [INFO] [stderr] Compiling num_cpus v1.16.0 [INFO] [stderr] Compiling multer v3.1.0 [INFO] [stderr] Compiling deadpool-runtime v0.1.4 [INFO] [stderr] Compiling colorchoice v1.0.3 [INFO] [stderr] Compiling anstyle-query v1.1.2 [INFO] [stderr] Compiling anstyle v1.0.10 [INFO] [stderr] Compiling assert-json-diff v2.0.2 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling clap_lex v0.7.4 [INFO] [stderr] Compiling heck v0.5.0 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling clap_derive v4.5.32 [INFO] [stderr] Compiling anstream v0.6.18 [INFO] [stderr] Compiling clap_builder v4.5.32 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling derive_builder_core v0.20.2 [INFO] [stderr] Compiling matchers v0.1.0 [INFO] [stderr] Compiling derive_builder_macro v0.20.2 [INFO] [stderr] Compiling derive_builder v0.20.2 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling clap v4.5.32 [INFO] [stderr] Compiling vergen-lib v0.1.6 [INFO] [stderr] Compiling vergen v9.0.6 [INFO] [stderr] Compiling vergen-gitcl v1.0.8 [INFO] [stderr] Compiling gitlab-runner v0.3.0-rc1 (/opt/rustwide/workdir) [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: not within a suitable 'git' worktree! [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_SHA set to default [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_DIRTY set to default [INFO] [stderr] Compiling tokio-util v0.7.14 [INFO] [stderr] Compiling tokio-native-tls v0.3.1 [INFO] [stderr] Compiling deadpool v0.10.0 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling tokio-retry2 v0.5.7 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling h2 v0.4.8 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling hyper v1.6.0 [INFO] [stderr] Compiling hyper-util v0.1.10 [INFO] [stderr] Compiling hyper-tls v0.6.0 [INFO] [stderr] Compiling wiremock v0.6.3 [INFO] [stderr] Compiling reqwest v0.12.15 [INFO] [stderr] Compiling gitlab-runner-mock v0.2.1 [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:60:17 [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:65:21 [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:316:21 [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:321:22 [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:326:25 [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:60:17 [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/artifact.rs:65:21 [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stdout] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:316:21 [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:321:22 [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stdout] --> src/job.rs:326:25 [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stdout] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stdout] | | [INFO] [stdout] | the lifetime is elided here [INFO] [stdout] | [INFO] [stdout] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stdout] help: use `'_` for type paths [INFO] [stdout] | [INFO] [stdout] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stdout] | ++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 43.24s [INFO] running `Command { std: "docker" "inspect" "317f6c0b23dbd17e271fcec1e8754c339cdef3cc69ffd00dff6a06c4cf7e3fbc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "317f6c0b23dbd17e271fcec1e8754c339cdef3cc69ffd00dff6a06c4cf7e3fbc", kill_on_drop: false }` [INFO] [stdout] 317f6c0b23dbd17e271fcec1e8754c339cdef3cc69ffd00dff6a06c4cf7e3fbc [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] b389ae3f9da187abc6aef4f6042a3c4157e0f164ea93e4fa65ce344d80beadd9 [INFO] running `Command { std: "docker" "start" "-a" "b389ae3f9da187abc6aef4f6042a3c4157e0f164ea93e4fa65ce344d80beadd9", kill_on_drop: false }` [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: not within a suitable 'git' worktree! [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_SHA set to default [INFO] [stderr] warning: gitlab-runner@0.3.0-rc1: VERGEN_GIT_DIRTY set to default [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/artifact.rs:60:17 [INFO] [stderr] | [INFO] [stderr] 60 | pub fn file(&mut self, name: &str) -> Option { [INFO] [stderr] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] = note: `#[warn(mismatched_lifetime_syntaxes)]` on by default [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 60 | pub fn file(&mut self, name: &str) -> Option> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/artifact.rs:65:21 [INFO] [stderr] | [INFO] [stderr] 65 | pub fn by_index(&mut self, i: usize) -> Option { [INFO] [stderr] | ^^^^^^^^^ ^^^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 65 | pub fn by_index(&mut self, i: usize) -> Option> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/job.rs:316:21 [INFO] [stderr] | [INFO] [stderr] 316 | pub fn variable(&self, key: &str) -> Option { [INFO] [stderr] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 316 | pub fn variable(&self, key: &str) -> Option> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/job.rs:321:22 [INFO] [stderr] | [INFO] [stderr] 321 | pub fn variables(&self) -> impl Iterator { [INFO] [stderr] | ^^^^^ ^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 321 | pub fn variables(&self) -> impl Iterator> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: hiding a lifetime that's elided elsewhere is confusing [INFO] [stderr] --> src/job.rs:326:25 [INFO] [stderr] | [INFO] [stderr] 326 | pub fn dependencies(&self) -> impl Iterator { [INFO] [stderr] | ^^^^^ ^^^^^^^^^^ the same lifetime is hidden here [INFO] [stderr] | | [INFO] [stderr] | the lifetime is elided here [INFO] [stderr] | [INFO] [stderr] = help: the same lifetime is referred to in inconsistent ways, making the signature confusing [INFO] [stderr] help: use `'_` for type paths [INFO] [stderr] | [INFO] [stderr] 326 | pub fn dependencies(&self) -> impl Iterator> { [INFO] [stderr] | ++++ [INFO] [stderr] [INFO] [stderr] warning: `gitlab-runner` (lib) generated 5 warnings (run `cargo fix --lib -p gitlab-runner` to apply 5 suggestions) [INFO] [stderr] warning: `gitlab-runner` (lib test) generated 5 warnings (5 duplicates) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.36s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/gitlab_runner-6cfaf19342c24026) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test client::test::deserialize_variables ... ok [INFO] [stdout] test runlist::test::runlist ... ok [INFO] [stdout] test client::test::process_job ... ok [INFO] [stdout] test client::test::no_job ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s [INFO] [stdout] [INFO] [stderr] Running tests/artifacts.rs (/opt/rustwide/target/debug/deps/artifacts-25dc839350648628) [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test multiple_upload ... ok [INFO] [stdout] test upload_download ... ok [INFO] [stderr] Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-372a2ab4c6989323) [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.72s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 13 tests [INFO] [stdout] 2025-09-01T09:06:04.737954Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:36959) [INFO] [stdout] 2025-09-01T09:06:04.738098Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(36959)) [INFO] [stdout] 2025-09-01T09:06:04.738142Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:36959 [INFO] [stdout] 2025-09-01T09:06:04.738399Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:36959 [INFO] [stdout] 2025-09-01T09:06:04.738478Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.738629Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:36959) [INFO] [stdout] 2025-09-01T09:06:04.754685Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:36959) [INFO] [stdout] 2025-09-01T09:06:04.754747Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:36959) [INFO] [stdout] 2025-09-01T09:06:04.769997Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:43105) [INFO] [stdout] 2025-09-01T09:06:04.770127Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(43105)) [INFO] [stdout] 2025-09-01T09:06:04.770169Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:43105 [INFO] [stdout] 2025-09-01T09:06:04.770413Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:43105 [INFO] [stdout] 2025-09-01T09:06:04.770476Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.770613Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:43105) [INFO] [stdout] 2025-09-01T09:06:04.785335Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:36959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.785520Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:36959) [INFO] [stdout] 2025-09-01T09:06:04.786512Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:36959) [INFO] [stdout] 2025-09-01T09:06:04.786572Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:36959) [INFO] [stdout] test job_variables ... ok [INFO] [stdout] 2025-09-01T09:06:04.797069Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:43105) [INFO] [stdout] 2025-09-01T09:06:04.797222Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:43105) [INFO] [stdout] 2025-09-01T09:06:04.806744Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:04.812588Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.814723Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(44419)) [INFO] [stdout] 2025-09-01T09:06:04.815257Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:44419 [INFO] [stdout] 2025-09-01T09:06:04.816182Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:44419 [INFO] [stdout] 2025-09-01T09:06:04.816256Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.816346Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.819881Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.819929Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.823090Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:44419): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.823140Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.824123Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.824170Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:44419) [INFO] [stdout] 2025-09-01T09:06:04.832134Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:42525) [INFO] [stdout] 2025-09-01T09:06:04.832278Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(42525)) [INFO] [stdout] 2025-09-01T09:06:04.832331Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:42525 [INFO] [stdout] test job_fail ... ok [INFO] [stdout] 2025-09-01T09:06:04.832599Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:42525 [INFO] [stdout] 2025-09-01T09:06:04.832760Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.832906Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:42525) [INFO] [stdout] 2025-09-01T09:06:04.836974Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42525) [INFO] [stdout] 2025-09-01T09:06:04.837037Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42525) [INFO] [stdout] 2025-09-01T09:06:04.875478Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.875630Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35123)) [INFO] [stdout] 2025-09-01T09:06:04.875675Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.875927Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.876006Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.876140Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.885924Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.886055Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.886905Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35123): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.886941Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.897942Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.898054Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.898827Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35123): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.898857Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.905862Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.905915Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.906658Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35123): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.906696Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.907745Z  INFO integration: TEST [INFO] [stdout] 2025-09-01T09:06:04.920114Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.920284Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35433)) [INFO] [stdout] 2025-09-01T09:06:04.920378Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35433 [INFO] [stdout] 2025-09-01T09:06:04.907877Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:04.910199Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.920814Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(46685)) [INFO] [stdout] 2025-09-01T09:06:04.920879Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:46685 [INFO] [stdout] 2025-09-01T09:06:04.921133Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:46685 [INFO] [stdout] 2025-09-01T09:06:04.921216Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.921340Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.911556Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.921611Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.922418Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35123): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.922450Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.922640Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35433 [INFO] [stdout] 2025-09-01T09:06:04.922701Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.922796Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.912127Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.923900Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(42959)) [INFO] [stdout] 2025-09-01T09:06:04.923936Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:42959 [INFO] [stdout] 2025-09-01T09:06:04.924106Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:42959 [INFO] [stdout] 2025-09-01T09:06:04.924167Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.924312Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.925057Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.925079Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.925702Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35123): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.925726Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.927726Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.927795Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.928974Z  INFO run{gitlab.job=266}: integration: TEST1234 [INFO] [stdout] 2025-09-01T09:06:04.929059Z TRACE run{gitlab.job=266}: integration: aa gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:04.929103Z TRACE run{gitlab.job=266}: integration: cc gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:04.929735Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35433): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.929996Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.930214Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.930241Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.930731Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.930757Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.930961Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.930987Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.932022Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:46685): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.932067Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.932762Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.932785Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.932801Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.932811Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.932962Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35433): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.933001Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.932533Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.933183Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.933509Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:35123): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.933568Z DEBUG run{gitlab.job=270}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.933920Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.933944Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.934374Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.934480Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35123)) [INFO] [stdout] 2025-09-01T09:06:04.934527Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.934692Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.934713Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.934726Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:46685) [INFO] [stdout] 2025-09-01T09:06:04.934745Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35433) [INFO] [stdout] 2025-09-01T09:06:04.935855Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.935876Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.936753Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.936819Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35123)) [INFO] [stdout] 2025-09-01T09:06:04.936854Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.937093Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.937149Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35123)) [INFO] [stdout] 2025-09-01T09:06:04.937182Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.937392Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.937447Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(35123)) [INFO] [stdout] 2025-09-01T09:06:04.937478Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.937739Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.937809Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.937877Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.937931Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.937986Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.938029Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.938082Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::connect::http: connected to 127.0.0.1:35123 [INFO] [stdout] 2025-09-01T09:06:04.938125Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.938180Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.938187Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.938208Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.939221Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:38333) [INFO] [stdout] 2025-09-01T09:06:04.939287Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(38333)) [INFO] [stdout] 2025-09-01T09:06:04.939311Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:38333 [INFO] [stdout] 2025-09-01T09:06:04.939424Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.939436Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:38333 [INFO] [stdout] 2025-09-01T09:06:04.939446Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.939476Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.939577Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: put; found waiter for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.939767Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.939855Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.939936Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.940002Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:35123) [INFO] [stdout] test job_log ... ok [INFO] [stdout] 2025-09-01T09:06:04.940732Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.940761Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.941278Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.941319Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.941499Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.941531Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.941714Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:38333) [INFO] [stdout] test job_success ... ok [INFO] [stdout] 2025-09-01T09:06:04.946751Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.946809Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.947049Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.947084Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:35123) [INFO] [stdout] 2025-09-01T09:06:04.949772Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.949823Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.950665Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38333) [INFO] [stdout] 2025-09-01T09:06:04.950693Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38333) [INFO] [stdout] 2025-09-01T09:06:04.951336Z TRACE hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.951364Z DEBUG hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.952104Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.952097Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:38333): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.952128Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.952141Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:38333) [INFO] [stdout] 2025-09-01T09:06:04.952768Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.952815Z DEBUG run{gitlab.job=270}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.953233Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:38333) [INFO] [stdout] 2025-09-01T09:06:04.953284Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:38333) [INFO] [stdout] 2025-09-01T09:06:04.953916Z TRACE run{gitlab.job=270}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.953956Z DEBUG run{gitlab.job=270}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.954632Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.954677Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.955219Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.955250Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] test job_drain ... ok [INFO] [stdout] 2025-09-01T09:06:04.955796Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.955833Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.956327Z TRACE run{gitlab.job=268}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.956357Z DEBUG run{gitlab.job=268}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.956865Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.956903Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.957415Z TRACE run{gitlab.job=267}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.957445Z DEBUG run{gitlab.job=267}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.957918Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42959): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.957952Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.958458Z TRACE run{gitlab.job=269}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] 2025-09-01T09:06:04.958488Z DEBUG run{gitlab.job=269}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42959) [INFO] [stdout] test job_steps ... ok [INFO] [stdout] 2025-09-01T09:06:04.967621Z TRACE hyper_util::client::legacy::pool: checkout waiting for idle connection: ("http", 127.0.0.1:42823) [INFO] [stdout] 2025-09-01T09:06:04.967731Z TRACE hyper_util::client::legacy::connect::http: Http::connect; scheme=Some("http"), host=Some("127.0.0.1"), port=Some(Port(42823)) [INFO] [stdout] 2025-09-01T09:06:04.967772Z DEBUG hyper_util::client::legacy::connect::http: connecting to 127.0.0.1:42823 [INFO] [stdout] 2025-09-01T09:06:04.967962Z DEBUG hyper_util::client::legacy::connect::http: connected to 127.0.0.1:42823 [INFO] [stdout] 2025-09-01T09:06:04.968021Z TRACE hyper_util::client::legacy::client: http1 handshake complete, spawning background dispatcher task [INFO] [stdout] 2025-09-01T09:06:04.968114Z TRACE hyper_util::client::legacy::pool: checkout dropped for ("http", 127.0.0.1:42823) [INFO] [stdout] test job_parallel ... ok [INFO] [stdout] 2025-09-01T09:06:04.972974Z TRACE hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42823) [INFO] [stdout] 2025-09-01T09:06:04.973019Z DEBUG hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42823) [INFO] [stdout] 2025-09-01T09:06:04.976189Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42823): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:04.976241Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42823) [INFO] [stdout] 2025-09-01T09:06:04.977330Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42823) [INFO] [stdout] 2025-09-01T09:06:04.977366Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42823) [INFO] [stdout] test job_metadata ... ok [INFO] [stdout] test runner_run ... ok [INFO] [stdout] 2025-09-01T09:06:05.022012Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.051865Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:42525): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:05.051927Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:42525) [INFO] [stdout] 2025-09-01T09:06:05.053292Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:42525) [INFO] [stdout] 2025-09-01T09:06:05.053371Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:42525) [INFO] [stdout] test job_panic ... ok [INFO] [stdout] 2025-09-01T09:06:05.123446Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.224852Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.325401Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.426960Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.528592Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.629346Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.731154Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] test runner_delay ... ok [INFO] [stdout] 2025-09-01T09:06:05.832809Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:05.933287Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] test runner_limit ... ok [INFO] [stdout] 2025-09-01T09:06:06.034763Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.135315Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.236921Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.338524Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.440100Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.541702Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.644750Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.745288Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.846879Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:06.947426Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.049023Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.150610Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.251897Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.353472Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.455052Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.556679Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.657363Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.758912Z TRACE run{gitlab.job=266}: integration: logging! gitlab.output=true [INFO] [stdout] 2025-09-01T09:06:07.799414Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: take? ("http", 127.0.0.1:43105): expiration = Some(90s) [INFO] [stdout] 2025-09-01T09:06:07.799509Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: reuse idle connection for ("http", 127.0.0.1:43105) [INFO] [stdout] 2025-09-01T09:06:07.800765Z TRACE run{gitlab.job=266}: hyper_util::client::legacy::pool: put; add idle connection for ("http", 127.0.0.1:43105) [INFO] [stdout] 2025-09-01T09:06:07.800942Z DEBUG run{gitlab.job=266}: hyper_util::client::legacy::pool: pooling idle connection for ("http", 127.0.0.1:43105) [INFO] [stdout] test job_cancel_step ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.53s [INFO] [stdout] [INFO] [stderr] Running tests/runhandler.rs (/opt/rustwide/target/debug/deps/runhandler-d5f8f199f8ec661c) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test update_interval ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.46s [INFO] [stdout] [INFO] [stderr] Doc-tests gitlab_runner [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test src/lib.rs - (line 16) - compile ... ok [INFO] [stdout] test src/logging.rs - logging::GitlabLayer::new (line 72) ... ok [INFO] [stdout] test src/lib.rs - RunnerBuilder::new (line 231) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.57s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "b389ae3f9da187abc6aef4f6042a3c4157e0f164ea93e4fa65ce344d80beadd9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b389ae3f9da187abc6aef4f6042a3c4157e0f164ea93e4fa65ce344d80beadd9", kill_on_drop: false }` [INFO] [stdout] b389ae3f9da187abc6aef4f6042a3c4157e0f164ea93e4fa65ce344d80beadd9