2026-07-29T01:41:10.7365382Z Current runner version: '2.336.0'2026-07-29T01:41:10.7391693Z ##[group]Runner Image Provisioner2026-07-29T01:41:10.7392573Z Hosted Compute Agent2026-07-29T01:41:10.7393295Z Version: 20260707.5632026-07-29T01:41:10.7393957Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-07-29T01:41:10.7394749Z Build Date: 2026-07-07T19:33:50Z2026-07-29T01:41:10.7395498Z Worker ID: {e1fe3b88-5414-47e1-b0bf-aca7f99dff01}2026-07-29T01:41:10.7396232Z Azure Region: westus2026-07-29T01:41:10.7397107Z ##[endgroup]2026-07-29T01:41:10.7398713Z ##[group]Operating System2026-07-29T01:41:10.7399331Z Ubuntu2026-07-29T01:41:10.7400010Z 22.04.52026-07-29T01:41:10.7400530Z LTS2026-07-29T01:41:10.7401118Z ##[endgroup]2026-07-29T01:41:10.7401741Z ##[group]Runner Image2026-07-29T01:41:10.7402350Z Image: ubuntu-22.042026-07-29T01:41:10.7403013Z Version: 20260720.234.22026-07-29T01:41:10.7404298Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-07-29T01:41:10.7405910Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-07-29T01:41:10.7407162Z ##[endgroup]2026-07-29T01:41:10.7408467Z ##[group]GITHUB_TOKEN Permissions2026-07-29T01:41:10.7410700Z Contents: read2026-07-29T01:41:10.7411424Z Metadata: read2026-07-29T01:41:10.7412030Z Packages: read2026-07-29T01:41:10.7412555Z ##[endgroup]2026-07-29T01:41:10.7415272Z Secret source: Actions2026-07-29T01:41:10.7416447Z Prepare workflow directory2026-07-29T01:41:10.7767259Z Prepare all required actions2026-07-29T01:41:10.7819250Z Getting action download info2026-07-29T01:41:11.1256077Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-07-29T01:41:11.5368031Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-07-29T01:41:11.9391336Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-07-29T01:41:12.3161274Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-07-29T01:41:12.5685191Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-07-29T01:41:12.5691185Z ##[group] Inputs2026-07-29T01:41:12.5691738Z testName: TestOSPoolEP2026-07-29T01:41:12.5692606Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-29T01:41:12.5693524Z setupScript: 2026-07-29T01:41:12.5693969Z setupEnv: {}2026-07-29T01:41:12.5694404Z ##[endgroup]2026-07-29T01:41:12.5695085Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-07-29T01:41:12.6592999Z ##[group]Run actions/checkout@v52026-07-29T01:41:12.6593928Z with:2026-07-29T01:41:12.6594438Z repository: mwestphall/k8s-integration-tests2026-07-29T01:41:12.6599233Z token: ***2026-07-29T01:41:12.6599730Z ssh-strict: true2026-07-29T01:41:12.6600235Z ssh-user: git2026-07-29T01:41:12.6600698Z persist-credentials: true2026-07-29T01:41:12.6601193Z clean: true2026-07-29T01:41:12.6601639Z sparse-checkout-cone-mode: true2026-07-29T01:41:12.6602157Z fetch-depth: 12026-07-29T01:41:12.6602589Z fetch-tags: false2026-07-29T01:41:12.6603037Z show-progress: true2026-07-29T01:41:12.6603479Z lfs: false2026-07-29T01:41:12.6603874Z submodules: false2026-07-29T01:41:12.6604317Z set-safe-directory: true2026-07-29T01:41:12.6604831Z allow-unsafe-pr-checkout: false2026-07-29T01:41:12.6605708Z ##[endgroup]2026-07-29T01:41:12.7804901Z Syncing repository: mwestphall/k8s-integration-tests2026-07-29T01:41:12.7807746Z ##[group]Getting Git version info2026-07-29T01:41:12.7809125Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-07-29T01:41:12.7810656Z [command]/usr/bin/git version2026-07-29T01:41:12.7886085Z git version 2.54.02026-07-29T01:41:12.7919405Z ##[endgroup]2026-07-29T01:41:12.7935261Z Temporarily overriding HOME='/home/runner/work/_temp/96f3d9e6-bd11-42f2-a08d-5085bf423dc9' before making global git config changes2026-07-29T01:41:12.7938460Z Adding repository directory to the temporary git global config as a safe directory2026-07-29T01:41:12.7942853Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-29T01:41:12.7990780Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-07-29T01:41:12.7994001Z ##[group]Initializing the repository2026-07-29T01:41:12.8002563Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-29T01:41:12.8147105Z hint: Using 'master' as the name for the initial branch. This default branch name2026-07-29T01:41:12.8165484Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-07-29T01:41:12.8167686Z hint: to use in all of your new repositories, which will suppress this warning,2026-07-29T01:41:12.8169128Z hint: call:2026-07-29T01:41:12.8169936Z hint:2026-07-29T01:41:12.8171051Z hint: git config --global init.defaultBranch <name>2026-07-29T01:41:12.8172205Z hint:2026-07-29T01:41:12.8173350Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-07-29T01:41:12.8175215Z hint: 'development'. The just-created branch can be renamed via this command:2026-07-29T01:41:12.8176538Z hint:2026-07-29T01:41:12.8177835Z hint: git branch -m <name>2026-07-29T01:41:12.8178768Z hint:2026-07-29T01:41:12.8180164Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-07-29T01:41:12.8182253Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-07-29T01:41:12.8185965Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-07-29T01:41:12.8246521Z ##[endgroup]2026-07-29T01:41:12.8249662Z ##[group]Disabling automatic garbage collection2026-07-29T01:41:12.8251991Z [command]/usr/bin/git config --local gc.auto 02026-07-29T01:41:12.8295946Z ##[endgroup]2026-07-29T01:41:12.8298850Z ##[group]Setting up auth2026-07-29T01:41:12.8302658Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-07-29T01:41:12.8379252Z [command]/usr/bin/git submodule foreach --recursive sh -c "git config --local --name-only --get-regexp 'core\.sshCommand' && git config --local --unset-all 'core.sshCommand' || :"2026-07-29T01:41:12.8819492Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-07-29T01:41:12.8862613Z [command]/usr/bin/git submodule foreach --recursive sh -c "git config --local --name-only --get-regexp 'http\.https\:\/\/github\.com\/\.extraheader' && git config --local --unset-all 'http.https://github.com/.extraheader' || :"2026-07-29T01:41:12.9125202Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-07-29T01:41:12.9165797Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-07-29T01:41:12.9420649Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-07-29T01:41:12.9464153Z ##[endgroup]2026-07-29T01:41:12.9465409Z ##[group]Fetching the repository2026-07-29T01:41:12.9474359Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-07-29T01:41:13.3541077Z From https://github.com/mwestphall/k8s-integration-tests2026-07-29T01:41:13.3542334Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-07-29T01:41:13.3569279Z ##[endgroup]2026-07-29T01:41:13.3570410Z ##[group]Determining the checkout info2026-07-29T01:41:13.3572269Z ##[endgroup]2026-07-29T01:41:13.3577599Z [command]/usr/bin/git sparse-checkout disable2026-07-29T01:41:13.3629249Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-07-29T01:41:13.3662571Z ##[group]Checking out the ref2026-07-29T01:41:13.3668707Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-07-29T01:41:13.3763500Z Switched to a new branch 'main'2026-07-29T01:41:13.3766592Z branch 'main' set up to track 'origin/main'.2026-07-29T01:41:13.3810076Z ##[endgroup]2026-07-29T01:41:13.3854208Z [command]/usr/bin/git log -1 --format=%H2026-07-29T01:41:13.3883023Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-07-29T01:41:13.4202509Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-07-29T01:41:13.4203776Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-07-29T01:41:13.4204849Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-07-29T01:41:13.4328050Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-29T01:41:13.4328795Z env:2026-07-29T01:41:13.4329617Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-29T01:41:13.4330566Z SETUP_ENV_YAML: {}2026-07-29T01:41:13.4331015Z ##[endgroup]2026-07-29T01:41:13.9362464Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-07-29T01:41:13.9365367Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-07-29T01:41:13.9428923Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-29T01:41:13.9430551Z env:2026-07-29T01:41:13.9432669Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-07-29T01:41:13.9435208Z ##[endgroup]2026-07-29T01:41:13.9641797Z ##[group]Run true2026-07-29T01:41:13.9642781Z [36;1mtrue[0m2026-07-29T01:41:13.9706560Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-29T01:41:13.9708340Z ##[endgroup]2026-07-29T01:41:14.0014782Z ##[group]Run medyagh/setup-minikube@latest2026-07-29T01:41:14.0016023Z with:2026-07-29T01:41:14.0016997Z start: true2026-07-29T01:41:14.0017843Z cache: true2026-07-29T01:41:14.0018755Z minikube-version: latest2026-07-29T01:41:14.0019741Z wait: all2026-07-29T01:41:14.0020544Z ##[endgroup]2026-07-29T01:41:14.1348477Z (node:2051) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-07-29T01:41:14.1353475Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-07-29T01:41:15.1725011Z [command]/usr/bin/chmod +x /home/runner/work/_temp/dbe33496-ad34-4c5d-808d-eb1a68d9f0e12026-07-29T01:41:15.3402658Z [command]/home/runner/bin/minikube version --short2026-07-29T01:41:15.3796485Z v1.38.12026-07-29T01:41:15.6407243Z Cache hit for: kic-v1.38.1-x642026-07-29T01:41:15.6530203Z (node:2051) [DEP0169] DeprecationWarning: `url.parse()` behavior is not standardized and prone to errors that have security implications. Use the WHATWG URL API instead. CVEs are not issued for `url.parse()` vulnerabilities.2026-07-29T01:41:15.6547889Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-07-29T01:41:16.9436675Z Received 4194304 of 544082657 (0.8%), 4.0 MBs/sec2026-07-29T01:41:16.9662676Z Received 4194304 of 224218863 (1.9%), 4.0 MBs/sec2026-07-29T01:41:17.9434617Z Received 113246208 of 544082657 (20.8%), 54.0 MBs/sec2026-07-29T01:41:17.9660455Z Received 113246208 of 224218863 (50.5%), 54.0 MBs/sec2026-07-29T01:41:18.9447255Z Received 213909504 of 544082657 (39.3%), 68.0 MBs/sec2026-07-29T01:41:18.9685119Z Received 203247343 of 224218863 (90.6%), 64.6 MBs/sec2026-07-29T01:41:19.1260681Z Received 224218863 of 224218863 (100.0%), 67.7 MBs/sec2026-07-29T01:41:19.1262277Z Cache Size: ~214 MB (224218863 B)2026-07-29T01:41:19.1348183Z [command]/usr/bin/tar -xf /home/runner/work/_temp/da767493-ec03-43ad-bbc4-7b3317627f29/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-29T01:41:19.7389282Z Cache restored successfully2026-07-29T01:41:19.9453338Z Received 314572800 of 544082657 (57.8%), 74.9 MBs/sec2026-07-29T01:41:20.9469435Z Received 423624704 of 544082657 (77.9%), 80.7 MBs/sec2026-07-29T01:41:21.9709135Z Received 536870912 of 544082657 (98.7%), 84.9 MBs/sec2026-07-29T01:41:22.1793117Z Received 544082657 of 544082657 (100.0%), 83.2 MBs/sec2026-07-29T01:41:22.1798329Z Cache Size: ~519 MB (544082657 B)2026-07-29T01:41:22.1937163Z [command]/usr/bin/tar -xf /home/runner/work/_temp/8133b4dd-02e4-462b-84f2-bd4917970f72/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-29T01:41:22.9836259Z Cache restored successfully2026-07-29T01:41:23.0977165Z [command]/home/runner/bin/minikube start --wait all2026-07-29T01:41:23.1428383Z * minikube v1.38.1 on Ubuntu 22.042026-07-29T01:41:23.6607191Z ! The minimum required version for podman is "4.9.0". your version is "3.4.4". minikube might not work. use at your own risk. To install latest version please see https://podman.io/getting-started/installation.html2026-07-29T01:41:23.6629351Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-07-29T01:41:23.7174189Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-07-29T01:41:23.7184725Z * Using Docker driver with root privileges2026-07-29T01:41:23.7196900Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-07-29T01:41:23.7205073Z * Pulling base image v0.0.50 ...2026-07-29T01:41:51.7973696Z * Configuring bridge CNI (Container Networking Interface) ...2026-07-29T01:41:56.9843824Z * Verifying Kubernetes components...2026-07-29T01:41:57.0045654Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-07-29T01:41:57.7639738Z * Enabled addons: default-storageclass, storage-provisioner2026-07-29T01:42:34.7410358Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-07-29T01:42:34.7522830Z [command]/home/runner/bin/minikube version --short2026-07-29T01:42:34.7927488Z v1.38.12026-07-29T01:42:34.8224781Z ##[group]Run actions/setup-go@v62026-07-29T01:42:34.8225069Z with:2026-07-29T01:42:34.8225256Z go-version: 1.262026-07-29T01:42:34.8225456Z cache: true2026-07-29T01:42:34.8225680Z cache-dependency-path: go.sum2026-07-29T01:42:34.8225931Z check-latest: false2026-07-29T01:42:34.8228485Z token: ***2026-07-29T01:42:34.8228692Z ##[endgroup]2026-07-29T01:42:34.9583203Z Setup go version spec 1.262026-07-29T01:42:34.9647383Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-07-29T01:42:34.9650509Z Added go to the path2026-07-29T01:42:34.9654445Z Successfully set up Go version 1.262026-07-29T01:42:35.5127854Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-07-29T01:42:35.5169124Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-07-29T01:42:35.5201020Z /home/runner/go/pkg/mod2026-07-29T01:42:35.5227338Z /home/runner/.cache/go-build2026-07-29T01:42:35.7848696Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-07-29T01:42:37.0792096Z Received 8388608 of 251839808 (3.3%), 8.0 MBs/sec2026-07-29T01:42:38.0828375Z Received 130023424 of 251839808 (51.6%), 61.9 MBs/sec2026-07-29T01:42:39.1262305Z Received 251839808 of 251839808 (100.0%), 78.8 MBs/sec2026-07-29T01:42:39.1264711Z Cache Size: ~240 MB (251839808 B)2026-07-29T01:42:39.1369164Z [command]/usr/bin/tar -xf /home/runner/work/_temp/853831db-e543-4f89-a4a3-b632a60149fd/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-29T01:42:42.2619628Z Cache restored successfully2026-07-29T01:42:42.3178417Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-07-29T01:42:42.3230949Z go version go1.26.5 linux/amd642026-07-29T01:42:42.3231806Z 2026-07-29T01:42:42.3232292Z ##[group]go env2026-07-29T01:42:42.3936308Z AR='ar'2026-07-29T01:42:42.3937028Z CC='gcc'2026-07-29T01:42:42.3937447Z CGO_CFLAGS='-O2 -g'2026-07-29T01:42:42.3937848Z CGO_CPPFLAGS=''2026-07-29T01:42:42.3938309Z CGO_CXXFLAGS='-O2 -g'2026-07-29T01:42:42.3938693Z CGO_ENABLED='1'2026-07-29T01:42:42.3939044Z CGO_FFLAGS='-O2 -g'2026-07-29T01:42:42.3939396Z CGO_LDFLAGS='-O2 -g'2026-07-29T01:42:42.3939739Z CXX='g++'2026-07-29T01:42:42.3940061Z GCCGO='gccgo'2026-07-29T01:42:42.3940383Z GO111MODULE=''2026-07-29T01:42:42.3940713Z GOAMD64='v1'2026-07-29T01:42:42.3941034Z GOARCH='amd64'2026-07-29T01:42:42.3941530Z GOAUTH='netrc'2026-07-29T01:42:42.3941880Z GOBIN=''2026-07-29T01:42:42.3942245Z GOCACHE='/home/runner/.cache/go-build'2026-07-29T01:42:42.3942717Z GOCACHEPROG=''2026-07-29T01:42:42.3943051Z GODEBUG=''2026-07-29T01:42:42.3943404Z GOENV='/home/runner/.config/go/env'2026-07-29T01:42:42.3943861Z GOEXE=''2026-07-29T01:42:42.3944220Z GOEXPERIMENT=''2026-07-29T01:42:42.3944575Z GOFIPS140='off'2026-07-29T01:42:42.3944896Z GOFLAGS=''2026-07-29T01:42:42.3945994Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build221099554=/tmp/go-build -gno-record-gcc-switches'2026-07-29T01:42:42.3947422Z GOHOSTARCH='amd64'2026-07-29T01:42:42.3947795Z GOHOSTOS='linux'2026-07-29T01:42:42.3948149Z GOINSECURE=''2026-07-29T01:42:42.3948757Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-07-29T01:42:42.3949517Z GOMODCACHE='/home/runner/go/pkg/mod'2026-07-29T01:42:42.3949960Z GONOPROXY=''2026-07-29T01:42:42.3950291Z GONOSUMDB=''2026-07-29T01:42:42.3950598Z GOOS='linux'2026-07-29T01:42:42.3950936Z GOPATH='/home/runner/go'2026-07-29T01:42:42.3951329Z GOPRIVATE=''2026-07-29T01:42:42.3951768Z GOPROXY='https://proxy.golang.org,direct'2026-07-29T01:42:42.3952342Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-07-29T01:42:42.3952832Z GOSUMDB='sum.golang.org'2026-07-29T01:42:42.3953267Z GOTELEMETRY='local'2026-07-29T01:42:42.3953712Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-07-29T01:42:42.3954225Z GOTMPDIR=''2026-07-29T01:42:42.3955048Z GOTOOLCHAIN='local'2026-07-29T01:42:42.3955600Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-07-29T01:42:42.3956198Z GOVCS=''2026-07-29T01:42:42.3956512Z GOVERSION='go1.26.5'2026-07-29T01:42:42.3957370Z GOWORK=''2026-07-29T01:42:42.3957740Z PKG_CONFIG='pkg-config'2026-07-29T01:42:42.3958000Z 2026-07-29T01:42:42.3958671Z ##[endgroup]2026-07-29T01:42:42.4146637Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-07-29T01:42:42.4147491Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-07-29T01:42:42.4205842Z shell: /usr/bin/bash -e {0}2026-07-29T01:42:42.4206102Z env:2026-07-29T01:42:42.4206299Z GOTOOLCHAIN: local2026-07-29T01:42:42.4206537Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-07-29T01:42:42.4207161Z OSPOOL_EP_CMTag: 25.x-el92026-07-29T01:42:42.4207435Z OSPOOL_EP_CvmfsType: cvmfsexec2026-07-29T01:42:42.4207685Z ##[endgroup]2026-07-29T01:42:48.7603039Z === RUN TestOSPoolEP2026-07-29T01:42:48.7605745Z === PAUSE TestOSPoolEP2026-07-29T01:42:48.7606215Z === CONT TestOSPoolEP2026-07-29T01:42:48.7617840Z TestOSPoolEP 2026-07-29T01:42:48Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:42:48.7699256Z TestOSPoolEP 2026-07-29T01:42:48Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 apply -f /tmp/TestOSPoolEP1715199263]2026-07-29T01:42:49.2285099Z TestOSPoolEP 2026-07-29T01:42:49Z logger.go:67: pod/idtoken-generator created2026-07-29T01:42:49.2356649Z TestOSPoolEP 2026-07-29T01:42:49Z logger.go:67: secret/pool-passwd created2026-07-29T01:42:49.2403014Z TestOSPoolEP 2026-07-29T01:42:49Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-29T01:42:49.2415906Z TestOSPoolEP 2026-07-29T01:42:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:42:49.2441610Z TestOSPoolEP 2026-07-29T01:42:49Z retry.go:103: Wait for pod idtoken-generator to be provisioned. returned an error: Pod idtoken-generator is not available, reason: , message: . Sleeping for 10s and will try again.2026-07-29T01:42:59.2452532Z TestOSPoolEP 2026-07-29T01:42:59Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-29T01:42:59.2456061Z TestOSPoolEP 2026-07-29T01:42:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:42:59.2491462Z TestOSPoolEP 2026-07-29T01:42:59Z retry.go:103: Wait for pod idtoken-generator to be provisioned. returned an error: Pod idtoken-generator is not available, reason: , message: . Sleeping for 10s and will try again.2026-07-29T01:43:09.2512476Z TestOSPoolEP 2026-07-29T01:43:09Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-29T01:43:09.2514697Z TestOSPoolEP 2026-07-29T01:43:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:09.2541093Z TestOSPoolEP 2026-07-29T01:43:09Z logger.go:67: Pod is now available2026-07-29T01:43:09.2542939Z TestOSPoolEP 2026-07-29T01:43:09Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-07-29T01:43:09.9063087Z TestOSPoolEP 2026-07-29T01:43:09Z logger.go:67: ***2026-07-29T01:43:09.9132451Z TestOSPoolEP 2026-07-29T01:43:09Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 apply -f /tmp/TestOSPoolEP797011531]2026-07-29T01:43:10.0960348Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: secret/pool-token created2026-07-29T01:43:10.1001264Z TestOSPoolEP 2026-07-29T01:43:10Z retry.go:91: Wait for secret pool-token to be provisioned.2026-07-29T01:43:10.1003733Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:10.1022923Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: Secret is now available2026-07-29T01:43:10.1043018Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 apply -k /tmp/kustomize-template-3313145226]2026-07-29T01:43:10.4218378Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: service/test-cm created2026-07-29T01:43:10.4322867Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: Warning: spec.template.spec.containers[0].env[8]: hides previous definition of "OSG_SQUID_LOCATION", which may be dropped when using apply2026-07-29T01:43:10.4332536Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: deployment.apps/ospool-ep created2026-07-29T01:43:10.4425618Z TestOSPoolEP 2026-07-29T01:43:10Z logger.go:67: deployment.apps/test-cm created2026-07-29T01:43:10.4476466Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-07-29T01:43:10.4478506Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:10.4652263Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-29T01:43:10.4658724Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:10.4671378Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-29T01:43:10.4673550Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:10.4835286Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "ospool-ep-65c844ddbb". Sleeping for 10s and will try again.2026-07-29T01:43:10.4841612Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:10Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "test-cm-5bb545475c". Sleeping for 10s and will try again.2026-07-29T01:43:20.4831693Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:20Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-29T01:43:20.4833772Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:20.4840729Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:20Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-29T01:43:20.4843280Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:20.4868779Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:20Z logger.go:67: Deployment is now available2026-07-29T01:43:20.4873429Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:20Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-65c844ddbb" is progressing.. Sleeping for 10s and will try again.2026-07-29T01:43:30.4881256Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:30Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-29T01:43:30.4883306Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.4924164Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-29T01:43:30Z logger.go:67: Deployment is now available2026-07-29T01:43:30.4926102Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-29T01:43:30.4927547Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-29T01:43:30.4928868Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-29T01:43:30.4929695Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-29T01:43:30.4930425Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-29T01:43:30.4931167Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-29T01:43:30.4931910Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-29T01:43:30.4933273Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.4934876Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-29T01:43:30.4936290Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.4938159Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-29T01:43:30.4939643Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.4965073Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.4968516Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.4978201Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-29T01:43:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:43:30.5000910Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)']2026-07-29T01:43:30.5027590Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:43:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:43:30.5049053Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-29T01:43:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_SINGULARITY]2026-07-29T01:43:30.7881075Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-978ww failed. Retrying in 10s.2026-07-29T01:43:30.7959952Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-29T01:43:30.7970546Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)']' in pod test-cm-5bb545475c-978ww failed. Retrying in 10s.2026-07-29T01:43:30.7975789Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-29T01:43:30.7978731Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:43:40.7883262Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-29T01:43:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_SINGULARITY]2026-07-29T01:43:40.7968993Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)']2026-07-29T01:43:41.0373191Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-29T01:43:41.0377507Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-978ww failed. Retrying in 10s.2026-07-29T01:43:41.0908853Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-29T01:43:41.0911219Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)']' in pod test-cm-5bb545475c-978ww failed. Retrying in 10s.2026-07-29T01:43:51.0395403Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-29T01:43:51Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_SINGULARITY]2026-07-29T01:43:51.0918852Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)']2026-07-29T01:43:51.2289395Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-29T01:43:51Z logger.go:67: true2026-07-29T01:43:51.2941091Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-07-29T01:43:51.2942916Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: 2026-07-29T01:43:51.2947575Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-rgdtq LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-07-29T01:43:51.2960551Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: 2026-07-29T01:43:51.2962636Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-07-29T01:43:51.2964525Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: 2026-07-29T01:43:51.2966283Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-07-29T01:43:51.2968547Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: 2026-07-29T01:43:51.2970239Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-29T01:43:51Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-07-29T01:44:00.7984697Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:44:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:44:00.9470700Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:44:00Z logger.go:67: undefined2026-07-29T01:44:00.9528779Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-29T01:44:00.9530355Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:44:30.9538667Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:44:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:44:31.1096901Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:44:31Z logger.go:67: undefined2026-07-29T01:44:31.1156072Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:45:01.1169383Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:45:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:45:01.2882920Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:45:01Z logger.go:67: undefined2026-07-29T01:45:01.2948758Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:45:31.2952387Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:45:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:45:31.4454687Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:45:31Z logger.go:67: undefined2026-07-29T01:45:31.4515733Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:46:01.4537543Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:46:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:46:01.6268046Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:46:01Z logger.go:67: undefined2026-07-29T01:46:01.6325958Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:46:31.6348240Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:46:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:46:31.7810974Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:46:31Z logger.go:67: undefined2026-07-29T01:46:31.7877683Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:47:01.7882431Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:47:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:47:01.9396759Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:47:01Z logger.go:67: undefined2026-07-29T01:47:01.9457424Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:47:31.9462368Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:47:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:47:32.1125673Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:47:32Z logger.go:67: undefined2026-07-29T01:47:32.1189816Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:48:02.1197415Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:48:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:48:02.2797452Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:48:02Z logger.go:67: undefined2026-07-29T01:48:02.2857475Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:48:32.2875507Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:48:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:48:32.4329305Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:48:32Z logger.go:67: undefined2026-07-29T01:48:32.4382552Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-978ww failed. Retrying in 30s.2026-07-29T01:49:02.4392496Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:49:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 exec test-cm-5bb545475c-978ww -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rgdtq",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-29T01:49:02.6017527Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-29T01:49:02Z logger.go:67: true2026-07-29T01:49:02.6086824Z TestOSPoolEP 2026-07-29T01:49:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:02.6176146Z TestOSPoolEP 2026-07-29T01:49:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:02.7824576Z TestOSPoolEP 2026-07-29T01:49:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:02.9259068Z TestOSPoolEP 2026-07-29T01:49:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:03.0608991Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:03.0642391Z === NAME TestOSPoolEP2026-07-29T01:49:03.0643238Z ospool_ep_test.go:101: ---2026-07-29T01:49:03.0644373Z Events for pod idtoken-generator:2026-07-29T01:49:03.0646058Z 2026-07-29 01:42:49 +0000 UTC Normal Successfully assigned test-ospool-ep-xkonn1/idtoken-generator to minikube2026-07-29T01:49:03.0647925Z 2026-07-29 01:42:52 +0000 UTC Normal Pulling image "htcondor/base"2026-07-29T01:49:03.0652335Z 2026-07-29 01:43:01 +0000 UTC Normal Successfully pulled image "htcondor/base" in 9.304s (9.304s including waiting). Image size: 560910730 bytes.2026-07-29T01:49:03.0653801Z 2026-07-29 01:43:01 +0000 UTC Normal Container created2026-07-29T01:49:03.0655203Z 2026-07-29 01:43:01 +0000 UTC Normal Container started2026-07-29T01:49:03.0655817Z 2026-07-29T01:49:03.0656177Z ---2026-07-29T01:49:03.0657394Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:03.0686160Z ospool_ep_test.go:101: ---2026-07-29T01:49:03.0687855Z Events for pod ospool-ep-65c844ddbb-rgdtq:2026-07-29T01:49:03.0690100Z 2026-07-29 01:43:10 +0000 UTC Normal Successfully assigned test-ospool-ep-xkonn1/ospool-ep-65c844ddbb-rgdtq to minikube2026-07-29T01:49:03.0692182Z 2026-07-29 01:43:10 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-07-29T01:49:03.0695731Z 2026-07-29 01:43:13 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.278s (2.278s including waiting). Image size: 5590942 bytes.2026-07-29T01:49:03.0700962Z 2026-07-29 01:43:13 +0000 UTC Normal Container created2026-07-29T01:49:03.0701910Z 2026-07-29 01:43:13 +0000 UTC Normal Container started2026-07-29T01:49:03.0703298Z 2026-07-29 01:43:13 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-07-29T01:49:03.0705812Z 2026-07-29 01:43:29 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 15.188s (15.823s including waiting). Image size: 1250776798 bytes.2026-07-29T01:49:03.0708722Z 2026-07-29 01:43:29 +0000 UTC Normal Container created2026-07-29T01:49:03.0709841Z 2026-07-29 01:43:29 +0000 UTC Normal Container started2026-07-29T01:49:03.0710417Z 2026-07-29T01:49:03.0711065Z ---2026-07-29T01:49:03.0714661Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:03.0728952Z ospool_ep_test.go:101: ---2026-07-29T01:49:03.0730129Z Events for pod test-cm-5bb545475c-978ww:2026-07-29T01:49:03.0731694Z 2026-07-29 01:43:10 +0000 UTC Normal Successfully assigned test-ospool-ep-xkonn1/test-cm-5bb545475c-978ww to minikube2026-07-29T01:49:03.0733144Z 2026-07-29 01:43:10 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-07-29T01:49:03.0735005Z 2026-07-29 01:43:14 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 1.129s (3.387s including waiting). Image size: 560910756 bytes.2026-07-29T01:49:03.0736475Z 2026-07-29 01:43:14 +0000 UTC Normal Container created2026-07-29T01:49:03.0737815Z 2026-07-29 01:43:14 +0000 UTC Normal Container started2026-07-29T01:49:03.0738401Z 2026-07-29T01:49:03.0738752Z ---2026-07-29T01:49:03.0739744Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-29T01:49:03.0778907Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 delete -f /tmp/TestOSPoolEP3243892490]2026-07-29T01:49:03.1843953Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-xkonn1 namespace2026-07-29T01:49:03.1924972Z TestOSPoolEP 2026-07-29T01:49:03Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-xkonn1 namespace2026-07-29T01:49:04.0343964Z TestOSPoolEP 2026-07-29T01:49:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 delete -f /tmp/TestOSPoolEP648221464]2026-07-29T01:49:04.1468365Z TestOSPoolEP 2026-07-29T01:49:04Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-xkonn1 namespace2026-07-29T01:49:04.1515311Z TestOSPoolEP 2026-07-29T01:49:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-xkonn1 delete -k /tmp/kustomize-template-3313145226]2026-07-29T01:49:04.2790035Z TestOSPoolEP 2026-07-29T01:49:04Z logger.go:67: service "test-cm" deleted from test-ospool-ep-xkonn1 namespace2026-07-29T01:49:04.2852742Z TestOSPoolEP 2026-07-29T01:49:04Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-xkonn1 namespace2026-07-29T01:49:04.2902734Z TestOSPoolEP 2026-07-29T01:49:04Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-xkonn1 namespace2026-07-29T01:49:04.3019030Z --- PASS: TestOSPoolEP (43.42s)2026-07-29T01:49:04.3048164Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (20.04s)2026-07-29T01:49:04.3098767Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.74s)2026-07-29T01:49:04.3128980Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.82s)2026-07-29T01:49:04.3130324Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.12s)2026-07-29T01:49:04.3157594Z PASS2026-07-29T01:49:04.3169918Z ok github.com/osg-htc/k8s-integration-tests/test 375.557s2026-07-29T01:49:04.4289333Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-07-29T01:49:04.4290033Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-07-29T01:49:04.4361924Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-29T01:49:04.4362522Z env:2026-07-29T01:49:04.4362880Z GOTOOLCHAIN: local2026-07-29T01:49:04.4363683Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-29T01:49:04.4364549Z ##[endgroup]2026-07-29T01:49:04.4607503Z Node 20 is being deprecated. This workflow is running with Node 24 by default. If you need to temporarily use Node 20, you can set the ACTIONS_ALLOW_USE_UNSECURE_NODE_VERSION=true environment variable. For more information see: https://github.blog/changelog/2025-09-19-deprecation-of-node-20-on-github-actions-runners/2026-07-29T01:49:04.4624877Z ##[group]Run actions/upload-artifact@v42026-07-29T01:49:04.4625447Z with:2026-07-29T01:49:04.4626124Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-07-29T01:49:04.4627156Z path: /tmp/k8s-tests2026-07-29T01:49:04.4627621Z retention-days: 52026-07-29T01:49:04.4628043Z if-no-files-found: warn2026-07-29T01:49:04.4628516Z compression-level: 62026-07-29T01:49:04.4628945Z overwrite: false2026-07-29T01:49:04.4629359Z include-hidden-files: false2026-07-29T01:49:04.4629825Z env:2026-07-29T01:49:04.4630180Z GOTOOLCHAIN: local2026-07-29T01:49:04.4630582Z ##[endgroup]2026-07-29T01:49:04.6683166Z (node:17487) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-07-29T01:49:04.6684601Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-07-29T01:49:04.6824361Z With the provided path, there will be 6 files uploaded2026-07-29T01:49:04.6831332Z Artifact name is valid!2026-07-29T01:49:04.6832072Z Root directory input is valid!2026-07-29T01:49:05.0455108Z Beginning upload of artifact content to blob storage2026-07-29T01:49:05.0980162Z (node:17487) [DEP0169] DeprecationWarning: `url.parse()` behavior is not standardized and prone to errors that have security implications. Use the WHATWG URL API instead. CVEs are not issued for `url.parse()` vulnerabilities.2026-07-29T01:49:05.4703902Z Uploaded bytes 139662026-07-29T01:49:05.5558030Z Finished uploading artifact content to blob storage!2026-07-29T01:49:05.5566045Z SHA256 digest of uploaded artifact zip is e347821bd2fef55ca0faea9470f0c247b8b3ced6f812700a92948455be6001b02026-07-29T01:49:05.5567828Z Finalizing artifact upload2026-07-29T01:49:05.7879000Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 87098648672026-07-29T01:49:05.7880621Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 13966 bytes. Artifact ID is 87098648672026-07-29T01:49:05.7887819Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30414769998/artifacts/87098648672026-07-29T01:49:05.8058262Z Post job cleanup.2026-07-29T01:49:05.9445143Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-07-29T01:49:05.9511390Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-07-29T01:49:05.9549815Z /home/runner/go/pkg/mod2026-07-29T01:49:05.9597945Z /home/runner/.cache/go-build2026-07-29T01:49:05.9618617Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-07-29T01:49:05.9871155Z Post job cleanup.2026-07-29T01:49:06.0902408Z [command]/usr/bin/git version2026-07-29T01:49:06.0950107Z git version 2.54.02026-07-29T01:49:06.0996970Z Temporarily overriding HOME='/home/runner/work/_temp/38ad5078-0c88-461f-85b7-8cb7a22b89c5' before making global git config changes2026-07-29T01:49:06.0998408Z Adding repository directory to the temporary git global config as a safe directory2026-07-29T01:49:06.1003469Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-29T01:49:06.1049964Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-07-29T01:49:06.1089050Z [command]/usr/bin/git submodule foreach --recursive sh -c "git config --local --name-only --get-regexp 'core\.sshCommand' && git config --local --unset-all 'core.sshCommand' || :"2026-07-29T01:49:06.1415524Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-07-29T01:49:06.1444591Z http.https://github.com/.extraheader2026-07-29T01:49:06.1458742Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-07-29T01:49:06.1504848Z [command]/usr/bin/git submodule foreach --recursive sh -c "git config --local --name-only --get-regexp 'http\.https\:\/\/github\.com\/\.extraheader' && git config --local --unset-all 'http.https://github.com/.extraheader' || :"2026-07-29T01:49:06.1836995Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-07-29T01:49:06.1880650Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-07-29T01:49:06.2318106Z Cleaning up orphan processes2026-07-29T01:49:06.3150333Z ##[warning]Node.js 20 is deprecated. The following actions target Node.js 20 but are being forced to run on Node.js 24: actions/upload-artifact@v4. For more information see: https://github.blog/changelog/2025-09-19-deprecation-of-node-20-on-github-actions-runners/