[INFO] cloning repository https://github.com/collabora/obs-gitlab-runner [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/collabora/obs-gitlab-runner" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 6142a4c0487e6fdb2d015cafe06f9b6fe5d8f549 [INFO] testing collabora/obs-gitlab-runner against beta-2025-01-12 for beta-1.85-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcollabora%2Fobs-gitlab-runner" "/workspace/builds/worker-5-tc2/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc2/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/collabora/obs-gitlab-runner on toolchain beta-2025-01-12 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-01-12" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/collabora/obs-gitlab-runner [INFO] finished tweaking git repo https://github.com/collabora/obs-gitlab-runner [INFO] tweaked toml for git repo https://github.com/collabora/obs-gitlab-runner written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] crate git repo https://github.com/collabora/obs-gitlab-runner 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" "+beta-2025-01-12" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating crates.io index [INFO] [stderr] Updating git repository `https://github.com/collabora/open-build-service-rs` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded deadpool v0.7.0 [INFO] [stderr] Downloaded blocking v1.1.0 [INFO] [stderr] Downloaded async-lock v2.4.0 [INFO] [stderr] Downloaded shellexpand-fork v2.1.1 [INFO] [stderr] Downloaded assert-json-diff v2.0.1 [INFO] [stderr] Downloaded crossbeam-queue v0.3.4 [INFO] [stderr] Downloaded wiremock v0.5.10 [INFO] [stderr] Downloaded xml-builder v0.5.0 [INFO] [stderr] Downloaded claim v0.5.0 [INFO] [stderr] Downloaded eyre v0.6.6 [INFO] [stderr] Downloaded clap_derive v3.1.0 [INFO] [stderr] Downloaded gitlab-runner v0.0.7 [INFO] [stderr] Downloaded zip v0.6.2 [INFO] [stderr] Downloaded tiny_http v0.6.4 [INFO] [stderr] Downloaded gitlab-runner-mock v0.0.5 [INFO] [stderr] Downloaded rfc822-like v0.2.1 [INFO] [stderr] Downloaded owo-colors v3.2.0 [INFO] [stderr] Downloaded clap v3.1.0 [INFO] [stderr] Downloaded md-5 v0.10.0 [INFO] [stderr] Downloaded async-global-executor v2.0.2 [INFO] [stderr] Downloaded fmt2io v0.2.0 [INFO] [stderr] Downloaded color-eyre v0.6.0 [INFO] [stderr] Downloaded rstest v0.12.0 [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:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-01-12" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 0e66e181e91f8bbd9ed69309a6617bdc35978823e9268df4f12737dfbe3566cc [INFO] running `Command { std: "docker" "start" "-a" "0e66e181e91f8bbd9ed69309a6617bdc35978823e9268df4f12737dfbe3566cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "0e66e181e91f8bbd9ed69309a6617bdc35978823e9268df4f12737dfbe3566cc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0e66e181e91f8bbd9ed69309a6617bdc35978823e9268df4f12737dfbe3566cc", kill_on_drop: false }` [INFO] [stdout] 0e66e181e91f8bbd9ed69309a6617bdc35978823e9268df4f12737dfbe3566cc [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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-01-12" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f416c1aab32e09e435dad325e8f3dbf538ed139adb6abff2b8c6a0b9c4b03539 [INFO] running `Command { std: "docker" "start" "-a" "f416c1aab32e09e435dad325e8f3dbf538ed139adb6abff2b8c6a0b9c4b03539", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.117 [INFO] [stderr] Compiling proc-macro2 v1.0.36 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling syn v1.0.86 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling version_check v0.9.4 [INFO] [stderr] Compiling autocfg v1.0.1 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling pin-project-lite v0.2.8 [INFO] [stderr] Compiling once_cell v1.9.0 [INFO] [stderr] Compiling smallvec v1.8.0 [INFO] [stderr] Compiling pkg-config v0.3.24 [INFO] [stderr] Compiling serde_derive v1.0.136 [INFO] [stderr] Compiling itoa v1.0.1 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling typenum v1.15.0 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling futures-core v0.3.21 [INFO] [stderr] Compiling parking_lot_core v0.8.5 [INFO] [stderr] Compiling scopeguard v1.1.0 [INFO] [stderr] Compiling lock_api v0.4.6 [INFO] [stderr] Compiling tracing-core v0.1.22 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling generic-array v0.14.5 [INFO] [stderr] Compiling futures-task v0.3.21 [INFO] [stderr] Compiling futures-channel v0.3.21 [INFO] [stderr] Compiling futures-sink v0.3.21 [INFO] [stderr] Compiling vcpkg v0.2.15 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling indexmap v1.8.0 [INFO] [stderr] Compiling ryu v1.0.9 [INFO] [stderr] Compiling futures-io v0.3.21 [INFO] [stderr] Compiling slab v0.4.5 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling subtle v2.4.1 [INFO] [stderr] Compiling hashbrown v0.11.2 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling unicase v2.6.0 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling openssl v0.10.66 [INFO] [stderr] Compiling foreign-types-shared v0.1.1 [INFO] [stderr] Compiling http v0.2.6 [INFO] [stderr] Compiling foreign-types v0.3.2 [INFO] [stderr] Compiling miniz_oxide v0.4.4 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling matches v0.1.9 [INFO] [stderr] Compiling bitflags v2.6.0 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling httparse v1.6.0 [INFO] [stderr] Compiling tinyvec v1.5.1 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling quote v1.0.15 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling try-lock v0.2.3 [INFO] [stderr] Compiling percent-encoding v2.1.0 [INFO] [stderr] Compiling rustversion v1.0.6 [INFO] [stderr] Compiling cpufeatures v0.2.1 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling form_urlencoded v1.0.1 [INFO] [stderr] Compiling proc-macro-error-attr v1.0.4 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling mime_guess v2.0.3 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling tower-service v0.3.1 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling zstd-safe v4.1.6+zstd.1.5.2 [INFO] [stderr] Compiling crc32fast v1.3.2 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling getrandom v0.2.4 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling cc v1.0.72 [INFO] [stderr] Compiling parking_lot v0.11.2 [INFO] [stderr] Compiling rand_core v0.6.3 [INFO] [stderr] Compiling httpdate v1.0.2 [INFO] [stderr] Compiling unicode-normalization v0.1.19 [INFO] [stderr] Compiling unicode-bidi v0.3.7 [INFO] [stderr] Compiling encoding_rs v0.8.30 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling unicode-segmentation v1.9.0 [INFO] [stderr] Compiling heck v0.4.0 [INFO] [stderr] Compiling base64ct v1.0.1 [INFO] [stderr] Compiling parking_lot_core v0.9.1 [INFO] [stderr] Compiling mime v0.3.16 [INFO] [stderr] Compiling password-hash v0.3.2 [INFO] [stderr] Compiling ipnet v2.3.1 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling async-trait v0.1.52 [INFO] [stderr] Compiling doc-comment v0.3.3 [INFO] [stderr] Compiling block-buffer v0.10.2 [INFO] [stderr] Compiling crypto-common v0.1.3 [INFO] [stderr] Compiling cipher v0.3.0 [INFO] [stderr] Compiling digest v0.10.3 [INFO] [stderr] Compiling time-macros v0.2.4 [INFO] [stderr] Compiling gimli v0.26.1 [INFO] [stderr] Compiling openssl-sys v0.9.103 [INFO] [stderr] Compiling zstd-sys v1.6.3+zstd.1.5.2 [INFO] [stderr] Compiling bzip2-sys v0.1.11+1.0.8 [INFO] [stderr] Compiling backtrace v0.3.64 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling bitflags v1.2.1 [INFO] [stderr] Compiling eyre v0.6.6 [INFO] [stderr] Compiling sha2 v0.10.2 [INFO] [stderr] Compiling hmac v0.12.1 [INFO] [stderr] Compiling num_threads v0.1.6 [INFO] [stderr] Compiling idna v0.2.3 [INFO] [stderr] Compiling opaque-debug v0.3.0 [INFO] [stderr] Compiling aes v0.7.5 [INFO] [stderr] Compiling pbkdf2 v0.10.1 [INFO] [stderr] Compiling sha1 v0.10.1 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling heck v0.3.3 [INFO] [stderr] Compiling flate2 v1.0.22 [INFO] [stderr] Compiling http-body v0.4.4 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling object v0.27.1 [INFO] [stderr] Compiling owo-colors v3.2.0 [INFO] [stderr] Compiling rustc-demangle v0.1.21 [INFO] [stderr] Compiling remove_dir_all v0.5.3 [INFO] [stderr] Compiling indenter v0.3.3 [INFO] [stderr] Compiling fastrand v1.7.0 [INFO] [stderr] Compiling linked-hash-map v0.5.4 [INFO] [stderr] Compiling camino v1.0.7 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling time v0.3.9 [INFO] [stderr] Compiling constant_time_eq v0.1.5 [INFO] [stderr] Compiling tempfile v3.3.0 [INFO] [stderr] Compiling yaml-rust v0.4.5 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling parking_lot v0.12.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling md-5 v0.10.0 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling termcolor v1.1.2 [INFO] [stderr] Compiling base16ct v0.1.1 [INFO] [stderr] Compiling fmt2io v0.2.0 [INFO] [stderr] Compiling strsim v0.10.0 [INFO] [stderr] Compiling textwrap v0.14.2 [INFO] [stderr] Compiling shellexpand-fork v2.1.1 [INFO] [stderr] Compiling shell-words v1.1.0 [INFO] [stderr] Compiling addr2line v0.17.0 [INFO] [stderr] Compiling bzip2 v0.4.3 [INFO] [stderr] Compiling tokio-macros v1.7.0 [INFO] [stderr] Compiling futures-macro v0.3.21 [INFO] [stderr] Compiling tracing-attributes v0.1.19 [INFO] [stderr] Compiling openssl-macros v0.1.0 [INFO] [stderr] Compiling thiserror-impl v1.0.30 [INFO] [stderr] Compiling pin-project-internal v1.0.10 [INFO] [stderr] Compiling strum_macros v0.24.0 [INFO] [stderr] Compiling tokio v1.16.1 [INFO] [stderr] Compiling clap_derive v3.1.0 [INFO] [stderr] Compiling strum_macros v0.23.1 [INFO] [stderr] Compiling derivative v2.2.0 [INFO] [stderr] Compiling thiserror v1.0.30 [INFO] [stderr] Compiling tracing v0.1.30 [INFO] [stderr] Compiling pin-project v1.0.10 [INFO] [stderr] Compiling strum v0.24.0 [INFO] [stderr] Compiling clap v3.1.0 [INFO] [stderr] Compiling futures-executor v0.3.21 [INFO] [stderr] Compiling futures v0.3.21 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling tokio-util v0.7.0 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling h2 v0.3.11 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling serde_urlencoded v0.7.1 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling quick-xml v0.22.0 [INFO] [stderr] Compiling rfc822-like v0.2.1 [INFO] [stderr] Compiling serde_yaml v0.8.23 [INFO] [stderr] Compiling tracing-subscriber v0.3.8 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling color-spantrace v0.2.0 [INFO] [stderr] Compiling color-eyre v0.6.0 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling zstd v0.10.2+zstd.1.5.2 [INFO] [stderr] Compiling reqwest v0.11.9 [INFO] [stderr] Compiling zip v0.6.2 [INFO] [stderr] Compiling open-build-service-api v0.1.0 (https://github.com/collabora/open-build-service-rs#1bb9a29e) [INFO] [stderr] Compiling gitlab-runner v0.0.7 [INFO] [stderr] Compiling obs-gitlab-runner v0.1.8 (/opt/rustwide/workdir) [INFO] [stdout] warning: field `size` is never read [INFO] [stdout] --> src/dsc.rs:10:9 [INFO] [stdout] | [INFO] [stdout] 8 | pub struct FileEntry { [INFO] [stdout] | --------- field in this struct [INFO] [stdout] 9 | pub hash: String, [INFO] [stdout] 10 | pub size: usize, [INFO] [stdout] | ^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `FileEntry` has a derived impl for the trait `Debug`, but this is intentionally ignored during dead code analysis [INFO] [stdout] = note: `#[warn(dead_code)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 58s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: quick-xml v0.22.0 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 1` [INFO] running `Command { std: "docker" "inspect" "f416c1aab32e09e435dad325e8f3dbf538ed139adb6abff2b8c6a0b9c4b03539", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f416c1aab32e09e435dad325e8f3dbf538ed139adb6abff2b8c6a0b9c4b03539", kill_on_drop: false }` [INFO] [stdout] f416c1aab32e09e435dad325e8f3dbf538ed139adb6abff2b8c6a0b9c4b03539 [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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-01-12" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5cbb2cf9e2298cf14a23ee400730bd38e18995d541fa9e00901b6d7858b4d1fe [INFO] running `Command { std: "docker" "start" "-a" "5cbb2cf9e2298cf14a23ee400730bd38e18995d541fa9e00901b6d7858b4d1fe", kill_on_drop: false }` [INFO] [stderr] Compiling value-bag v1.0.0-alpha.8 [INFO] [stderr] Compiling memchr v2.4.1 [INFO] [stderr] Compiling log v0.4.14 [INFO] [stderr] Compiling autocfg v0.1.7 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling version_check v0.1.5 [INFO] [stderr] Compiling ctor v0.1.21 [INFO] [stderr] Compiling socket2 v0.4.4 [INFO] [stderr] Compiling cache-padded v1.2.0 [INFO] [stderr] Compiling standback v0.2.17 [INFO] [stderr] Compiling waker-fn v1.1.0 [INFO] [stderr] Compiling siphasher v0.2.3 [INFO] [stderr] Compiling parking v2.0.0 [INFO] [stderr] Compiling event-listener v2.5.2 [INFO] [stderr] Compiling proc-macro-hack v0.5.19 [INFO] [stderr] Compiling cipher v0.2.5 [INFO] [stderr] Compiling crossbeam-utils v0.8.8 [INFO] [stderr] Compiling concurrent-queue v1.2.2 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling unicase v1.4.2 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling digest v0.9.0 [INFO] [stderr] Compiling universal-hash v0.4.1 [INFO] [stderr] Compiling traitobject v0.1.0 [INFO] [stderr] Compiling lexical-core v0.7.6 [INFO] [stderr] Compiling async-task v4.1.0 [INFO] [stderr] Compiling cpuid-bool v0.2.0 [INFO] [stderr] Compiling const_fn v0.4.9 [INFO] [stderr] Compiling polyval v0.4.5 [INFO] [stderr] Compiling phf_shared v0.7.24 [INFO] [stderr] Compiling url v2.2.2 [INFO] [stderr] Compiling mime_guess v2.0.3 [INFO] [stderr] Compiling async-channel v1.6.1 [INFO] [stderr] Compiling futures-util v0.3.21 [INFO] [stderr] Compiling futures-lite v1.12.0 [INFO] [stderr] Compiling aes-soft v0.6.4 [INFO] [stderr] Compiling idna v0.1.5 [INFO] [stderr] Compiling crypto-mac v0.10.1 [INFO] [stderr] Compiling time v0.2.27 [INFO] [stderr] Compiling nom v5.1.2 [INFO] [stderr] Compiling percent-encoding v1.0.1 [INFO] [stderr] Compiling atomic-waker v1.0.0 [INFO] [stderr] Compiling static_assertions v1.1.0 [INFO] [stderr] Compiling safemem v0.3.3 [INFO] [stderr] Compiling arrayvec v0.5.2 [INFO] [stderr] Compiling hmac v0.10.1 [INFO] [stderr] Compiling ghash v0.3.1 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling aho-corasick v0.7.18 [INFO] [stderr] Compiling aes v0.6.0 [INFO] [stderr] Compiling phf v0.7.24 [INFO] [stderr] Compiling unsafe-any v0.4.2 [INFO] [stderr] Compiling ctr v0.6.0 [INFO] [stderr] Compiling async-mutex v1.4.0 [INFO] [stderr] Compiling aead v0.3.2 [INFO] [stderr] Compiling block-buffer v0.9.0 [INFO] [stderr] Compiling cookie v0.14.4 [INFO] [stderr] Compiling crossbeam-queue v0.3.4 [INFO] [stderr] Compiling semver v1.0.6 [INFO] [stderr] Compiling anyhow v1.0.53 [INFO] [stderr] Compiling time-macros-impl v0.1.2 [INFO] [stderr] Compiling regex-syntax v0.6.25 [INFO] [stderr] Compiling sha2 v0.9.9 [INFO] [stderr] Compiling aes-gcm v0.8.0 [INFO] [stderr] Compiling async-executor v1.4.1 [INFO] [stderr] Compiling blocking v1.1.0 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling log v0.3.9 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling phf_generator v0.7.24 [INFO] [stderr] Compiling polling v2.2.0 [INFO] [stderr] Compiling url v1.7.2 [INFO] [stderr] Compiling phf_codegen v0.7.24 [INFO] [stderr] Compiling mime v0.2.6 [INFO] [stderr] Compiling native-tls v0.2.8 [INFO] [stderr] Compiling async-io v1.6.0 [INFO] [stderr] Compiling mime_guess v1.8.8 [INFO] [stderr] Compiling kv-log-macro v1.0.7 [INFO] [stderr] Compiling tracing-log v0.1.2 [INFO] [stderr] Compiling tokio v1.16.1 [INFO] [stderr] Compiling typemap v0.3.3 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling hkdf v0.10.0 [INFO] [stderr] Compiling base64 v0.9.3 [INFO] [stderr] Compiling async-lock v2.4.0 [INFO] [stderr] Compiling language-tags v0.2.2 [INFO] [stderr] Compiling async-global-executor v2.0.2 [INFO] [stderr] Compiling http-types v2.12.0 [INFO] [stderr] Compiling typeable v0.1.2 [INFO] [stderr] Compiling tracing-subscriber v0.3.8 [INFO] [stderr] Compiling hyper v0.10.16 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling time-macros v0.1.1 [INFO] [stderr] Compiling async-std v1.10.0 [INFO] [stderr] Compiling plugin v0.2.6 [INFO] [stderr] Compiling serde_qs v0.8.5 [INFO] [stderr] Compiling infer v0.2.3 [INFO] [stderr] Compiling modifier v0.1.0 [INFO] [stderr] Compiling mustache v0.9.0 [INFO] [stderr] Compiling assert-json-diff v2.0.1 [INFO] [stderr] Compiling rustc_version v0.4.0 [INFO] [stderr] Compiling groupable v0.2.0 [INFO] [stderr] Compiling chunked_transfer v0.3.1 [INFO] [stderr] Compiling ascii v0.8.7 [INFO] [stderr] Compiling futures-timer v3.0.2 [INFO] [stderr] Compiling rstest v0.12.0 [INFO] [stderr] Compiling tiny_http v0.6.4 [INFO] [stderr] Compiling buf_redux v0.8.4 [INFO] [stderr] Compiling twoway v0.1.8 [INFO] [stderr] Compiling object v0.27.1 [INFO] [stderr] Compiling tracing-error v0.2.0 [INFO] [stderr] Compiling quick-xml v0.22.0 [INFO] [stderr] Compiling claim v0.5.0 [INFO] [stderr] Compiling quick-error v1.2.3 [INFO] [stderr] Compiling color-spantrace v0.2.0 [INFO] [stderr] Compiling os_str_bytes v6.0.0 [INFO] [stderr] Compiling strum v0.23.0 [INFO] [stderr] Compiling xml-builder v0.5.0 [INFO] [stderr] Compiling clap v3.1.0 [INFO] [stderr] Compiling futures-executor v0.3.21 [INFO] [stderr] Compiling regex v1.5.4 [INFO] [stderr] Compiling futures v0.3.21 [INFO] [stderr] Compiling iron v0.6.1 [INFO] [stderr] Compiling zip v0.5.13 [INFO] [stderr] Compiling config v0.10.1 [INFO] [stderr] Compiling nickel v0.11.0 [INFO] [stderr] Compiling multipart v0.18.0 [INFO] [stderr] Compiling tokio-util v0.6.9 [INFO] [stderr] Compiling tokio-native-tls v0.3.0 [INFO] [stderr] Compiling deadpool v0.7.0 [INFO] [stderr] Compiling tokio-util v0.7.0 [INFO] [stderr] Compiling backoff v0.4.0 [INFO] [stderr] Compiling backtrace v0.3.64 [INFO] [stderr] Compiling h2 v0.3.11 [INFO] [stderr] Compiling color-eyre v0.6.0 [INFO] [stderr] Compiling hyper v0.14.17 [INFO] [stderr] Compiling hyper-tls v0.5.0 [INFO] [stderr] Compiling wiremock v0.5.10 [INFO] [stderr] Compiling reqwest v0.11.9 [INFO] [stderr] Compiling open-build-service-mock v0.1.0 (https://github.com/collabora/open-build-service-rs#1bb9a29e) [INFO] [stderr] Compiling gitlab-runner-mock v0.0.5 [INFO] [stderr] Compiling open-build-service-api v0.1.0 (https://github.com/collabora/open-build-service-rs#1bb9a29e) [INFO] [stderr] Compiling gitlab-runner v0.0.7 [INFO] [stderr] Compiling obs-gitlab-runner v0.1.8 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 50s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: buf_redux v0.8.4, multipart v0.18.0, nom v5.1.2, quick-xml v0.22.0, traitobject v0.1.0, typemap v0.3.3 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 2` [INFO] running `Command { std: "docker" "inspect" "5cbb2cf9e2298cf14a23ee400730bd38e18995d541fa9e00901b6d7858b4d1fe", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5cbb2cf9e2298cf14a23ee400730bd38e18995d541fa9e00901b6d7858b4d1fe", kill_on_drop: false }` [INFO] [stdout] 5cbb2cf9e2298cf14a23ee400730bd38e18995d541fa9e00901b6d7858b4d1fe [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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:86ea7c7af713d31e8cfdb68a6d0db50b5cf7cbeecde3d112f9f257f747318d36" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-01-12" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 51e4d31daaa12c67398214c80d3b6efef98f4b3d3a464a062063164d64d65338 [INFO] running `Command { std: "docker" "start" "-a" "51e4d31daaa12c67398214c80d3b6efef98f4b3d3a464a062063164d64d65338", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 1.05s [INFO] [stderr] warning: the following packages contain code that will be rejected by a future version of Rust: buf_redux v0.8.4, multipart v0.18.0, nom v5.1.2, quick-xml v0.22.0, traitobject v0.1.0, typemap v0.3.3 [INFO] [stderr] note: to see what the problems were, use the option `--future-incompat-report`, or run `cargo report future-incompatibilities --id 2` [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/obs_gitlab_runner-53f24de150e4dd8a) [INFO] [stdout] [INFO] [stdout] running 122 tests [INFO] [stdout] test dsc::tests::test_de ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_repos ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_1::dirty_1 ... ok [INFO] [stdout] test handler::tests::test_generate_monitor_timeouts::test_3 ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_2::dirty_2 ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_2::dirty_1 ... ok [INFO] [stdout] test binaries::tests::test_build_results ... ok [INFO] [stdout] test handler::tests::test_generate_monitor_timeouts::test_2 ... ok [INFO] [stdout] test handler::tests::test_generate_monitor_timeouts::test_1 ... ok [INFO] [stdout] test build_meta::tests::test_build_meta_ignores_empty::skip_early_1::dirty_2 ... ok [INFO] [stdout] test handler::tests::test_flag_parsing ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... FAILED [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_1::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_2::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_3::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test monitor::tests::test_download_log ... ok [INFO] [stdout] test handler::tests::test_variable_expansion ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_1::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test monitor::tests::test_handles_old_build_status ... ok [INFO] [stdout] test monitor::tests::test_latest_state ... ok [INFO] [stdout] test monitor::tests::test_fails_after_repeated_duplicate_endtimes ... ok [INFO] [stdout] test monitor::tests::test_handles_missing_status ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test retry::tests::test_no_retry_on_client_errors ... ok [INFO] [stdout] test monitor::tests::test_srcmd5_checks ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test retry::tests::test_no_retry_on_nested_client_errors ... ok [INFO] [stdout] test prune::tests::test_prune ... ok [INFO] [stdout] test upload::tests::test_create_list_package ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test upload::tests::test_commit ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_1::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test upload::tests::test_upload ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_2::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_1::prune_only_if_job_unsuccessful_1 ... ok [INFO] [stdout] test retry::tests::test_retry_on_nested_non_client_errors ... ok [INFO] [stdout] test retry::tests::test_retry_on_non_client_errors ... ok [INFO] [stdout] test handler::tests::test_handler_flow::dput_test_4::build_success_2::log_test_3::download_binaries_2::prune_only_if_job_unsuccessful_2 ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 stdout ---- [INFO] [stdout] -------------- TEST START -------------- [INFO] [stdout] 2025-01-15T12:53:52.414295Z TRACE command{cmdline="dput foo test1-bad.dsc"}: obs_gitlab_runner::handler: > dput foo test1-bad.dsc gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:52.463195Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-01-15T12:53:52.502556Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-01-15T12:53:52.542998Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(38181), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }, RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-01-15T12:53:52.543207Z TRACE command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1-bad.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:52.575499Z TRACE command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"test1.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"} present_files={} [INFO] [stdout] 2025-01-15T12:53:52.582209Z TRACE command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"}}: obs_gitlab_runner::upload: Retry commit, missing 2 entries [INFO] [stdout] 2025-01-15T12:53:52.582296Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"}}: obs_gitlab_runner::upload: missing=MissingEntries { entries: [CommitEntry { name: "test1.missing", md5: "202cb962ac59075b964b07152d234b70" }, CommitEntry { name: "test1-bad.dsc", md5: "c09a28e451b8586a98f614f9fe0742de" }] } [INFO] [stdout] 2025-01-15T12:53:52.582393Z DEBUG command{cmdline="dput foo test1-bad.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1-bad.dsc" root="" files={"test1.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"}}:upload_file{root="" filename="test1.missing"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-01-15T12:53:52.604584Z ERROR obs_gitlab_runner::handler: Error running command: [INFO] [stdout] 0: Could not find artifact 'test1.missing' [INFO] [stdout] [INFO] [stdout] Location: [INFO] [stdout] src/artifacts.rs:34 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ SPANTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout] [INFO] [stdout] 0: obs_gitlab_runner::upload::upload_file with root="" filename="test1.missing" [INFO] [stdout] at src/upload.rs:223 [INFO] [stdout] 221 │ } [INFO] [stdout] 222 │ [INFO] [stdout]  223 > #[instrument(skip(self, artifacts))] [INFO] [stdout] 224 │ async fn upload_file( [INFO] [stdout] 225 │ &self, [INFO] [stdout] 1: obs_gitlab_runner::upload::commit with commit_message="test1-bad.dsc" root="" files={"test1.missing": "202cb962ac59075b964b07152d234b70", "_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1-bad.dsc": "c09a28e451b8586a98f614f9fe0742de"} [INFO] [stdout] at src/upload.rs:250 [INFO] [stdout] 248 │ } [INFO] [stdout] 249 │ [INFO] [stdout]  250 > #[instrument(skip(self, artifacts))] [INFO] [stdout] 251 │ async fn commit( [INFO] [stdout] 252 │ &self, [INFO] [stdout] 2: obs_gitlab_runner::upload::upload_package with self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1-bad.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1.missing" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } } [INFO] [stdout] at src/upload.rs:307 [INFO] [stdout] 305 │ } [INFO] [stdout] 306 │ [INFO] [stdout]  307 > #[instrument(skip(artifacts))] [INFO] [stdout] 308 │ pub async fn upload_package(&self, artifacts: &impl ArtifactDirectory) -> Result { [INFO] [stdout] 309 │ let dsc_parent = self [INFO] [stdout] 3: obs_gitlab_runner::handler::run_dput with args=DputAction { project: "foo", dsc: "test1-bad.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false } [INFO] [stdout] at src/handler.rs:275 [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ [INFO] [stdout]  275 > #[instrument(skip(self))] [INFO] [stdout] 276 │ async fn run_dput(&mut self, args: DputAction) -> Result<()> { [INFO] [stdout] 277 │ let branch_to = if !args.branch_to.is_empty() { [INFO] [stdout] 4: obs_gitlab_runner::handler::command with cmdline="dput foo test1-bad.dsc" [INFO] [stdout] at src/handler.rs:576 [INFO] [stdout] 574 │ } [INFO] [stdout] 575 │ [INFO] [stdout]  576 > #[instrument(skip(self))] [INFO] [stdout] 577 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 578 │ // TODO: inject user? [INFO] [stdout] 5: gitlab_runner::run::run with gitlab.job=267 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/gitlab-runner-0.0.7/src/run.rs:172 [INFO] [stdout] 170 │ } [INFO] [stdout] 171 │ [INFO] [stdout]  172 > #[tracing::instrument(skip(self, process,build_dir),fields(gitlab.job=self.response.id))] [INFO] [stdout] 173 │ pub(crate) async fn run(&mut self, process: F, build_dir: PathBuf) [INFO] [stdout] 174 │ where [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 5 frames hidden ⋮  [INFO] [stdout] 6: obs_gitlab_runner::artifacts::ArtifactDirectory::get::{{closure}}::{{closure}}::h904f917024d4022e [INFO] [stdout] at /opt/rustwide/workdir/src/artifacts.rs:34 [INFO] [stdout] 32 │ self.get_or_none(filename) [INFO] [stdout] 33 │ .await? [INFO] [stdout]  34 >  .ok_or_else(|| eyre!("Could not find artifact '{}'", filename)) [INFO] [stdout] 35 │ } [INFO] [stdout] 36 │ [INFO] [stdout] 7: core::option::Option::ok_or_else::h26e19d611f30867e [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/option.rs:1283 [INFO] [stdout] 8: obs_gitlab_runner::artifacts::ArtifactDirectory::get::{{closure}}::h9e700339dc346d74 [INFO] [stdout] at /opt/rustwide/workdir/src/artifacts.rs:32 [INFO] [stdout] 30 │ [INFO] [stdout] 31 │ async fn get(&self, filename: &str) -> Result { [INFO] [stdout]  32 >  self.get_or_none(filename) [INFO] [stdout] 33 │ .await? [INFO] [stdout] 34 │ .ok_or_else(|| eyre!("Could not find artifact '{}'", filename)) [INFO] [stdout] 9:  as core::future::future::Future>::poll::hc4df7b429e00f6a4 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/future/future.rs:124 [INFO] [stdout] 10: obs_gitlab_runner::artifacts::ArtifactDirectory::get_file::{{closure}}::h2e463e00464dee29 [INFO] [stdout] at /opt/rustwide/workdir/src/artifacts.rs:47 [INFO] [stdout] 45 │ [INFO] [stdout] 46 │ async fn get_file(&self, filename: &str) -> Result { [INFO] [stdout]  47 >  let reader = self.get(filename).await?; [INFO] [stdout] 48 │ async_save_to_tempfile(reader).await [INFO] [stdout] 49 │ } [INFO] [stdout] 11:  as core::future::future::Future>::poll::h70cd0e2d163b1186 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/future/future.rs:124 [INFO] [stdout] 12: obs_gitlab_runner::upload::ObsDscUploader::upload_file::{{closure}}::{{closure}}::hafbfa2045626c799 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:231 [INFO] [stdout] 229 │ ) -> Result<()> { [INFO] [stdout] 230 │ debug!("Uploading file"); [INFO] [stdout]  231 >  let file = artifacts.get_file(root.join(filename).as_str()).await?; [INFO] [stdout] 232 │ [INFO] [stdout] 233 │ retry_request(|| { [INFO] [stdout] 13:  as core::future::future::Future>::poll::hc068445f8bea426f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 14: obs_gitlab_runner::upload::ObsDscUploader::upload_file::{{closure}}::h829265916aa54f80 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:223 [INFO] [stdout] 221 │ } [INFO] [stdout] 222 │ [INFO] [stdout]  223 >  #[instrument(skip(self, artifacts))] [INFO] [stdout] 224 │ async fn upload_file( [INFO] [stdout] 225 │ &self, [INFO] [stdout] 15: obs_gitlab_runner::upload::ObsDscUploader::commit::{{closure}}::{{closure}}::ha27fd974315b0ea8 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:300 [INFO] [stdout] 298 │ [INFO] [stdout] 299 │ for entry in missing.entries { [INFO] [stdout]  300 >  self.upload_file(root, &entry.name, artifacts).await?; [INFO] [stdout] 301 │ } [INFO] [stdout] 302 │ } [INFO] [stdout] 16:  as core::future::future::Future>::poll::hf928c90fd7fd9839 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 17: obs_gitlab_runner::upload::ObsDscUploader::commit::{{closure}}::h62fa12c907eaab0f [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:250 [INFO] [stdout] 248 │ } [INFO] [stdout] 249 │ [INFO] [stdout]  250 >  #[instrument(skip(self, artifacts))] [INFO] [stdout] 251 │ async fn commit( [INFO] [stdout] 252 │ &self, [INFO] [stdout] 18: obs_gitlab_runner::upload::ObsDscUploader::upload_package::{{closure}}::{{closure}}::hfccbb88d4600cbbe [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:347 [INFO] [stdout] 345 │ ( [INFO] [stdout] 346 │ self.commit(dsc_filename, dsc_parent, files_to_commit, artifacts) [INFO] [stdout]  347 >  .await?, [INFO] [stdout] 348 │ false, [INFO] [stdout] 349 │ ) [INFO] [stdout] 19:  as core::future::future::Future>::poll::h7a363c7ca0522e11 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 20: obs_gitlab_runner::upload::ObsDscUploader::upload_package::{{closure}}::h14c3a731f6cfb5a5 [INFO] [stdout] at /opt/rustwide/workdir/src/upload.rs:307 [INFO] [stdout] 305 │ } [INFO] [stdout] 306 │ [INFO] [stdout]  307 >  #[instrument(skip(artifacts))] [INFO] [stdout] 308 │ pub async fn upload_package(&self, artifacts: &impl ArtifactDirectory) -> Result { [INFO] [stdout] 309 │ let dsc_parent = self [INFO] [stdout] 21: obs_gitlab_runner::handler::ObsJobHandler::run_dput::{{closure}}::{{closure}}::h2787acfd770ce05b [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:329 [INFO] [stdout] 327 │ debug!(?initial_build_meta); [INFO] [stdout] 328 │ [INFO] [stdout]  329 >  let result = uploader.upload_package(self).await?; [INFO] [stdout] 330 │ [INFO] [stdout] 331 │ // If we couldn't get the metadata before because the package didn't [INFO] [stdout] 22:  as core::future::future::Future>::poll::h2e2d4717c93c9048 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 23: obs_gitlab_runner::handler::ObsJobHandler::run_dput::{{closure}}::h2abbdfe071832f01 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:275 [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ [INFO] [stdout]  275 >  #[instrument(skip(self))] [INFO] [stdout] 276 │ async fn run_dput(&mut self, args: DputAction) -> Result<()> { [INFO] [stdout] 277 │ let branch_to = if !args.branch_to.is_empty() { [INFO] [stdout] 24: obs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::{{closure}}::h4670574fe675d0a3 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:587 [INFO] [stdout] 585 │ [INFO] [stdout] 586 │ match command.action { [INFO] [stdout]  587 >  Action::Dput(args) => self.run_dput(args).await?, [INFO] [stdout] 588 │ Action::GenerateMonitor(args) => self.run_generate_monitor(args).await?, [INFO] [stdout] 589 │ Action::Monitor(args) => self.run_monitor(args).await?, [INFO] [stdout] 25:  as core::future::future::Future>::poll::h0bb3852a7984554a [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 26: obs_gitlab_runner::handler::ObsJobHandler::command::{{closure}}::h8fe74a656904b5d6 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:576 [INFO] [stdout] 574 │ } [INFO] [stdout] 575 │ [INFO] [stdout]  576 >  #[instrument(skip(self))] [INFO] [stdout] 577 │ async fn command(&mut self, cmdline: &str) -> Result<()> { [INFO] [stdout] 578 │ // TODO: inject user? [INFO] [stdout] 27: ::step::{{closure}}::heaaccb49d4ced8d7 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:630 [INFO] [stdout] 628 │ async fn step(&mut self, script: &[String], _phase: Phase) -> JobResult { [INFO] [stdout] 629 │ for command in script { [INFO] [stdout]  630 >  if let Err(err) = self.command(command).await { [INFO] [stdout] 631 │ // Failed builds would already have information on them printed [INFO] [stdout] 632 │ // above, so don't print anything on them again. [INFO] [stdout] 28:  as core::future::future::Future>::poll::h085133f38f6031af [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/future/future.rs:124 [INFO] [stdout] 29: ::step::{{closure}}::{{closure}}::h245b95f477bc72bd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/macros/select.rs:505 [INFO] [stdout] 503 │ [INFO] [stdout] 504 │ // Try polling it [INFO] [stdout]  505 >  let out = match Future::poll(fut, cx) { [INFO] [stdout] 506 │ Ready(out) => out, [INFO] [stdout] 507 │ Pending => { [INFO] [stdout] 30:  as core::future::future::Future>::poll::hcf19be1c9960f4dc [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/future/poll_fn.rs:38 [INFO] [stdout] 36 │ [INFO] [stdout] 37 │ fn poll(mut self: Pin<&mut Self>, cx: &mut Context<'_>) -> Poll { [INFO] [stdout]  38 >  (&mut self.f)(cx) [INFO] [stdout] 39 │ } [INFO] [stdout] 40 │ } [INFO] [stdout] 31: ::step::{{closure}}::h1e77e158e43c844f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/gitlab-runner-0.0.7/src/lib.rs:197 [INFO] [stdout] 195 │ cancel_token: &CancellationToken, [INFO] [stdout] 196 │ ) -> JobResult { [INFO] [stdout]  197 >  tokio::select! { [INFO] [stdout] 198 │ r = self.step(script, phase) => r, [INFO] [stdout] 199 │ _ = cancel_token.cancelled() => Ok(()), [INFO] [stdout] 32:  as core::future::future::Future>::poll::h085133f38f6031af [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/future/future.rs:124 [INFO] [stdout] 33: gitlab_runner::run::run::{{closure}}::h1858ac64138d7e2d [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/gitlab-runner-0.0.7/src/run.rs:41 [INFO] [stdout] 39 │ let script_result = handler [INFO] [stdout] 40 │ .step(&script.script, Phase::Script, &cancel_token) [INFO] [stdout]  41 >  .await; [INFO] [stdout] 42 │ [INFO] [stdout] 43 │ if !cancel_token.is_cancelled() { [INFO] [stdout] 34:  as core::future::future::Future>::poll::h268e29eaeb8f7543 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:272 [INFO] [stdout] 270 │ let this = self.project(); [INFO] [stdout] 271 │ let _enter = this.span.enter(); [INFO] [stdout]  272 >  this.inner.poll(cx) [INFO] [stdout] 273 │ } [INFO] [stdout] 274 │ } [INFO] [stdout] 35:  as core::future::future::Future>::poll::h687af6393d0307cf [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:329 [INFO] [stdout] 327 │ let future = this.inner; [INFO] [stdout] 328 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout]  329 >  future.poll(cx) [INFO] [stdout] 330 │ } [INFO] [stdout] 331 │ } [INFO] [stdout] 36:  as core::future::future::Future>::poll::h6ebc25680857b1f4 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/future/future.rs:124 [INFO] [stdout] 37: tokio::runtime::task::core::CoreStage::poll::{{closure}}::h1cd89cb18ff9866e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/core.rs:161 [INFO] [stdout] 159 │ let future = unsafe { Pin::new_unchecked(future) }; [INFO] [stdout] 160 │ [INFO] [stdout]  161 >  future.poll(&mut cx) [INFO] [stdout] 162 │ }) [INFO] [stdout] 163 │ }; [INFO] [stdout] 38: tokio::loom::std::unsafe_cell::UnsafeCell::with_mut::hf6521df288b6023f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/loom/std/unsafe_cell.rs:14 [INFO] [stdout] 12 │ [INFO] [stdout] 13 │ pub(crate) fn with_mut(&self, f: impl FnOnce(*mut T) -> R) -> R { [INFO] [stdout]  14 >  f(self.0.get()) [INFO] [stdout] 15 │ } [INFO] [stdout] 16 │ } [INFO] [stdout] 39: tokio::runtime::task::core::CoreStage::poll::h846066c900961a17 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/core.rs:151 [INFO] [stdout] 149 │ pub(super) fn poll(&self, mut cx: Context<'_>) -> Poll { [INFO] [stdout] 150 │ let res = { [INFO] [stdout]  151 >  self.stage.with_mut(|ptr| { [INFO] [stdout] 152 │ // Safety: The caller ensures mutual exclusion to the field. [INFO] [stdout] 153 │ let future = match unsafe { &mut *ptr } { [INFO] [stdout] 40: tokio::runtime::task::harness::poll_future::{{closure}}::h13c5b8ca0b505eda [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/harness.rs:461 [INFO] [stdout] 459 │ } [INFO] [stdout] 460 │ let guard = Guard { core }; [INFO] [stdout]  461 >  let res = guard.core.poll(cx); [INFO] [stdout] 462 │ mem::forget(guard); [INFO] [stdout] 463 │ res [INFO] [stdout] 41:  as core::ops::function::FnOnce<()>>::call_once::h2b27d59044f17230 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 42: std::panicking::try::do_call::h6cb22ceaf7b2b9a2 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:584 [INFO] [stdout] 43: __rust_try [INFO] [stdout] at : [INFO] [stdout] 44: std::panicking::try::h703a5a89a0aab0a5 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:547 [INFO] [stdout] 45: std::panic::catch_unwind::h9d93a1a65f3d4aea [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panic.rs:358 [INFO] [stdout] 46: tokio::runtime::task::harness::poll_future::h34fc86524c4d5325 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/harness.rs:449 [INFO] [stdout] 447 │ fn poll_future(core: &CoreStage, cx: Context<'_>) -> Poll<()> { [INFO] [stdout] 448 │ // Poll the future. [INFO] [stdout]  449 >  let output = panic::catch_unwind(panic::AssertUnwindSafe(|| { [INFO] [stdout] 450 │ struct Guard<'a, T: Future> { [INFO] [stdout] 451 │ core: &'a CoreStage, [INFO] [stdout] 47: tokio::runtime::task::harness::Harness::poll_inner::hd152b6d6ea688d5b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/harness.rs:98 [INFO] [stdout] 96 │ let waker_ref = waker_ref::(self.header()); [INFO] [stdout] 97 │ let cx = Context::from_waker(&*waker_ref); [INFO] [stdout]  98 >  let res = poll_future(&self.core().stage, cx); [INFO] [stdout] 99 │ [INFO] [stdout] 100 │ if res == Poll::Ready(()) { [INFO] [stdout] 48: tokio::runtime::task::harness::Harness::poll::h3e0b4c929c6c648e [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/harness.rs:53 [INFO] [stdout] 51 │ pub(super) fn poll(self) { [INFO] [stdout] 52 │ // We pass our ref-count to `poll_inner`. [INFO] [stdout]  53 >  match self.poll_inner() { [INFO] [stdout] 54 │ PollFuture::Notified => { [INFO] [stdout] 55 │ // The `poll_inner` call has given us two ref-counts back. [INFO] [stdout] 49: tokio::runtime::task::raw::poll::h66dba02c0ebcd596 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/raw.rs:113 [INFO] [stdout] 111 │ unsafe fn poll(ptr: NonNull
) { [INFO] [stdout] 112 │ let harness = Harness::::from_raw(ptr); [INFO] [stdout]  113 >  harness.poll(); [INFO] [stdout] 114 │ } [INFO] [stdout] 115 │ [INFO] [stdout] 50: tokio::runtime::task::raw::RawTask::poll::hc286cea0f076b93a [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/raw.rs:70 [INFO] [stdout] 68 │ pub(super) fn poll(self) { [INFO] [stdout] 69 │ let vtable = self.header().vtable; [INFO] [stdout]  70 >  unsafe { (vtable.poll)(self.ptr) } [INFO] [stdout] 71 │ } [INFO] [stdout] 72 │ [INFO] [stdout] 51: tokio::runtime::task::LocalNotified::run::h67357402a5ef95a5 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/task/mod.rs:343 [INFO] [stdout] 341 │ let raw = self.task.raw; [INFO] [stdout] 342 │ mem::forget(self); [INFO] [stdout]  343 >  raw.poll(); [INFO] [stdout] 344 │ } [INFO] [stdout] 345 │ } [INFO] [stdout] 52: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::ha71b5ba45d0761b1 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:557 [INFO] [stdout] 555 │ [INFO] [stdout] 556 │ let (c, _) = context.run_task(core, || { [INFO] [stdout]  557 >  task.run(); [INFO] [stdout] 558 │ }); [INFO] [stdout] 559 │ [INFO] [stdout] 53: tokio::coop::with_budget::{{closure}}::h5c09d916abf4e119 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/coop.rs:102 [INFO] [stdout] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stdout] 101 │ [INFO] [stdout]  102 >  f() [INFO] [stdout] 103 │ }) [INFO] [stdout] 104 │ } [INFO] [stdout] 54: std::thread::local::LocalKey::try_with::hac50353a6cceed3b [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/local.rs:308 [INFO] [stdout] 55: std::thread::local::LocalKey::with::h3c80b8f25ce72bfd [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/local.rs:272 [INFO] [stdout] 56: tokio::coop::with_budget::h8a8ae6be02c1f954 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/coop.rs:95 [INFO] [stdout] 93 │ } [INFO] [stdout] 94 │ [INFO] [stdout]  95 >  CURRENT.with(move |cell| { [INFO] [stdout] 96 │ let prev = cell.get(); [INFO] [stdout] 97 │ [INFO] [stdout] 57: tokio::coop::budget::h2e819bd70f5f2b62 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/coop.rs:72 [INFO] [stdout] 70 │ #[inline(always)] [INFO] [stdout] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  72 >  with_budget(Budget::initial(), f) [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 58: tokio::runtime::basic_scheduler::Context::run_task::{{closure}}::hd8a98f0aa8183cfd [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:309 [INFO] [stdout] 307 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 308 │ core.metrics.incr_poll_count(); [INFO] [stdout]  309 >  self.enter(core, || crate::coop::budget(f)) [INFO] [stdout] 310 │ } [INFO] [stdout] 311 │ [INFO] [stdout] 59: tokio::runtime::basic_scheduler::Context::enter::hb96d99269bc43d3f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:374 [INFO] [stdout] 372 │ [INFO] [stdout] 373 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  374 >  let ret = f(); [INFO] [stdout] 375 │ [INFO] [stdout] 376 │ // Take the scheduler core back [INFO] [stdout] 60: tokio::runtime::basic_scheduler::Context::run_task::h36d63170f6b37b20 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:309 [INFO] [stdout] 307 │ fn run_task(&self, mut core: Box, f: impl FnOnce() -> R) -> (Box, R) { [INFO] [stdout] 308 │ core.metrics.incr_poll_count(); [INFO] [stdout]  309 >  self.enter(core, || crate::coop::budget(f)) [INFO] [stdout] 310 │ } [INFO] [stdout] 311 │ [INFO] [stdout] 61: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::h5398ed9186e838e2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:556 [INFO] [stdout] 554 │ let task = context.spawner.shared.owned.assert_owner(task); [INFO] [stdout] 555 │ [INFO] [stdout]  556 >  let (c, _) = context.run_task(core, || { [INFO] [stdout] 557 │ task.run(); [INFO] [stdout] 558 │ }); [INFO] [stdout] 62: tokio::runtime::basic_scheduler::CoreGuard::enter::{{closure}}::h9a762b55468ae78c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 63: tokio::macros::scoped_tls::ScopedKey::set::h96a1f43bdbf64631 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/macros/scoped_tls.rs:61 [INFO] [stdout] 59 │ }; [INFO] [stdout] 60 │ [INFO] [stdout]  61 >  f() [INFO] [stdout] 62 │ } [INFO] [stdout] 63 │ [INFO] [stdout] 64: tokio::runtime::basic_scheduler::CoreGuard::enter::h18a40979e01e6381 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 65: tokio::runtime::basic_scheduler::CoreGuard::block_on::h6a47a6862fdc70c8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:506 [INFO] [stdout] 504 │ impl CoreGuard<'_> { [INFO] [stdout] 505 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  506 >  self.enter(|mut core, context| { [INFO] [stdout] 507 │ let _enter = crate::runtime::enter(false); [INFO] [stdout] 508 │ let waker = context.spawner.waker_ref(); [INFO] [stdout] 66: tokio::runtime::basic_scheduler::BasicScheduler::block_on::hcf6bc9af283bc121 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:182 [INFO] [stdout] 180 │ loop { [INFO] [stdout] 181 │ if let Some(core) = self.take_core() { [INFO] [stdout]  182 >  return core.block_on(future); [INFO] [stdout] 183 │ } else { [INFO] [stdout] 184 │ let mut enter = crate::runtime::enter(false); [INFO] [stdout] 67: tokio::runtime::Runtime::block_on::h213dd1c71237c7ed [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/mod.rs:475 [INFO] [stdout] 473 │ [INFO] [stdout] 474 │ match &self.kind { [INFO] [stdout]  475 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stdout] 476 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 477 │ Kind::ThreadPool(exec) => exec.block_on(future), [INFO] [stdout] 68: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2::hc4fc89fa5ae4a72a [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1693 [INFO] [stdout] 1691 │ } [INFO] [stdout] 1692 │ [INFO] [stdout]  1693 >  #[rstest] [INFO] [stdout] 1694 │ #[tokio::test] [INFO] [stdout] 1695 │ async fn test_handler_flow( [INFO] [stdout] 69: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::ha4ed7efa1f363740 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1693 [INFO] [stdout] 1691 │ } [INFO] [stdout] 1692 │ [INFO] [stdout]  1693 >  #[rstest] [INFO] [stdout] 1694 │ #[tokio::test] [INFO] [stdout] 1695 │ async fn test_handler_flow( [INFO] [stdout] 70: core::ops::function::FnOnce::call_once::hde00bce13c2874a1 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/ops/function.rs:250 [INFO] [stdout] 71: core::ops::function::FnOnce::call_once::h42df3dc9a56fb705 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/ops/function.rs:250 [INFO] [stdout] 72: test::__rust_begin_short_backtrace::hf2ff4d4d12dd75b0 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:632 [INFO] [stdout] 73: test::run_test_in_process::{{closure}}::h8461a23c6c0ac10c [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:655 [INFO] [stdout] 74:  as core::ops::function::FnOnce<()>>::call_once::hefab1bfcb42baf2e [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 75: std::panicking::try::do_call::h75c82cb1133d19f1 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:584 [INFO] [stdout] 76: std::panicking::try::h41d68fc63841231a [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:547 [INFO] [stdout] 77: std::panic::catch_unwind::h7a7ff6adf9e0c698 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panic.rs:358 [INFO] [stdout] 78: test::run_test_in_process::hcd527530689ec177 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:655 [INFO] [stdout] 79: test::run_test::{{closure}}::h51ba581aac56ca21 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:576 [INFO] [stdout] 80: test::run_test::{{closure}}::h9d0d2581742c4e6c [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:606 [INFO] [stdout] 81: std::sys::backtrace::__rust_begin_short_backtrace::h94b953e0285eca69 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/sys/backtrace.rs:152 [INFO] [stdout] 82: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hac891bfe52f0501a [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/mod.rs:564 [INFO] [stdout] 83:  as core::ops::function::FnOnce<()>>::call_once::hd4f76b3ef0615394 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 84: std::panicking::try::do_call::h31b1a419f4fa670c [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:584 [INFO] [stdout] 85: std::panicking::try::h277e348adef7f0af [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:547 [INFO] [stdout] 86: std::panic::catch_unwind::h06db5059e51a109d [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panic.rs:358 [INFO] [stdout] 87: std::thread::Builder::spawn_unchecked_::{{closure}}::h048aed1b5c822396 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/mod.rs:562 [INFO] [stdout] 88: core::ops::function::FnOnce::call_once{{vtable.shim}}::h9f03098da71d0b51 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/ops/function.rs:250 [INFO] [stdout] 89:  as core::ops::function::FnOnce>::call_once::hcc221cc1d8cbbdc7 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/alloc/src/boxed.rs:1993 [INFO] [stdout] 90:  as core::ops::function::FnOnce>::call_once::he5e5264a0dcd6e5f [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/alloc/src/boxed.rs:1993 [INFO] [stdout] 91: std::sys::pal::unix::thread::Thread::new::thread_start::h307a072356a0dfca [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/sys/pal/unix/thread.rs:106 [INFO] [stdout] 92: clone [INFO] [stdout] at : [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:53.611952Z TRACE command{cmdline="dput foo test1.dsc"}: obs_gitlab_runner::handler: > dput foo test1.dsc gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:53.654298Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-01-15T12:53:53.656768Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-01-15T12:53:53.677357Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(38181), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [] }, RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-01-15T12:53:53.677524Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:53.699983Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"} present_files={} [INFO] [stdout] 2025-01-15T12:53:53.710830Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"}}: obs_gitlab_runner::upload: Retry commit, missing 2 entries [INFO] [stdout] 2025-01-15T12:53:53.710925Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"}}: obs_gitlab_runner::upload: missing=MissingEntries { entries: [CommitEntry { name: "test1", md5: "202cb962ac59075b964b07152d234b70" }, CommitEntry { name: "test1.dsc", md5: "4c85fb7ae65cc69676f0d043082779e7" }] } [INFO] [stdout] 2025-01-15T12:53:53.711083Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"}}:upload_file{root="" filename="test1"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-01-15T12:53:53.741041Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}:commit{commit_message="test1.dsc" root="" files={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"}}:upload_file{root="" filename="test1.dsc"}: obs_gitlab_runner::upload: Uploading file [INFO] [stdout] 2025-01-15T12:53:53.800860Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="aarch64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="aarch64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Unknown, dirty: false, details: Some("") } [INFO] [stdout] 2025-01-15T12:53:53.806436Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="x86_64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="x86_64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: Some("") } [INFO] [stdout] 2025-01-15T12:53:53.806535Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::build_meta: Disabling repo=repo repo_arch.arch=x86_64 [INFO] [stdout] 2025-01-15T12:53:53.806592Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Package uploaded with revision 1. gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:53.806670Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving complete build info: ObsBuildInfo { project: "foo", package: "bar", rev: Some("1"), srcmd5: Some("062dc3e152164165ec489ea21e7bd68e"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: None }} } [INFO] [stdout] 2025-01-15T12:53:54.415242Z TRACE command{cmdline="dput foo test1.dsc"}: obs_gitlab_runner::handler: > dput foo test1.dsc gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:54.441212Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-01-15T12:53:54.456761Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-01-15T12:53:54.477706Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(38181), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [JobHist { package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", versrel: "1.0-1", bcnt: "1", readytime: 1736945633, starttime: 1736945633, endtime: 1736945633, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "7be7fb7142d88bfa318ea9003fd21293" }] }, RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-01-15T12:53:54.477885Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:54.526664Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"} present_files={"test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7", "_meta": "f4f75daf7cd22556e6bef525a11ed94f"} [INFO] [stdout] 2025-01-15T12:53:54.551811Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="aarch64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="aarch64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Failed, dirty: false, details: Some("") } [INFO] [stdout] 2025-01-15T12:53:54.567644Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="x86_64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="x86_64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: Some("") } [INFO] [stdout] 2025-01-15T12:53:54.567776Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::build_meta: Disabling repo=repo repo_arch.arch=x86_64 [INFO] [stdout] 2025-01-15T12:53:54.567840Z TRACE command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Package unchanged at revision 1. gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:54.567929Z DEBUG command{cmdline="dput foo test1.dsc"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: false }}: obs_gitlab_runner::handler: Saving complete build info: ObsBuildInfo { project: "foo", package: "bar", rev: Some("1"), srcmd5: Some("062dc3e152164165ec489ea21e7bd68e"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: None }} } [INFO] [stdout] 2025-01-15T12:53:55.325651Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}: obs_gitlab_runner::handler: > dput foo test1.dsc --rebuild-if-unchanged gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:55.391326Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: Saving initial build info: ObsBuildInfo { project: "foo", package: "bar", rev: None, srcmd5: None, is_branched: false, enabled_repos: {} } [INFO] [stdout] 2025-01-15T12:53:55.406049Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:get_if_package_exists{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}:get{project="foo" package="bar" options=BuildMetaOptions { history_retrieval: Full, disabled_repos: Keep }}: obs_gitlab_runner::build_meta: project_meta=ProjectMeta { name: "foo", repositories: [RepositoryMeta { name: "repo", rebuild: Transitive, block: All, arches: ["x86_64", "aarch64"] }] } [INFO] [stdout] 2025-01-15T12:53:55.437301Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: initial_build_meta=Some(BuildMeta { client: Client { base: Url { scheme: "http", cannot_be_a_base: false, username: "", password: None, host: Some(Ipv4(127.0.0.1)), port: Some(38181), path: "/", query: None, fragment: None }, user: "user", pass: "[redacted]", client: Client { accepts: Accepts, proxies: [Proxy(System({}), None)], referer: true, default_headers: {"accept": "*/*"} } }, project: "foo", package: "bar", repos: {RepoArch { repo: "repo", arch: "aarch64" }: JobHistList { jobhist: [JobHist { package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", versrel: "1.0-1", bcnt: "1", readytime: 1736945633, starttime: 1736945633, endtime: 1736945633, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "7be7fb7142d88bfa318ea9003fd21293" }] }, RepoArch { repo: "repo", arch: "x86_64" }: JobHistList { jobhist: [] }} }) [INFO] [stdout] 2025-01-15T12:53:55.437463Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: Uploading test1.dsc to foo/bar... gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:55.491042Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:upload_package{self=ObsDscUploader { project: "foo", package: "bar", dsc_path: "test1.dsc", dsc: Dsc { source: "bar", files: [FileEntry { hash: "202cb962ac59075b964b07152d234b70", size: 3, filename: "test1" }] }, options: ObsUploaderOptions { max_commit_attempts: 3 } }}: obs_gitlab_runner::upload: files_to_commit={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"} present_files={"_meta": "f4f75daf7cd22556e6bef525a11ed94f", "test1": "202cb962ac59075b964b07152d234b70", "test1.dsc": "4c85fb7ae65cc69676f0d043082779e7"} [INFO] [stdout] 2025-01-15T12:53:55.532324Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="aarch64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="aarch64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Failed, dirty: false, details: Some("") } [INFO] [stdout] 2025-01-15T12:53:55.566157Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}:get_status_when_ready{project="foo" package="bar" repo="repo" arch="x86_64" options=BuildMetaWaitOptions { sleep_until_ready: 15s }}:get_status{project="foo" package="bar" repo="repo" arch="x86_64"}: obs_gitlab_runner::build_meta: status=BuildStatus { package: "bar", code: Disabled, dirty: false, details: Some("") } [INFO] [stdout] 2025-01-15T12:53:55.566323Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::build_meta: Disabling repo=repo repo_arch.arch=x86_64 [INFO] [stdout] 2025-01-15T12:53:55.566403Z TRACE command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: Package unchanged at revision 1. gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:55.568129Z DEBUG command{cmdline="dput foo test1.dsc --rebuild-if-unchanged"}:run_dput{args=DputAction { project: "foo", dsc: "test1.dsc", branch_to: "", build_info_out: "build-info.yml", rebuild_if_unchanged: true }}: obs_gitlab_runner::handler: Saving complete build info: ObsBuildInfo { project: "foo", package: "bar", rev: Some("1"), srcmd5: Some("062dc3e152164165ec489ea21e7bd68e"), is_branched: false, enabled_repos: {RepoArch { repo: "repo", arch: "aarch64" }: CommitBuildInfo { prev_endtime_for_commit: Some(1736945633) }} } [INFO] [stdout] 2025-01-15T12:53:56.270399Z TRACE command{cmdline="generate-monitor test-tag --job-timeout '1 day' --rules '[{a: 1}, {b: 2}]' --download-build-results-to results"}: obs_gitlab_runner::handler: > generate-monitor test-tag --job-timeout '1 day' --rules '[{a: 1}, {b: 2}]' --download-build-results-to results gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:56.300197Z TRACE command{cmdline="generate-monitor test-tag --job-timeout '1 day' --rules '[{a: 1}, {b: 2}]' --download-build-results-to results"}:run_generate_monitor{args=GenerateMonitorAction { tag: "test-tag", rules: Some("[{a: 1}, {b: 2}]"), build_results_dir: Some("results"), build_info: "build-info.yml", pipeline_out: "obs.yml", job_prefix: "obs", job_timeout: Some("1 day"), artifact_expiration: "3 days", build_log_out: "build.log" }}: obs_gitlab_runner::handler: Wrote pipeline file 'obs.yml'. gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:57.557168Z TRACE command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}: obs_gitlab_runner::handler: > monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64 gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:57.558476Z TRACE command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1736945633), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", prev_endtime_for_commit: Some(1736945633) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}: obs_gitlab_runner::monitor: Live build log: http://127.0.0.1:38181/package/live_build_log/foo/bar/repo/aarch64 gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:57.615820Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1736945633), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", prev_endtime_for_commit: Some(1736945633) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: jobhist.jobhist=[JobHist { package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", versrel: "1.0-1", bcnt: "1", readytime: 1736945633, starttime: 1736945633, endtime: 1736945633, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "7be7fb7142d88bfa318ea9003fd21293" }, JobHist { package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", versrel: "1.0-1", bcnt: "1", readytime: 1736945637, starttime: 1736945637, endtime: 999, code: Succeeded, uri: "http://127.0.0.1:9000", workerid: "worker:1", hostarch: "x86_64", reason: "source change", verifymd5: "1eb99dc24eedc12a3bae23563909198c" }] [INFO] [stdout] 2025-01-15T12:53:57.615917Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1736945633), build_log_out: "build.log" }}:monitor_package{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", prev_endtime_for_commit: Some(1736945633) } } options=PackageMonitoringOptions { sleep_on_building: 0ns, sleep_on_old_status: 100ms, max_old_status_retries: 99 }}:get_latest_state: obs_gitlab_runner::monitor: prev_endtime_for_commit=Some(999) [INFO] [stdout] 2025-01-15T12:53:57.616009Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1736945633), build_log_out: "build.log" }}: obs_gitlab_runner::handler: Completed with: Succeeded [INFO] [stdout] 2025-01-15T12:53:57.634911Z DEBUG command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1736945633), build_log_out: "build.log" }}:download_build_log{self=ObsMonitor { package: MonitoredPackage { project: "foo", package: "bar", repository: "repo", arch: "aarch64", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", prev_endtime_for_commit: Some(1736945633) } }}:check_log_md5: obs_gitlab_runner::monitor: needle=srcmd5 '062dc3e152164165ec489ea21e7bd68e' content=srcmd5 '062dc3e152164165ec489ea21e7bd68e' short [INFO] [stdout] 2025-01-15T12:53:57.635151Z TRACE command{cmdline="monitor --rev 1 --srcmd5 062dc3e152164165ec489ea21e7bd68e --build-log-out build.log --prev-endtime-for-commit 1736945633 --project foo --package bar --repository repo --arch aarch64"}:run_monitor{args=MonitorAction { project: "foo", package: "bar", rev: "1", srcmd5: "062dc3e152164165ec489ea21e7bd68e", repository: "repo", arch: "aarch64", prev_endtime_for_commit: Some(1736945633), build_log_out: "build.log" }}: obs_gitlab_runner::handler: Build succeeded! gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:57.635326Z TRACE command{cmdline="download-binaries --build-results-dir results --project foo --package bar --repository repo --arch aarch64"}: obs_gitlab_runner::handler: > download-binaries --build-results-dir results --project foo --package bar --repository repo --arch aarch64 gitlab.output=true [INFO] [stdout] 2025-01-15T12:53:57.660788Z TRACE command{cmdline="download-binaries --build-results-dir results --project foo --package bar --repository repo --arch aarch64"}:run_download_binaries{args=DownloadBinariesAction { project: "foo", package: "bar", repository: "repo", arch: "aarch64", build_results_dir: "results" }}: obs_gitlab_runner::handler: Downloaded 1 artifact(s). gitlab.output=true [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: assertion `left == right` failed [INFO] [stdout] left: false [INFO] [stdout] right: true [INFO] [stdout] Location: src/handler.rs:1538 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 8 frames hidden ⋮  [INFO] [stdout] 9: core::panicking::assert_failed_inner::h313082505cbbbd77 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panicking.rs: [INFO] [stdout] 10: core::panicking::assert_failed::he5d8fd36ec5da39d [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panicking.rs:364 [INFO] [stdout] 11: obs_gitlab_runner::handler::tests::test_monitoring::{{closure}}::h714ee972ed73f924 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1538 [INFO] [stdout] 1536 │ // so don't check for an old build status. [INFO] [stdout] 1537 │ let build_actually_occurred = dput_test != DputTest::ReusePreviousBuild; [INFO] [stdout]  1538 >  assert_eq!( [INFO] [stdout] 1539 │ job_log.contains("Waiting for build status"), [INFO] [stdout] 1540 │ build_actually_occurred [INFO] [stdout] 12: obs_gitlab_runner::handler::tests::test_handler_flow::{{closure}}::{{closure}}::hc71ecca6f8a7bf58 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1727 [INFO] [stdout] 1725 │ download_binaries, [INFO] [stdout] 1726 │ ) [INFO] [stdout]  1727 >  .await; [INFO] [stdout] 1728 │ [INFO] [stdout] 1729 │ test_prune( [INFO] [stdout] 13:  as core::future::future::Future>::poll::h2a12d073cb332c55 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tracing-0.1.30/src/instrument.rs:329 [INFO] [stdout] 327 │ let future = this.inner; [INFO] [stdout] 328 │ let _default = dispatcher::set_default(dispatcher); [INFO] [stdout]  329 >  future.poll(cx) [INFO] [stdout] 330 │ } [INFO] [stdout] 331 │ } [INFO] [stdout] 14: obs_gitlab_runner::handler::tests::with_tracing::{{closure}}::h0bec36f9c07e89d0 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:815 [INFO] [stdout] 813 │ .with(layer), [INFO] [stdout] 814 │ ) [INFO] [stdout]  815 >  .await [INFO] [stdout] 816 │ } [INFO] [stdout] 817 │ [INFO] [stdout] 15: obs_gitlab_runner::handler::tests::test_handler_flow::{{closure}}::h9973ebe9e719b998 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1737 [INFO] [stdout] 1735 │ .await; [INFO] [stdout] 1736 │ }) [INFO] [stdout]  1737 >  .await; [INFO] [stdout] 1738 │ } [INFO] [stdout] 1739 │ [INFO] [stdout] 16: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::h925f3ecdabc740df [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1693 [INFO] [stdout] 1691 │ } [INFO] [stdout] 1692 │ [INFO] [stdout]  1693 >  #[rstest] [INFO] [stdout] 1694 │ #[tokio::test] [INFO] [stdout] 1695 │ async fn test_handler_flow( [INFO] [stdout] 17:  as core::future::future::Future>::poll::hbe3ad25db8425d40 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/future/future.rs:124 [INFO] [stdout] 18: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::hfcca66cc6ae4d59f [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:516 [INFO] [stdout] 514 │ if core.spawner.reset_woken() { [INFO] [stdout] 515 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  516 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 517 │ }); [INFO] [stdout] 518 │ [INFO] [stdout] 19: tokio::coop::with_budget::{{closure}}::h754e72e80fd77d00 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/coop.rs:102 [INFO] [stdout] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stdout] 101 │ [INFO] [stdout]  102 >  f() [INFO] [stdout] 103 │ }) [INFO] [stdout] 104 │ } [INFO] [stdout] 20: std::thread::local::LocalKey::try_with::ha141e12d1531ee8e [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/local.rs:308 [INFO] [stdout] 21: std::thread::local::LocalKey::with::h3f2ea256d5a7ba87 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/local.rs:272 [INFO] [stdout] 22: tokio::coop::with_budget::h4a7ca63a336538f0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/coop.rs:95 [INFO] [stdout] 93 │ } [INFO] [stdout] 94 │ [INFO] [stdout]  95 >  CURRENT.with(move |cell| { [INFO] [stdout] 96 │ let prev = cell.get(); [INFO] [stdout] 97 │ [INFO] [stdout] 23: tokio::coop::budget::hf21c9d290e105594 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/coop.rs:72 [INFO] [stdout] 70 │ #[inline(always)] [INFO] [stdout] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  72 >  with_budget(Budget::initial(), f) [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 24: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::{{closure}}::ha2857046db91fe5b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:516 [INFO] [stdout] 514 │ if core.spawner.reset_woken() { [INFO] [stdout] 515 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  516 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 517 │ }); [INFO] [stdout] 518 │ [INFO] [stdout] 25: tokio::runtime::basic_scheduler::Context::enter::h29caeaa98464a4a7 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:374 [INFO] [stdout] 372 │ [INFO] [stdout] 373 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  374 >  let ret = f(); [INFO] [stdout] 375 │ [INFO] [stdout] 376 │ // Take the scheduler core back [INFO] [stdout] 26: tokio::runtime::basic_scheduler::CoreGuard::block_on::{{closure}}::h5398ed9186e838e2 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:515 [INFO] [stdout] 513 │ 'outer: loop { [INFO] [stdout] 514 │ if core.spawner.reset_woken() { [INFO] [stdout]  515 >  let (c, res) = context.enter(core, || { [INFO] [stdout] 516 │ crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 517 │ }); [INFO] [stdout] 27: tokio::runtime::basic_scheduler::CoreGuard::enter::{{closure}}::h9a762b55468ae78c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 28: tokio::macros::scoped_tls::ScopedKey::set::h96a1f43bdbf64631 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/macros/scoped_tls.rs:61 [INFO] [stdout] 59 │ }; [INFO] [stdout] 60 │ [INFO] [stdout]  61 >  f() [INFO] [stdout] 62 │ } [INFO] [stdout] 63 │ [INFO] [stdout] 29: tokio::runtime::basic_scheduler::CoreGuard::enter::h18a40979e01e6381 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:582 [INFO] [stdout] 580 │ [INFO] [stdout] 581 │ // Call the closure and place `core` back [INFO] [stdout]  582 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 583 │ [INFO] [stdout] 584 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 30: tokio::runtime::basic_scheduler::CoreGuard::block_on::h6a47a6862fdc70c8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:506 [INFO] [stdout] 504 │ impl CoreGuard<'_> { [INFO] [stdout] 505 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  506 >  self.enter(|mut core, context| { [INFO] [stdout] 507 │ let _enter = crate::runtime::enter(false); [INFO] [stdout] 508 │ let waker = context.spawner.waker_ref(); [INFO] [stdout] 31: tokio::runtime::basic_scheduler::BasicScheduler::block_on::hcf6bc9af283bc121 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/basic_scheduler.rs:182 [INFO] [stdout] 180 │ loop { [INFO] [stdout] 181 │ if let Some(core) = self.take_core() { [INFO] [stdout]  182 >  return core.block_on(future); [INFO] [stdout] 183 │ } else { [INFO] [stdout] 184 │ let mut enter = crate::runtime::enter(false); [INFO] [stdout] 32: tokio::runtime::Runtime::block_on::h213dd1c71237c7ed [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.16.1/src/runtime/mod.rs:475 [INFO] [stdout] 473 │ [INFO] [stdout] 474 │ match &self.kind { [INFO] [stdout]  475 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stdout] 476 │ #[cfg(feature = "rt-multi-thread")] [INFO] [stdout] 477 │ Kind::ThreadPool(exec) => exec.block_on(future), [INFO] [stdout] 33: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2::hc4fc89fa5ae4a72a [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1693 [INFO] [stdout] 1691 │ } [INFO] [stdout] 1692 │ [INFO] [stdout]  1693 >  #[rstest] [INFO] [stdout] 1694 │ #[tokio::test] [INFO] [stdout] 1695 │ async fn test_handler_flow( [INFO] [stdout] 34: obs_gitlab_runner::handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2::{{closure}}::ha4ed7efa1f363740 [INFO] [stdout] at /opt/rustwide/workdir/src/handler.rs:1693 [INFO] [stdout] 1691 │ } [INFO] [stdout] 1692 │ [INFO] [stdout]  1693 >  #[rstest] [INFO] [stdout] 1694 │ #[tokio::test] [INFO] [stdout] 1695 │ async fn test_handler_flow( [INFO] [stdout] 35: core::ops::function::FnOnce::call_once::hde00bce13c2874a1 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/ops/function.rs:250 [INFO] [stdout] 36: core::ops::function::FnOnce::call_once::h42df3dc9a56fb705 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/ops/function.rs:250 [INFO] [stdout] 37: test::__rust_begin_short_backtrace::hf2ff4d4d12dd75b0 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:632 [INFO] [stdout] 38: test::run_test_in_process::{{closure}}::h8461a23c6c0ac10c [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:655 [INFO] [stdout] 39:  as core::ops::function::FnOnce<()>>::call_once::hefab1bfcb42baf2e [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 40: std::panicking::try::do_call::h75c82cb1133d19f1 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:584 [INFO] [stdout] 41: std::panicking::try::h41d68fc63841231a [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:547 [INFO] [stdout] 42: std::panic::catch_unwind::h7a7ff6adf9e0c698 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panic.rs:358 [INFO] [stdout] 43: test::run_test_in_process::hcd527530689ec177 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:655 [INFO] [stdout] 44: test::run_test::{{closure}}::h51ba581aac56ca21 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:576 [INFO] [stdout] 45: test::run_test::{{closure}}::h9d0d2581742c4e6c [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/test/src/lib.rs:606 [INFO] [stdout] 46: std::sys::backtrace::__rust_begin_short_backtrace::h94b953e0285eca69 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/sys/backtrace.rs:152 [INFO] [stdout] 47: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hac891bfe52f0501a [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/mod.rs:564 [INFO] [stdout] 48:  as core::ops::function::FnOnce<()>>::call_once::hd4f76b3ef0615394 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/panic/unwind_safe.rs:272 [INFO] [stdout] 49: std::panicking::try::do_call::h31b1a419f4fa670c [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:584 [INFO] [stdout] 50: std::panicking::try::h277e348adef7f0af [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panicking.rs:547 [INFO] [stdout] 51: std::panic::catch_unwind::h06db5059e51a109d [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/panic.rs:358 [INFO] [stdout] 52: std::thread::Builder::spawn_unchecked_::{{closure}}::h048aed1b5c822396 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/thread/mod.rs:562 [INFO] [stdout] 53: core::ops::function::FnOnce::call_once{{vtable.shim}}::h9f03098da71d0b51 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/core/src/ops/function.rs:250 [INFO] [stdout] 54:  as core::ops::function::FnOnce>::call_once::hcc221cc1d8cbbdc7 [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/alloc/src/boxed.rs:1993 [INFO] [stdout] 55:  as core::ops::function::FnOnce>::call_once::he5e5264a0dcd6e5f [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/alloc/src/boxed.rs:1993 [INFO] [stdout] 56: std::sys::pal::unix::thread::Thread::new::thread_start::h307a072356a0dfca [INFO] [stdout] at /rustc/fe9b9751fa54a5871b87cd36a582f9b7b06123fd/library/std/src/sys/pal/unix/thread.rs:106 [INFO] [stdout] 57: clone [INFO] [stdout] at : [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] handler::tests::test_handler_flow::dput_test_2::build_success_1::log_test_2::download_binaries_1::prune_only_if_job_unsuccessful_2 [INFO] [stdout] [INFO] [stdout] test result: FAILED. 121 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 57.30s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--bin obs-gitlab-runner` [INFO] running `Command { std: "docker" "inspect" "51e4d31daaa12c67398214c80d3b6efef98f4b3d3a464a062063164d64d65338", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "51e4d31daaa12c67398214c80d3b6efef98f4b3d3a464a062063164d64d65338", kill_on_drop: false }` [INFO] [stdout] 51e4d31daaa12c67398214c80d3b6efef98f4b3d3a464a062063164d64d65338