2026-08-03T01:50:17.7864781Z Current runner version: '2.336.0'2026-08-03T01:50:17.7889018Z ##[group]Runner Image Provisioner2026-08-03T01:50:17.7890242Z Hosted Compute Agent2026-08-03T01:50:17.7890993Z Version: 20260707.5632026-08-03T01:50:17.7891654Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-03T01:50:17.7892396Z Build Date: 2026-07-07T19:33:50Z2026-08-03T01:50:17.7893166Z Worker ID: {312710c1-c4c4-4242-a566-c87333ffd888}2026-08-03T01:50:17.7893888Z Azure Region: westus2026-08-03T01:50:17.7894460Z ##[endgroup]2026-08-03T01:50:17.7896093Z ##[group]Operating System2026-08-03T01:50:17.7896729Z Ubuntu2026-08-03T01:50:17.7897339Z 22.04.52026-08-03T01:50:17.7897893Z LTS2026-08-03T01:50:17.7898423Z ##[endgroup]2026-08-03T01:50:17.7899035Z ##[group]Runner Image2026-08-03T01:50:17.7899860Z Image: ubuntu-22.042026-08-03T01:50:17.7900451Z Version: 20260720.234.22026-08-03T01:50:17.7901742Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-03T01:50:17.7903338Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-03T01:50:17.7904395Z ##[endgroup]2026-08-03T01:50:17.7905641Z ##[group]GITHUB_TOKEN Permissions2026-08-03T01:50:17.7907744Z Contents: read2026-08-03T01:50:17.7908362Z Metadata: read2026-08-03T01:50:17.7909023Z Packages: read2026-08-03T01:50:17.7909825Z ##[endgroup]2026-08-03T01:50:17.7912219Z Secret source: Actions2026-08-03T01:50:17.7913463Z Prepare workflow directory2026-08-03T01:50:17.8244420Z Prepare all required actions2026-08-03T01:50:17.8292864Z Getting action download info2026-08-03T01:50:18.2867466Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-03T01:50:18.6932656Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-03T01:50:19.0327476Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-03T01:50:19.4170833Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-03T01:50:19.6909254Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-03T01:50:19.6916145Z ##[group] Inputs2026-08-03T01:50:19.6917220Z testName: TestOSPoolEP2026-08-03T01:50:19.6919054Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:50:19.6921220Z setupScript: 2026-08-03T01:50:19.6922112Z setupEnv: {}2026-08-03T01:50:19.6922984Z ##[endgroup]2026-08-03T01:50:19.6924369Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-08-03T01:50:19.7893443Z ##[group]Run actions/checkout@v52026-08-03T01:50:19.7894878Z with:2026-08-03T01:50:19.7895849Z repository: mwestphall/k8s-integration-tests2026-08-03T01:50:19.7905101Z token: ***2026-08-03T01:50:19.7906017Z ssh-strict: true2026-08-03T01:50:19.7906916Z ssh-user: git2026-08-03T01:50:19.7907817Z persist-credentials: true2026-08-03T01:50:19.7908807Z clean: true2026-08-03T01:50:19.7909985Z sparse-checkout-cone-mode: true2026-08-03T01:50:19.7911087Z fetch-depth: 12026-08-03T01:50:19.7911952Z fetch-tags: false2026-08-03T01:50:19.7912842Z show-progress: true2026-08-03T01:50:19.7913730Z lfs: false2026-08-03T01:50:19.7914557Z submodules: false2026-08-03T01:50:19.7915453Z set-safe-directory: true2026-08-03T01:50:19.7916480Z allow-unsafe-pr-checkout: false2026-08-03T01:50:19.7917916Z ##[endgroup]2026-08-03T01:50:19.9008073Z Syncing repository: mwestphall/k8s-integration-tests2026-08-03T01:50:19.9012841Z ##[group]Getting Git version info2026-08-03T01:50:19.9015578Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-03T01:50:19.9020336Z [command]/usr/bin/git version2026-08-03T01:50:19.9124398Z git version 2.54.02026-08-03T01:50:19.9151084Z ##[endgroup]2026-08-03T01:50:19.9167783Z Temporarily overriding HOME='/home/runner/work/_temp/4186eeb0-0376-44c6-bfd0-3bfa4eb69136' before making global git config changes2026-08-03T01:50:19.9173233Z Adding repository directory to the temporary git global config as a safe directory2026-08-03T01:50:19.9177756Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-03T01:50:19.9220540Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-03T01:50:19.9225348Z ##[group]Initializing the repository2026-08-03T01:50:19.9231577Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-03T01:50:19.9341254Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-03T01:50:19.9344677Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-03T01:50:19.9348391Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-03T01:50:19.9351440Z hint: call:2026-08-03T01:50:19.9352908Z hint:2026-08-03T01:50:19.9354716Z hint: git config --global init.defaultBranch <name>2026-08-03T01:50:19.9356930Z hint:2026-08-03T01:50:19.9359030Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-03T01:50:19.9362657Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-03T01:50:19.9365566Z hint:2026-08-03T01:50:19.9367075Z hint: git branch -m <name>2026-08-03T01:50:19.9368810Z hint:2026-08-03T01:50:19.9371449Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-03T01:50:19.9375707Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-03T01:50:19.9382749Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-03T01:50:19.9398330Z ##[endgroup]2026-08-03T01:50:19.9401326Z ##[group]Disabling automatic garbage collection2026-08-03T01:50:19.9403732Z [command]/usr/bin/git config --local gc.auto 02026-08-03T01:50:19.9438689Z ##[endgroup]2026-08-03T01:50:19.9441443Z ##[group]Setting up auth2026-08-03T01:50:19.9446547Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-03T01:50:19.9494162Z [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-08-03T01:50:19.9905546Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-03T01:50:19.9949068Z [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-08-03T01:50:20.0198237Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-03T01:50:20.0237291Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-03T01:50:20.0494229Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-03T01:50:20.0530600Z ##[endgroup]2026-08-03T01:50:20.0532066Z ##[group]Fetching the repository2026-08-03T01:50:20.0544372Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-03T01:50:20.4589927Z From https://github.com/mwestphall/k8s-integration-tests2026-08-03T01:50:20.4591157Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-03T01:50:20.4614932Z ##[endgroup]2026-08-03T01:50:20.4615716Z ##[group]Determining the checkout info2026-08-03T01:50:20.4618327Z ##[endgroup]2026-08-03T01:50:20.4624229Z [command]/usr/bin/git sparse-checkout disable2026-08-03T01:50:20.4671660Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-03T01:50:20.4704375Z ##[group]Checking out the ref2026-08-03T01:50:20.4708488Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-03T01:50:20.4803093Z Switched to a new branch 'main'2026-08-03T01:50:20.4806780Z branch 'main' set up to track 'origin/main'.2026-08-03T01:50:20.4811721Z ##[endgroup]2026-08-03T01:50:20.4851666Z [command]/usr/bin/git log -1 --format=%H2026-08-03T01:50:20.4879143Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-03T01:50:20.5106581Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-03T01:50:20.5107300Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-03T01:50:20.5107890Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-03T01:50:20.5225772Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:50:20.5226146Z env:2026-08-03T01:50:20.5226576Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:50:20.5227070Z SETUP_ENV_YAML: {}2026-08-03T01:50:20.5227276Z ##[endgroup]2026-08-03T01:50:21.1481359Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-03T01:50:21.1482043Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-03T01:50:21.1538125Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:50:21.1538473Z env:2026-08-03T01:50:21.1538933Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-03T01:50:21.1539444Z ##[endgroup]2026-08-03T01:50:21.1670205Z ##[group]Run true2026-08-03T01:50:21.1670473Z [36;1mtrue[0m2026-08-03T01:50:21.1721189Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:50:21.1721548Z ##[endgroup]2026-08-03T01:50:21.1872596Z ##[group]Run medyagh/setup-minikube@latest2026-08-03T01:50:21.1872909Z with:2026-08-03T01:50:21.1873091Z start: true2026-08-03T01:50:21.1873273Z cache: true2026-08-03T01:50:21.1873473Z minikube-version: latest2026-08-03T01:50:21.1873720Z wait: all2026-08-03T01:50:21.1873900Z ##[endgroup]2026-08-03T01:50:21.3141317Z (node:2235) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-03T01:50:21.3143530Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-03T01:50:22.2868959Z [command]/usr/bin/chmod +x /home/runner/work/_temp/f72ca60e-26fa-4013-8602-333a0fc4334d2026-08-03T01:50:22.4427775Z [command]/home/runner/bin/minikube version --short2026-08-03T01:50:22.4804243Z v1.38.12026-08-03T01:50:22.7338798Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-03T01:50:22.7457405Z (node:2235) [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-08-03T01:50:22.7466353Z Cache hit for: kic-v1.38.1-x642026-08-03T01:50:24.0421584Z Received 12582912 of 544082657 (2.3%), 12.0 MBs/sec2026-08-03T01:50:24.0595779Z Received 8388608 of 224218863 (3.7%), 8.0 MBs/sec2026-08-03T01:50:25.1199074Z Received 134217728 of 544082657 (24.7%), 61.6 MBs/sec2026-08-03T01:50:25.1199944Z Received 92274688 of 224218863 (41.2%), 42.7 MBs/sec2026-08-03T01:50:26.2030653Z Received 268435456 of 544082657 (49.3%), 81.0 MBs/sec2026-08-03T01:50:26.2035977Z Received 176160768 of 224218863 (78.6%), 53.5 MBs/sec2026-08-03T01:50:26.5462744Z Received 224218863 of 224218863 (100.0%), 61.3 MBs/sec2026-08-03T01:50:26.5464212Z Cache Size: ~214 MB (224218863 B)2026-08-03T01:50:26.6050842Z [command]/usr/bin/tar -xf /home/runner/work/_temp/91efb746-7c4b-43b6-81ad-3e4a2856684f/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-03T01:50:27.2320607Z Received 402653184 of 544082657 (74.0%), 91.6 MBs/sec2026-08-03T01:50:27.2350073Z Cache restored successfully2026-08-03T01:50:28.2322964Z Received 536870912 of 544082657 (98.7%), 98.6 MBs/sec2026-08-03T01:50:28.3469135Z Received 544082657 of 544082657 (100.0%), 97.8 MBs/sec2026-08-03T01:50:28.3470611Z Cache Size: ~519 MB (544082657 B)2026-08-03T01:50:28.3615369Z [command]/usr/bin/tar -xf /home/runner/work/_temp/8907db75-50ff-4fd9-9255-b13065d39b1d/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-03T01:50:29.2761222Z Cache restored successfully2026-08-03T01:50:29.3918337Z [command]/home/runner/bin/minikube start --wait all2026-08-03T01:50:29.4313264Z * minikube v1.38.1 on Ubuntu 22.042026-08-03T01:50:29.5842638Z ! 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-08-03T01:50:29.8649270Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-03T01:50:29.9298686Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-03T01:50:29.9302989Z * Using Docker driver with root privileges2026-08-03T01:50:29.9313030Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-03T01:50:29.9321590Z * Pulling base image v0.0.50 ...2026-08-03T01:51:01.6706111Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-03T01:51:06.3400406Z * Verifying Kubernetes components...2026-08-03T01:51:06.3622656Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-03T01:51:06.9295011Z * Enabled addons: storage-provisioner, default-storageclass2026-08-03T01:51:46.2334304Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-03T01:51:46.2434707Z [command]/home/runner/bin/minikube version --short2026-08-03T01:51:46.2828851Z v1.38.12026-08-03T01:51:46.3111007Z ##[group]Run actions/setup-go@v62026-08-03T01:51:46.3111291Z with:2026-08-03T01:51:46.3111482Z go-version: 1.262026-08-03T01:51:46.3111691Z cache: true2026-08-03T01:51:46.3111911Z cache-dependency-path: go.sum2026-08-03T01:51:46.3112163Z check-latest: false2026-08-03T01:51:46.3114490Z token: ***2026-08-03T01:51:46.3114686Z ##[endgroup]2026-08-03T01:51:46.4482816Z Setup go version spec 1.262026-08-03T01:51:46.4559809Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-03T01:51:46.4561922Z Added go to the path2026-08-03T01:51:46.4564951Z Successfully set up Go version 1.262026-08-03T01:51:47.0680566Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-03T01:51:47.0721852Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-03T01:51:47.0753851Z /home/runner/go/pkg/mod2026-08-03T01:51:47.0780359Z /home/runner/.cache/go-build2026-08-03T01:51:47.3565109Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-03T01:51:48.6384110Z Received 4194304 of 251839808 (1.7%), 4.0 MBs/sec2026-08-03T01:51:49.6376453Z Received 117440512 of 251839808 (46.6%), 56.0 MBs/sec2026-08-03T01:51:50.6403430Z Received 247645504 of 251839808 (98.3%), 78.7 MBs/sec2026-08-03T01:51:50.7511329Z Received 251839808 of 251839808 (100.0%), 77.1 MBs/sec2026-08-03T01:51:50.7512741Z Cache Size: ~240 MB (251839808 B)2026-08-03T01:51:50.7555450Z [command]/usr/bin/tar -xf /home/runner/work/_temp/a63b451e-338b-4193-948e-0bdbcd9cac31/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-03T01:51:53.7999105Z Cache restored successfully2026-08-03T01:51:53.8540038Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-03T01:51:53.8561569Z go version go1.26.5 linux/amd642026-08-03T01:51:53.8562068Z 2026-08-03T01:51:53.8562407Z ##[group]go env2026-08-03T01:51:53.8889742Z AR='ar'2026-08-03T01:51:53.8890161Z CC='gcc'2026-08-03T01:51:53.8890543Z CGO_CFLAGS='-O2 -g'2026-08-03T01:51:53.8890953Z CGO_CPPFLAGS=''2026-08-03T01:51:53.8891350Z CGO_CXXFLAGS='-O2 -g'2026-08-03T01:51:53.8891617Z CGO_ENABLED='1'2026-08-03T01:51:53.8891857Z CGO_FFLAGS='-O2 -g'2026-08-03T01:51:53.8892110Z CGO_LDFLAGS='-O2 -g'2026-08-03T01:51:53.8892349Z CXX='g++'2026-08-03T01:51:53.8892573Z GCCGO='gccgo'2026-08-03T01:51:53.8892813Z GO111MODULE=''2026-08-03T01:51:53.8893040Z GOAMD64='v1'2026-08-03T01:51:53.8893271Z GOARCH='amd64'2026-08-03T01:51:53.8893494Z GOAUTH='netrc'2026-08-03T01:51:53.8893717Z GOBIN=''2026-08-03T01:51:53.8893961Z GOCACHE='/home/runner/.cache/go-build'2026-08-03T01:51:53.8894298Z GOCACHEPROG=''2026-08-03T01:51:53.8894523Z GODEBUG=''2026-08-03T01:51:53.8894761Z GOENV='/home/runner/.config/go/env'2026-08-03T01:51:53.8895056Z GOEXE=''2026-08-03T01:51:53.8895282Z GOEXPERIMENT=''2026-08-03T01:51:53.8895520Z GOFIPS140='off'2026-08-03T01:51:53.8895752Z GOFLAGS=''2026-08-03T01:51:53.8896511Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build2146961186=/tmp/go-build -gno-record-gcc-switches'2026-08-03T01:51:53.8897285Z GOHOSTARCH='amd64'2026-08-03T01:51:53.8897527Z GOHOSTOS='linux'2026-08-03T01:51:53.8897765Z GOINSECURE=''2026-08-03T01:51:53.8898158Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-03T01:51:53.8898650Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-03T01:51:53.8898953Z GONOPROXY=''2026-08-03T01:51:53.8899174Z GONOSUMDB=''2026-08-03T01:51:53.8899397Z GOOS='linux'2026-08-03T01:51:53.8899952Z GOPATH='/home/runner/go'2026-08-03T01:51:53.8900230Z GOPRIVATE=''2026-08-03T01:51:53.8900521Z GOPROXY='https://proxy.golang.org,direct'2026-08-03T01:51:53.8900880Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-03T01:51:53.8901186Z GOSUMDB='sum.golang.org'2026-08-03T01:51:53.8901466Z GOTELEMETRY='local'2026-08-03T01:51:53.8902012Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-03T01:51:53.8902352Z GOTMPDIR=''2026-08-03T01:51:53.8902573Z GOTOOLCHAIN='local'2026-08-03T01:51:53.8902910Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-03T01:51:53.8903295Z GOVCS=''2026-08-03T01:51:53.8903496Z GOVERSION='go1.26.5'2026-08-03T01:51:53.8903717Z GOWORK=''2026-08-03T01:51:53.8903926Z PKG_CONFIG='pkg-config'2026-08-03T01:51:53.8904077Z 2026-08-03T01:51:53.8904412Z ##[endgroup]2026-08-03T01:51:53.9094058Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-03T01:51:53.9094822Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-03T01:51:53.9150826Z shell: /usr/bin/bash -e {0}2026-08-03T01:51:53.9151079Z env:2026-08-03T01:51:53.9151278Z GOTOOLCHAIN: local2026-08-03T01:51:53.9151515Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-08-03T01:51:53.9151800Z OSPOOL_EP_CMTag: 25.x-el92026-08-03T01:51:53.9152070Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:51:53.9152319Z ##[endgroup]2026-08-03T01:52:00.3285050Z === RUN TestOSPoolEP2026-08-03T01:52:00.3287966Z === PAUSE TestOSPoolEP2026-08-03T01:52:00.3288449Z === CONT TestOSPoolEP2026-08-03T01:52:00.3289800Z TestOSPoolEP 2026-08-03T01:52:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:00.3813499Z TestOSPoolEP 2026-08-03T01:52:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi apply -f /tmp/TestOSPoolEP4150294320]2026-08-03T01:52:01.3959560Z TestOSPoolEP 2026-08-03T01:52:01Z logger.go:67: pod/idtoken-generator created2026-08-03T01:52:01.4071274Z TestOSPoolEP 2026-08-03T01:52:01Z logger.go:67: secret/pool-passwd created2026-08-03T01:52:01.4104135Z TestOSPoolEP 2026-08-03T01:52:01Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-03T01:52:01.4105705Z TestOSPoolEP 2026-08-03T01:52:01Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:01.4130047Z TestOSPoolEP 2026-08-03T01:52:01Z 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-08-03T01:52:11.4131683Z TestOSPoolEP 2026-08-03T01:52:11Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-03T01:52:11.4136008Z TestOSPoolEP 2026-08-03T01:52:11Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:11.4165119Z TestOSPoolEP 2026-08-03T01:52:11Z 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-08-03T01:52:21.4164971Z TestOSPoolEP 2026-08-03T01:52:21Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-03T01:52:21.4166611Z TestOSPoolEP 2026-08-03T01:52:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:21.4194692Z TestOSPoolEP 2026-08-03T01:52:21Z logger.go:67: Pod is now available2026-08-03T01:52:21.4196991Z TestOSPoolEP 2026-08-03T01:52:21Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-03T01:52:21.9968484Z TestOSPoolEP 2026-08-03T01:52:21Z logger.go:67: ***2026-08-03T01:52:22.0010220Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi apply -f /tmp/TestOSPoolEP3583440369]2026-08-03T01:52:22.1918704Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: secret/pool-token created2026-08-03T01:52:22.1947211Z TestOSPoolEP 2026-08-03T01:52:22Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-03T01:52:22.1948594Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:22.1966425Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: Secret is now available2026-08-03T01:52:22.1990569Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi apply -k /tmp/kustomize-template-4170922836]2026-08-03T01:52:22.4974381Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: service/test-cm created2026-08-03T01:52:22.5040565Z TestOSPoolEP 2026-08-03T01:52:22Z 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-08-03T01:52:22.5053825Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: deployment.apps/ospool-ep created2026-08-03T01:52:22.5138351Z TestOSPoolEP 2026-08-03T01:52:22Z logger.go:67: deployment.apps/test-cm created2026-08-03T01:52:22.5218770Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-03T01:52:22.5233975Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:22.5336465Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-03T01:52:22.5343676Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:22.5347047Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:22.5358028Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:22.5411167Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z 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-08-03T01:52:22.5426807Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:22Z 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-08-03T01:52:32.5423076Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:32Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-03T01:52:32.5425106Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:32Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:32.5430970Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:32Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:32.5433141Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:32Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:32.5452417Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:32Z logger.go:67: Deployment is now available2026-08-03T01:52:32.5460247Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:32Z 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-08-03T01:52:42.5462802Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:42Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:42.5463948Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:42Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:42.5492990Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:42Z 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-08-03T01:52:52.5492976Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:52Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:52.5494568Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5515157Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:52Z logger.go:67: Deployment is now available2026-08-03T01:52:52.5516298Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:52:52.5517039Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:52:52.5517810Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:52:52.5518618Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:52:52.5519098Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:52:52.5520123Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:52:52.5520598Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:52:52.5521632Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5522998Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:52:52.5523673Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:52:52.5524628Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5526164Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5561097Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5563382Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5577774Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:52:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:52.5581602Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:52:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)']2026-08-03T01:52:52.5584848Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:52:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_SINGULARITY]2026-08-03T01:52:52.5611009Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:52:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:52:52.7508350Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:52:52.7510558Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:52:52.8337781Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:52:52.8339784Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)']' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 10s.2026-08-03T01:52:52.8442629Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:52:52.8444019Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 10s.2026-08-03T01:53:02.8354453Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)']2026-08-03T01:53:02.8447960Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_SINGULARITY]2026-08-03T01:53:03.0398019Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 10s.2026-08-03T01:53:03.0409165Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:53:03.0411089Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)']' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 10s.2026-08-03T01:53:13.0412840Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:13Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_SINGULARITY]2026-08-03T01:53:13.0416811Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)']2026-08-03T01:53:13.2333238Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-03T01:53:13.2334934Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: 2026-08-03T01:53:13.2344907Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-rpqzm LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-08-03T01:53:13.2346738Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: 2026-08-03T01:53:13.2348385Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-03T01:53:13.2350110Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: 2026-08-03T01:53:13.2351562Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-03T01:53:13.2352755Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: 2026-08-03T01:53:13.2354009Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:13Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-03T01:53:13.2356153Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:13Z logger.go:67: true2026-08-03T01:53:22.7521358Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:53:22.9035768Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:22Z logger.go:67: undefined2026-08-03T01:53:22.9101473Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:53:22.9103104Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:53:52.9111617Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:53:53.0993281Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:53Z logger.go:67: undefined2026-08-03T01:53:53.1058948Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:54:23.1065022Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:54:23.2867211Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:23Z logger.go:67: undefined2026-08-03T01:54:23.2922876Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:54:53.2926590Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:54:53.4614327Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:53Z logger.go:67: undefined2026-08-03T01:54:53.4676543Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:55:23.4701181Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:55:23.6390500Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:23Z logger.go:67: undefined2026-08-03T01:55:23.6454117Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:55:53.6484040Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:55:53.7938345Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:53Z logger.go:67: undefined2026-08-03T01:55:53.7970769Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:56:23.8001963Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:56:23.9524531Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:23Z logger.go:67: undefined2026-08-03T01:56:23.9577124Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:56:53.9581664Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:56:54.1056957Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:54Z logger.go:67: undefined2026-08-03T01:56:54.1117505Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:57:24.1119286Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:57:24.2597299Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:24Z logger.go:67: undefined2026-08-03T01:57:24.2657503Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:57:54.2692189Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:54Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:57:54.4123975Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:54Z logger.go:67: undefined2026-08-03T01:57:54.4175374Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-jsqsh failed. Retrying in 30s.2026-08-03T01:58:24.4210461Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:58:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi exec test-cm-5bb545475c-jsqsh -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-rpqzm",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:58:24.6034351Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:58:24Z logger.go:67: true2026-08-03T01:58:24.6098481Z TestOSPoolEP 2026-08-03T01:58:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:24.6187419Z TestOSPoolEP 2026-08-03T01:58:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:24.8308634Z TestOSPoolEP 2026-08-03T01:58:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:24.9773989Z TestOSPoolEP 2026-08-03T01:58:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:25.0879413Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:25.0905253Z === NAME TestOSPoolEP2026-08-03T01:58:25.0905843Z ospool_ep_test.go:101: ---2026-08-03T01:58:25.0906419Z Events for pod idtoken-generator:2026-08-03T01:58:25.0907796Z 2026-08-03 01:52:01 +0000 UTC Normal Successfully assigned test-ospool-ep-h4uboi/idtoken-generator to minikube2026-08-03T01:58:25.0909150Z 2026-08-03 01:52:02 +0000 UTC Normal Pulling image "htcondor/base"2026-08-03T01:58:25.0911345Z 2026-08-03 01:52:11 +0000 UTC Normal Successfully pulled image "htcondor/base" in 9.326s (9.326s including waiting). Image size: 560910730 bytes.2026-08-03T01:58:25.0912743Z 2026-08-03 01:52:11 +0000 UTC Normal Container created2026-08-03T01:58:25.0913674Z 2026-08-03 01:52:11 +0000 UTC Normal Container started2026-08-03T01:58:25.0914279Z 2026-08-03T01:58:25.0914677Z ---2026-08-03T01:58:25.0915754Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:25.0937206Z ospool_ep_test.go:101: ---2026-08-03T01:58:25.0938101Z Events for pod ospool-ep-65c844ddbb-rpqzm:2026-08-03T01:58:25.0940157Z 2026-08-03 01:52:22 +0000 UTC Normal Successfully assigned test-ospool-ep-h4uboi/ospool-ep-65c844ddbb-rpqzm to minikube2026-08-03T01:58:25.0942375Z 2026-08-03 01:52:23 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-03T01:58:25.0945238Z 2026-08-03 01:52:25 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.462s (2.463s including waiting). Image size: 5590942 bytes.2026-08-03T01:58:25.0947543Z 2026-08-03 01:52:25 +0000 UTC Normal Container created2026-08-03T01:58:25.0949007Z 2026-08-03 01:52:25 +0000 UTC Normal Container started2026-08-03T01:58:25.0951227Z 2026-08-03 01:52:26 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-08-03T01:58:25.0955386Z 2026-08-03 01:52:43 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 16.656s (17.1s including waiting). Image size: 1250776798 bytes.2026-08-03T01:58:25.0957494Z 2026-08-03 01:52:43 +0000 UTC Normal Container created2026-08-03T01:58:25.0958773Z 2026-08-03 01:52:43 +0000 UTC Normal Container started2026-08-03T01:58:25.0959730Z 2026-08-03T01:58:25.0959986Z ---2026-08-03T01:58:25.0961064Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:25.0974775Z ospool_ep_test.go:101: ---2026-08-03T01:58:25.0975659Z Events for pod test-cm-5bb545475c-jsqsh:2026-08-03T01:58:25.0977124Z 2026-08-03 01:52:22 +0000 UTC Normal Successfully assigned test-ospool-ep-h4uboi/test-cm-5bb545475c-jsqsh to minikube2026-08-03T01:58:25.0978536Z 2026-08-03 01:52:23 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-08-03T01:58:25.0980699Z 2026-08-03 01:52:26 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 1.383s (3.834s including waiting). Image size: 560910756 bytes.2026-08-03T01:58:25.1000771Z 2026-08-03 01:52:26 +0000 UTC Normal Container created2026-08-03T01:58:25.1001760Z 2026-08-03 01:52:26 +0000 UTC Normal Container started2026-08-03T01:58:25.1002368Z 2026-08-03T01:58:25.1002743Z ---2026-08-03T01:58:25.1003743Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:25.1015519Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi delete -f /tmp/TestOSPoolEP3688528122]2026-08-03T01:58:25.2084925Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-h4uboi namespace2026-08-03T01:58:25.2157908Z TestOSPoolEP 2026-08-03T01:58:25Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-h4uboi namespace2026-08-03T01:58:26.5760088Z TestOSPoolEP 2026-08-03T01:58:26Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi delete -f /tmp/TestOSPoolEP2532006702]2026-08-03T01:58:26.6715252Z TestOSPoolEP 2026-08-03T01:58:26Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-h4uboi namespace2026-08-03T01:58:26.6757602Z TestOSPoolEP 2026-08-03T01:58:26Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-h4uboi delete -k /tmp/kustomize-template-4170922836]2026-08-03T01:58:26.7858530Z TestOSPoolEP 2026-08-03T01:58:26Z logger.go:67: service "test-cm" deleted from test-ospool-ep-h4uboi namespace2026-08-03T01:58:26.7914041Z TestOSPoolEP 2026-08-03T01:58:26Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-h4uboi namespace2026-08-03T01:58:26.7956927Z TestOSPoolEP 2026-08-03T01:58:26Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-h4uboi namespace2026-08-03T01:58:26.8061658Z --- PASS: TestOSPoolEP (54.42s)2026-08-03T01:58:26.8062829Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-08-03T01:58:26.8070485Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.69s)2026-08-03T01:58:26.8072088Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.69s)2026-08-03T01:58:26.8073234Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.06s)2026-08-03T01:58:26.8074401Z PASS2026-08-03T01:58:26.8124334Z ok github.com/osg-htc/k8s-integration-tests/test 386.493s2026-08-03T01:58:26.9306346Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-03T01:58:26.9307063Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-03T01:58:26.9381622Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:58:26.9382245Z env:2026-08-03T01:58:26.9382576Z GOTOOLCHAIN: local2026-08-03T01:58:26.9383343Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:58:26.9384203Z ##[endgroup]2026-08-03T01:58:26.9589192Z 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-08-03T01:58:26.9591504Z ##[group]Run actions/upload-artifact@v42026-08-03T01:58:26.9591972Z with:2026-08-03T01:58:26.9592635Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-08-03T01:58:26.9593115Z path: /tmp/k8s-tests2026-08-03T01:58:26.9593356Z retention-days: 52026-08-03T01:58:26.9593587Z if-no-files-found: warn2026-08-03T01:58:26.9593823Z compression-level: 62026-08-03T01:58:26.9594201Z overwrite: false2026-08-03T01:58:26.9594543Z include-hidden-files: false2026-08-03T01:58:26.9594790Z env:2026-08-03T01:58:26.9594980Z GOTOOLCHAIN: local2026-08-03T01:58:26.9595189Z ##[endgroup]2026-08-03T01:58:27.1678804Z (node:18041) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-03T01:58:27.1679919Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-03T01:58:27.1768102Z With the provided path, there will be 6 files uploaded2026-08-03T01:58:27.1775116Z Artifact name is valid!2026-08-03T01:58:27.1776195Z Root directory input is valid!2026-08-03T01:58:27.4902571Z Beginning upload of artifact content to blob storage2026-08-03T01:58:27.5307954Z (node:18041) [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-08-03T01:58:27.8627616Z Uploaded bytes 140062026-08-03T01:58:27.9388547Z Finished uploading artifact content to blob storage!2026-08-03T01:58:27.9390256Z SHA256 digest of uploaded artifact zip is 1b1544f3a698f0235f8c2a44897ead16141d96c64aef85e92abcbd4be4d3c1752026-08-03T01:58:27.9391374Z Finalizing artifact upload2026-08-03T01:58:28.1464518Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 88427417092026-08-03T01:58:28.1466911Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 14006 bytes. Artifact ID is 88427417092026-08-03T01:58:28.1472927Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30777819006/artifacts/88427417092026-08-03T01:58:28.1648057Z Post job cleanup.2026-08-03T01:58:28.2974238Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-03T01:58:28.3030782Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-03T01:58:28.3071806Z /home/runner/go/pkg/mod2026-08-03T01:58:28.3102327Z /home/runner/.cache/go-build2026-08-03T01:58:28.3108549Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-03T01:58:28.3302804Z Post job cleanup.2026-08-03T01:58:28.4291279Z [command]/usr/bin/git version2026-08-03T01:58:28.4335813Z git version 2.54.02026-08-03T01:58:28.4376960Z Temporarily overriding HOME='/home/runner/work/_temp/91700798-ba7a-4489-8ba4-70c372ada9c4' before making global git config changes2026-08-03T01:58:28.4378402Z Adding repository directory to the temporary git global config as a safe directory2026-08-03T01:58:28.4382133Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-03T01:58:28.4429023Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-03T01:58:28.4476878Z [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-08-03T01:58:28.4772169Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-03T01:58:28.4798458Z http.https://github.com/.extraheader2026-08-03T01:58:28.4812759Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-03T01:58:28.4852443Z [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-08-03T01:58:28.5139414Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-03T01:58:28.5187394Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-03T01:58:28.5605651Z Cleaning up orphan processes2026-08-03T01:58:28.6514013Z ##[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/