2026-08-15T00:37:31.2845492Z Current runner version: '2.336.0'2026-08-15T00:37:31.2872173Z ##[group]Runner Image Provisioner2026-08-15T00:37:31.2873433Z Hosted Compute Agent2026-08-15T00:37:31.2874177Z Version: 20260729.5662026-08-15T00:37:31.2874846Z Commit: cf7153fe6e25b664e8693c24944bf2b00355d1092026-08-15T00:37:31.2875588Z Build Date: 2026-07-29T19:17:02Z2026-08-15T00:37:31.2876418Z Worker ID: {8cb7dbbf-6a8b-454a-8800-6d387102c7b3}2026-08-15T00:37:31.2877176Z Azure Region: centralus2026-08-15T00:37:31.2877857Z ##[endgroup]2026-08-15T00:37:31.2879707Z ##[group]Operating System2026-08-15T00:37:31.2880366Z Ubuntu2026-08-15T00:37:31.2881082Z 22.04.52026-08-15T00:37:31.2881625Z LTS2026-08-15T00:37:31.2882154Z ##[endgroup]2026-08-15T00:37:31.2883157Z ##[group]Runner Image2026-08-15T00:37:31.2883807Z Image: ubuntu-22.042026-08-15T00:37:31.2884474Z Version: 20260810.260.12026-08-15T00:37:31.2885814Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260810.260/images/ubuntu/Ubuntu2204-Readme.md2026-08-15T00:37:31.2887382Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260810.2602026-08-15T00:37:31.2888390Z ##[endgroup]2026-08-15T00:37:31.2889787Z ##[group]GITHUB_TOKEN Permissions2026-08-15T00:37:31.2892171Z Contents: read2026-08-15T00:37:31.2893085Z Metadata: read2026-08-15T00:37:31.2893822Z Packages: read2026-08-15T00:37:31.2894427Z ##[endgroup]2026-08-15T00:37:31.2897525Z Secret source: Actions2026-08-15T00:37:31.2898988Z Prepare workflow directory2026-08-15T00:37:31.3238503Z Prepare all required actions2026-08-15T00:37:31.3287981Z Getting action download info2026-08-15T00:37:31.6059688Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-15T00:37:31.7163115Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-15T00:37:32.0919731Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-15T00:37:32.4567253Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-15T00:37:32.7783359Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-15T00:37:32.7788549Z ##[group] Inputs2026-08-15T00:37:32.7789182Z testName: TestOSPoolEP2026-08-15T00:37:32.7790155Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:37:32.7791183Z setupScript: 2026-08-15T00:37:32.7791702Z setupEnv: {}2026-08-15T00:37:32.7792228Z ##[endgroup]2026-08-15T00:37:32.7793328Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-08-15T00:37:32.8615706Z ##[group]Run actions/checkout@v52026-08-15T00:37:32.8616624Z with:2026-08-15T00:37:32.8617185Z repository: mwestphall/k8s-integration-tests2026-08-15T00:37:32.8621682Z token: ***2026-08-15T00:37:32.8622193Z ssh-strict: true2026-08-15T00:37:32.8622919Z ssh-user: git2026-08-15T00:37:32.8623456Z persist-credentials: true2026-08-15T00:37:32.8624010Z clean: true2026-08-15T00:37:32.8624521Z sparse-checkout-cone-mode: true2026-08-15T00:37:32.8625116Z fetch-depth: 12026-08-15T00:37:32.8625604Z fetch-tags: false2026-08-15T00:37:32.8626114Z show-progress: true2026-08-15T00:37:32.8626616Z lfs: false2026-08-15T00:37:32.8627429Z submodules: false2026-08-15T00:37:32.8628001Z set-safe-directory: true2026-08-15T00:37:32.8628572Z allow-unsafe-pr-checkout: false2026-08-15T00:37:32.8629400Z ##[endgroup]2026-08-15T00:37:32.9736218Z Syncing repository: mwestphall/k8s-integration-tests2026-08-15T00:37:32.9738689Z ##[group]Getting Git version info2026-08-15T00:37:32.9739832Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-15T00:37:32.9741511Z [command]/usr/bin/git version2026-08-15T00:37:32.9793994Z git version 2.54.02026-08-15T00:37:32.9816804Z ##[endgroup]2026-08-15T00:37:32.9833210Z Temporarily overriding HOME='/home/runner/work/_temp/d5bad675-8c14-4d8f-84c3-c10d41070de8' before making global git config changes2026-08-15T00:37:32.9835613Z Adding repository directory to the temporary git global config as a safe directory2026-08-15T00:37:32.9841355Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-15T00:37:32.9885495Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-15T00:37:32.9889160Z ##[group]Initializing the repository2026-08-15T00:37:32.9894206Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-15T00:37:33.0005667Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-15T00:37:33.0007780Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-15T00:37:33.0009417Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-15T00:37:33.0010751Z hint: call:2026-08-15T00:37:33.0011465Z hint:2026-08-15T00:37:33.0012360Z hint: git config --global init.defaultBranch <name>2026-08-15T00:37:33.0013624Z hint:2026-08-15T00:37:33.0014632Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-15T00:37:33.0016023Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-15T00:37:33.0017034Z hint:2026-08-15T00:37:33.0017519Z hint: git branch -m <name>2026-08-15T00:37:33.0018214Z hint:2026-08-15T00:37:33.0018920Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-15T00:37:33.0020180Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-15T00:37:33.0022393Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-15T00:37:33.0066189Z ##[endgroup]2026-08-15T00:37:33.0067572Z ##[group]Disabling automatic garbage collection2026-08-15T00:37:33.0070050Z [command]/usr/bin/git config --local gc.auto 02026-08-15T00:37:33.0109644Z ##[endgroup]2026-08-15T00:37:33.0110909Z ##[group]Setting up auth2026-08-15T00:37:33.0117777Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-15T00:37:33.0164147Z [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-15T00:37:33.0567636Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-15T00:37:33.0610174Z [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-15T00:37:33.0914431Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-15T00:37:33.0958418Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-15T00:37:33.1245166Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-15T00:37:33.1290330Z ##[endgroup]2026-08-15T00:37:33.1299346Z ##[group]Fetching the repository2026-08-15T00:37:33.1300809Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-15T00:37:33.5009563Z From https://github.com/mwestphall/k8s-integration-tests2026-08-15T00:37:33.5010920Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-15T00:37:33.5042088Z ##[endgroup]2026-08-15T00:37:33.5043393Z ##[group]Determining the checkout info2026-08-15T00:37:33.5045406Z ##[endgroup]2026-08-15T00:37:33.5051583Z [command]/usr/bin/git sparse-checkout disable2026-08-15T00:37:33.5109158Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-15T00:37:33.5150945Z ##[group]Checking out the ref2026-08-15T00:37:33.5155224Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-15T00:37:33.5257600Z Switched to a new branch 'main'2026-08-15T00:37:33.5260769Z branch 'main' set up to track 'origin/main'.2026-08-15T00:37:33.5268091Z ##[endgroup]2026-08-15T00:37:33.5352261Z [command]/usr/bin/git log -1 --format=%H2026-08-15T00:37:33.5386045Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-15T00:37:33.5759257Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-15T00:37:33.5760891Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-15T00:37:33.5762382Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-15T00:37:33.6124998Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:37:33.6125911Z env:2026-08-15T00:37:33.6126976Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:37:33.6128258Z SETUP_ENV_YAML: {}2026-08-15T00:37:33.6128784Z ##[endgroup]2026-08-15T00:37:34.2894004Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-15T00:37:34.2896623Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-15T00:37:34.2974642Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:37:34.2976035Z env:2026-08-15T00:37:34.2977903Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-15T00:37:34.2980222Z ##[endgroup]2026-08-15T00:37:34.3186322Z ##[group]Run true2026-08-15T00:37:34.3187094Z [36;1mtrue[0m2026-08-15T00:37:34.3257754Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:37:34.3259043Z ##[endgroup]2026-08-15T00:37:34.3548396Z ##[group]Run medyagh/setup-minikube@latest2026-08-15T00:37:34.3549471Z with:2026-08-15T00:37:34.3550153Z start: true2026-08-15T00:37:34.3550871Z cache: true2026-08-15T00:37:34.3551613Z minikube-version: latest2026-08-15T00:37:34.3552671Z wait: all2026-08-15T00:37:34.3553402Z ##[endgroup]2026-08-15T00:37:34.4959804Z (node:1936) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-15T00:37:34.4963590Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-15T00:37:35.6124061Z [command]/usr/bin/chmod +x /home/runner/work/_temp/38a0f54d-bada-4db1-9982-1154384e7e4a2026-08-15T00:37:35.7837562Z [command]/home/runner/bin/minikube version --short2026-08-15T00:37:35.8403539Z v1.38.12026-08-15T00:37:35.9784980Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-15T00:37:35.9799671Z Cache hit for: kic-v1.38.1-x642026-08-15T00:37:35.9905461Z (node:1936) [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-15T00:37:37.1203771Z Received 96468992 of 544082657 (17.7%), 91.7 MBs/sec2026-08-15T00:37:37.1346615Z Received 83886080 of 224218863 (37.4%), 80.0 MBs/sec2026-08-15T00:37:38.1420061Z Received 224218863 of 224218863 (100.0%), 106.5 MBs/sec2026-08-15T00:37:38.1420749Z Cache Size: ~214 MB (224218863 B)2026-08-15T00:37:38.1428799Z Received 226492416 of 544082657 (41.6%), 106.7 MBs/sec2026-08-15T00:37:38.2045226Z [command]/usr/bin/tar -xf /home/runner/work/_temp/3dd253bd-2236-4945-bcd1-c5cf08abe0b0/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-15T00:37:38.8966819Z Cache restored successfully2026-08-15T00:37:39.1430707Z Received 385875968 of 544082657 (70.9%), 121.7 MBs/sec2026-08-15T00:37:40.1438974Z Received 528482304 of 544082657 (97.1%), 125.2 MBs/sec2026-08-15T00:37:40.6823906Z Received 544082657 of 544082657 (100.0%), 113.7 MBs/sec2026-08-15T00:37:40.6824521Z Cache Size: ~519 MB (544082657 B)2026-08-15T00:37:40.6943325Z [command]/usr/bin/tar -xf /home/runner/work/_temp/5599bf20-6229-4690-9eeb-c0621b5452e0/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-15T00:37:41.6521568Z Cache restored successfully2026-08-15T00:37:41.7785857Z [command]/home/runner/bin/minikube start --wait all2026-08-15T00:37:42.1681036Z * minikube v1.38.1 on Ubuntu 22.042026-08-15T00:37:48.9728475Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-15T00:37:49.0640834Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-15T00:37:49.0645805Z * Using Docker driver with root privileges2026-08-15T00:37:49.0655632Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-15T00:37:49.0663418Z * Pulling base image v0.0.50 ...2026-08-15T00:38:21.1806151Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-15T00:38:26.3556894Z * Verifying Kubernetes components...2026-08-15T00:38:26.3879741Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-15T00:38:27.0251186Z * Enabled addons: default-storageclass, storage-provisioner2026-08-15T00:39:07.1738265Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-15T00:39:07.1857371Z [command]/home/runner/bin/minikube version --short2026-08-15T00:39:07.2345207Z v1.38.12026-08-15T00:39:07.2675606Z ##[group]Run actions/setup-go@v62026-08-15T00:39:07.2675894Z with:2026-08-15T00:39:07.2676100Z go-version: 1.262026-08-15T00:39:07.2676320Z cache: true2026-08-15T00:39:07.2676555Z cache-dependency-path: go.sum2026-08-15T00:39:07.2676822Z check-latest: false2026-08-15T00:39:07.2679539Z token: ***2026-08-15T00:39:07.2679761Z ##[endgroup]2026-08-15T00:39:07.4053607Z Setup go version spec 1.262026-08-15T00:39:07.4289734Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-15T00:39:07.4292801Z Added go to the path2026-08-15T00:39:07.4296721Z Successfully set up Go version 1.262026-08-15T00:39:08.0558401Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-15T00:39:08.0597208Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-15T00:39:08.0629765Z /home/runner/go/pkg/mod2026-08-15T00:39:08.0658802Z /home/runner/.cache/go-build2026-08-15T00:39:08.2046318Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-15T00:39:09.3554186Z Received 113246208 of 251839808 (45.0%), 107.9 MBs/sec2026-08-15T00:39:10.3283361Z Received 251839808 of 251839808 (100.0%), 121.5 MBs/sec2026-08-15T00:39:10.3285133Z Cache Size: ~240 MB (251839808 B)2026-08-15T00:39:10.3400820Z [command]/usr/bin/tar -xf /home/runner/work/_temp/cf8bd402-f7e1-451f-a6b9-6186221fb222/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-15T00:39:13.4983481Z Cache restored successfully2026-08-15T00:39:13.5613457Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-15T00:39:13.5642106Z go version go1.26.5 linux/amd642026-08-15T00:39:13.5642328Z 2026-08-15T00:39:13.5642886Z ##[group]go env2026-08-15T00:39:13.5976844Z AR='ar'2026-08-15T00:39:13.5977402Z CC='gcc'2026-08-15T00:39:13.5977892Z CGO_CFLAGS='-O2 -g'2026-08-15T00:39:13.5978312Z CGO_CPPFLAGS=''2026-08-15T00:39:13.5978680Z CGO_CXXFLAGS='-O2 -g'2026-08-15T00:39:13.5979064Z CGO_ENABLED='1'2026-08-15T00:39:13.5979407Z CGO_FFLAGS='-O2 -g'2026-08-15T00:39:13.5979800Z CGO_LDFLAGS='-O2 -g'2026-08-15T00:39:13.5980089Z CXX='g++'2026-08-15T00:39:13.6004254Z GCCGO='gccgo'2026-08-15T00:39:13.6004681Z GO111MODULE=''2026-08-15T00:39:13.6005017Z GOAMD64='v1'2026-08-15T00:39:13.6005689Z GOARCH='amd64'2026-08-15T00:39:13.6006018Z GOAUTH='netrc'2026-08-15T00:39:13.6006326Z GOBIN=''2026-08-15T00:39:13.6006696Z GOCACHE='/home/runner/.cache/go-build'2026-08-15T00:39:13.6007163Z GOCACHEPROG=''2026-08-15T00:39:13.6007519Z GODEBUG=''2026-08-15T00:39:13.6007882Z GOENV='/home/runner/.config/go/env'2026-08-15T00:39:13.6008301Z GOEXE=''2026-08-15T00:39:13.6008621Z GOEXPERIMENT=''2026-08-15T00:39:13.6008951Z GOFIPS140='off'2026-08-15T00:39:13.6009268Z GOFLAGS=''2026-08-15T00:39:13.6010376Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build3561187784=/tmp/go-build -gno-record-gcc-switches'2026-08-15T00:39:13.6011475Z GOHOSTARCH='amd64'2026-08-15T00:39:13.6011816Z GOHOSTOS='linux'2026-08-15T00:39:13.6012149Z GOINSECURE=''2026-08-15T00:39:13.6013259Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-15T00:39:13.6013966Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-15T00:39:13.6014413Z GONOPROXY=''2026-08-15T00:39:13.6014732Z GONOSUMDB=''2026-08-15T00:39:13.6015030Z GOOS='linux'2026-08-15T00:39:13.6015371Z GOPATH='/home/runner/go'2026-08-15T00:39:13.6015746Z GOPRIVATE=''2026-08-15T00:39:13.6016162Z GOPROXY='https://proxy.golang.org,direct'2026-08-15T00:39:13.6016708Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-15T00:39:13.6017206Z GOSUMDB='sum.golang.org'2026-08-15T00:39:13.6017585Z GOTELEMETRY='local'2026-08-15T00:39:13.6018017Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-15T00:39:13.6018519Z GOTMPDIR=''2026-08-15T00:39:13.6018837Z GOTOOLCHAIN='local'2026-08-15T00:39:13.6019651Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-15T00:39:13.6020238Z GOVCS=''2026-08-15T00:39:13.6020560Z GOVERSION='go1.26.5'2026-08-15T00:39:13.6020904Z GOWORK=''2026-08-15T00:39:13.6021235Z PKG_CONFIG='pkg-config'2026-08-15T00:39:13.6021478Z 2026-08-15T00:39:13.6021959Z ##[endgroup]2026-08-15T00:39:13.6253136Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-15T00:39:13.6253957Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-15T00:39:13.6334895Z shell: /usr/bin/bash -e {0}2026-08-15T00:39:13.6335165Z env:2026-08-15T00:39:13.6335383Z GOTOOLCHAIN: local2026-08-15T00:39:13.6335641Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-08-15T00:39:13.6335932Z OSPOOL_EP_CMTag: 25.x-el92026-08-15T00:39:13.6336201Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:39:13.6336473Z ##[endgroup]2026-08-15T00:39:22.1679587Z === RUN TestOSPoolEP2026-08-15T00:39:22.1682046Z === PAUSE TestOSPoolEP2026-08-15T00:39:22.1682730Z === CONT TestOSPoolEP2026-08-15T00:39:22.1683912Z TestOSPoolEP 2026-08-15T00:39:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:22.1799230Z TestOSPoolEP 2026-08-15T00:39:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb apply -f /tmp/TestOSPoolEP856787960]2026-08-15T00:39:22.7726533Z TestOSPoolEP 2026-08-15T00:39:22Z logger.go:67: pod/idtoken-generator created2026-08-15T00:39:22.7797396Z TestOSPoolEP 2026-08-15T00:39:22Z logger.go:67: secret/pool-passwd created2026-08-15T00:39:22.7867968Z TestOSPoolEP 2026-08-15T00:39:22Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-15T00:39:22.7869816Z TestOSPoolEP 2026-08-15T00:39:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:22.7882966Z TestOSPoolEP 2026-08-15T00:39:22Z 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-15T00:39:32.7892959Z TestOSPoolEP 2026-08-15T00:39:32Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-15T00:39:32.7893996Z TestOSPoolEP 2026-08-15T00:39:32Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:32.7921070Z TestOSPoolEP 2026-08-15T00:39:32Z 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-15T00:39:42.7930551Z TestOSPoolEP 2026-08-15T00:39:42Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-15T00:39:42.7931549Z TestOSPoolEP 2026-08-15T00:39:42Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:42.7956945Z TestOSPoolEP 2026-08-15T00:39:42Z logger.go:67: Pod is now available2026-08-15T00:39:42.7958468Z TestOSPoolEP 2026-08-15T00:39:42Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-15T00:39:43.3845583Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: ***2026-08-15T00:39:43.3924490Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb apply -f /tmp/TestOSPoolEP1090633088]2026-08-15T00:39:43.5873424Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: secret/pool-token created2026-08-15T00:39:43.5917453Z TestOSPoolEP 2026-08-15T00:39:43Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-15T00:39:43.5918676Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:43.5936581Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: Secret is now available2026-08-15T00:39:43.5967753Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb apply -k /tmp/kustomize-template-2971777529]2026-08-15T00:39:43.8681908Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: service/test-cm created2026-08-15T00:39:43.8770007Z TestOSPoolEP 2026-08-15T00:39:43Z 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-15T00:39:43.8773085Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: deployment.apps/ospool-ep created2026-08-15T00:39:43.8848136Z TestOSPoolEP 2026-08-15T00:39:43Z logger.go:67: deployment.apps/test-cm created2026-08-15T00:39:43.8946519Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-15T00:39:43.8948705Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:43.9055417Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-15T00:39:43.9059696Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:43.9064103Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:39:43.9067288Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:43.9181820Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z 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-15T00:39:43.9198837Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:43Z 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-15T00:39:53.9186596Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:53Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:39:53.9187984Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:53.9206712Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:53Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-15T00:39:53.9211094Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:53.9223027Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:53Z 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-15T00:39:53.9236342Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:53Z logger.go:67: Deployment is now available2026-08-15T00:40:03.9226001Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:03Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:40:03.9227752Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9270542Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:03Z logger.go:67: Deployment is now available2026-08-15T00:40:03.9276984Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:03.9277688Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:03.9278410Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:03.9279178Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:03.9280279Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:03.9280864Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:03.9281373Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:03.9282319Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9283933Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:03.9300039Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9301479Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:03.9303183Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9327254Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9329374Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9331443Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:03.9354731Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)']2026-08-15T00:40:03.9363135Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_SINGULARITY]2026-08-15T00:40:03.9392893Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:40:04.2631512Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:04.2645486Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)']' in pod test-cm-5bb545475c-269xl failed. Retrying in 10s.2026-08-15T00:40:04.2650713Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:04.2653008Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:40:04.2657167Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:04.2659218Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-269xl failed. Retrying in 10s.2026-08-15T00:40:14.2634305Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)']2026-08-15T00:40:14.2670082Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_SINGULARITY]2026-08-15T00:40:14.4914131Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:14.4916081Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)']' in pod test-cm-5bb545475c-269xl failed. Retrying in 10s.2026-08-15T00:40:14.4933980Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:14.4936099Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-269xl failed. Retrying in 10s.2026-08-15T00:40:24.4915982Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)']2026-08-15T00:40:24.4938632Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_SINGULARITY]2026-08-15T00:40:24.6755365Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:24Z logger.go:67: true2026-08-15T00:40:24.6766808Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-15T00:40:24.6768192Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: 2026-08-15T00:40:24.6769950Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-54d4p LINUX X86_64 Unclaimed Idle 0.000 15988 0+00:00:002026-08-15T00:40:24.6771942Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: 2026-08-15T00:40:24.6773486Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-15T00:40:24.6774832Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: 2026-08-15T00:40:24.6776172Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-15T00:40:24.6777405Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: 2026-08-15T00:40:24.6778652Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:24Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-15T00:40:34.2661120Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:34Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:40:34.4220278Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:34Z logger.go:67: undefined2026-08-15T00:40:34.4288484Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:34.4290020Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:41:04.4297257Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:41:04.5949881Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:04Z logger.go:67: undefined2026-08-15T00:41:04.6020042Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:41:34.6027239Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:34Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:41:34.7601775Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:34Z logger.go:67: undefined2026-08-15T00:41:34.7672071Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:42:04.7677139Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:42:04.9235541Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:04Z logger.go:67: undefined2026-08-15T00:42:04.9306485Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:42:34.9317106Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:34Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:42:35.0898674Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:35Z logger.go:67: undefined2026-08-15T00:42:35.0967959Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:43:05.0997235Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:43:05.2560795Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:05Z logger.go:67: undefined2026-08-15T00:43:05.2627543Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:43:35.2646957Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:43:35.4187570Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:35Z logger.go:67: undefined2026-08-15T00:43:35.4255584Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:44:05.4262108Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:44:05.6355349Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:05Z logger.go:67: undefined2026-08-15T00:44:05.6424632Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:44:35.6447589Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:44:35.7960296Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:35Z logger.go:67: undefined2026-08-15T00:44:35.8031598Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:45:05.8053757Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:45:05.9840541Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:05Z logger.go:67: undefined2026-08-15T00:45:05.9905196Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-269xl failed. Retrying in 30s.2026-08-15T00:45:35.9936066Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb exec test-cm-5bb545475c-269xl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-54d4p",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:45:36.1856969Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:36Z logger.go:67: true2026-08-15T00:45:36.1943968Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.2013964Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.3914268Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.5555772Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.6734190Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.6760913Z === NAME TestOSPoolEP2026-08-15T00:45:36.6763830Z ospool_ep_test.go:101: ---2026-08-15T00:45:36.6764474Z Events for pod idtoken-generator:2026-08-15T00:45:36.6765766Z 2026-08-15 00:39:22 +0000 UTC Normal Successfully assigned test-ospool-ep-ola7xb/idtoken-generator to minikube2026-08-15T00:45:36.6766997Z 2026-08-15 00:39:24 +0000 UTC Normal Pulling image "htcondor/base"2026-08-15T00:45:36.6768580Z 2026-08-15 00:39:32 +0000 UTC Normal Successfully pulled image "htcondor/base" in 8.061s (8.061s including waiting). Image size: 560910730 bytes.2026-08-15T00:45:36.6770396Z 2026-08-15 00:39:32 +0000 UTC Normal Container created2026-08-15T00:45:36.6771259Z 2026-08-15 00:39:32 +0000 UTC Normal Container started2026-08-15T00:45:36.6771791Z 2026-08-15T00:45:36.6772117Z ---2026-08-15T00:45:36.6773680Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.6787046Z ospool_ep_test.go:101: ---2026-08-15T00:45:36.6787847Z Events for pod ospool-ep-65c844ddbb-54d4p:2026-08-15T00:45:36.6789306Z 2026-08-15 00:39:43 +0000 UTC Normal Successfully assigned test-ospool-ep-ola7xb/ospool-ep-65c844ddbb-54d4p to minikube2026-08-15T00:45:36.6790870Z 2026-08-15 00:39:44 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-15T00:45:36.6793118Z 2026-08-15 00:39:45 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 1.326s (1.326s including waiting). Image size: 5590942 bytes.2026-08-15T00:45:36.6794600Z 2026-08-15 00:39:45 +0000 UTC Normal Container created2026-08-15T00:45:36.6795431Z 2026-08-15 00:39:45 +0000 UTC Normal Container started2026-08-15T00:45:36.6796692Z 2026-08-15 00:39:45 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-08-15T00:45:36.6799003Z 2026-08-15 00:39:59 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 13.052s (13.405s including waiting). Image size: 1273054585 bytes.2026-08-15T00:45:36.6800547Z 2026-08-15 00:39:59 +0000 UTC Normal Container created2026-08-15T00:45:36.6801371Z 2026-08-15 00:39:59 +0000 UTC Normal Container started2026-08-15T00:45:36.6801911Z 2026-08-15T00:45:36.6802254Z ---2026-08-15T00:45:36.6803378Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.6819923Z ospool_ep_test.go:101: ---2026-08-15T00:45:36.6820658Z Events for pod test-cm-5bb545475c-269xl:2026-08-15T00:45:36.6822187Z 2026-08-15 00:39:43 +0000 UTC Normal Successfully assigned test-ospool-ep-ola7xb/test-cm-5bb545475c-269xl to minikube2026-08-15T00:45:36.6823325Z 2026-08-15 00:39:44 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-08-15T00:45:36.6824588Z 2026-08-15 00:39:46 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 635ms (1.949s including waiting). Image size: 560910756 bytes.2026-08-15T00:45:36.6825596Z 2026-08-15 00:39:46 +0000 UTC Normal Container created2026-08-15T00:45:36.6826365Z 2026-08-15 00:39:46 +0000 UTC Normal Container started2026-08-15T00:45:36.6826856Z 2026-08-15T00:45:36.6827155Z ---2026-08-15T00:45:36.6827792Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:36.6863300Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb delete -f /tmp/TestOSPoolEP3762216965]2026-08-15T00:45:36.8530111Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-ola7xb namespace2026-08-15T00:45:36.8578531Z TestOSPoolEP 2026-08-15T00:45:36Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-ola7xb namespace2026-08-15T00:45:38.0316841Z TestOSPoolEP 2026-08-15T00:45:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb delete -f /tmp/TestOSPoolEP3237503055]2026-08-15T00:45:38.1228501Z TestOSPoolEP 2026-08-15T00:45:38Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-ola7xb namespace2026-08-15T00:45:38.1280480Z TestOSPoolEP 2026-08-15T00:45:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ola7xb delete -k /tmp/kustomize-template-2971777529]2026-08-15T00:45:38.2434138Z TestOSPoolEP 2026-08-15T00:45:38Z logger.go:67: service "test-cm" deleted from test-ospool-ep-ola7xb namespace2026-08-15T00:45:38.2480593Z TestOSPoolEP 2026-08-15T00:45:38Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-ola7xb namespace2026-08-15T00:45:38.2519654Z TestOSPoolEP 2026-08-15T00:45:38Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-ola7xb namespace2026-08-15T00:45:38.2627784Z --- PASS: TestOSPoolEP (43.83s)2026-08-15T00:45:38.2654286Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (20.03s)2026-08-15T00:45:38.2665518Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.75s)2026-08-15T00:45:38.2667182Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.76s)2026-08-15T00:45:38.2668611Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.27s)2026-08-15T00:45:38.2669230Z PASS2026-08-15T00:45:38.2669705Z ok github.com/osg-htc/k8s-integration-tests/test 376.113s2026-08-15T00:45:38.3872827Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-15T00:45:38.3874175Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-15T00:45:38.3977895Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:45:38.3978495Z env:2026-08-15T00:45:38.3978832Z GOTOOLCHAIN: local2026-08-15T00:45:38.3979542Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:45:38.3980175Z ##[endgroup]2026-08-15T00:45:38.4186948Z 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-15T00:45:38.4188252Z ##[group]Run actions/upload-artifact@v42026-08-15T00:45:38.4188778Z with:2026-08-15T00:45:38.4189306Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-08-15T00:45:38.4190312Z path: /tmp/k8s-tests2026-08-15T00:45:38.4190720Z retention-days: 52026-08-15T00:45:38.4191141Z if-no-files-found: warn2026-08-15T00:45:38.4191616Z compression-level: 62026-08-15T00:45:38.4192068Z overwrite: false2026-08-15T00:45:38.4192740Z include-hidden-files: false2026-08-15T00:45:38.4193226Z env:2026-08-15T00:45:38.4193612Z GOTOOLCHAIN: local2026-08-15T00:45:38.4194021Z ##[endgroup]2026-08-15T00:45:38.6615741Z (node:18015) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-15T00:45:38.6617534Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-15T00:45:38.6745186Z With the provided path, there will be 6 files uploaded2026-08-15T00:45:38.6746333Z Artifact name is valid!2026-08-15T00:45:38.6746891Z Root directory input is valid!2026-08-15T00:45:38.8659317Z Beginning upload of artifact content to blob storage2026-08-15T00:45:38.8950946Z (node:18015) [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-15T00:45:39.0780110Z Uploaded bytes 139732026-08-15T00:45:39.1352206Z Finished uploading artifact content to blob storage!2026-08-15T00:45:39.1354063Z SHA256 digest of uploaded artifact zip is fb8157ae9ebfc44c8bc0fabbcd63b0a6c11dba17cd78fd4698ac584b988767d62026-08-15T00:45:39.1355313Z Finalizing artifact upload2026-08-15T00:45:39.3424319Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 92386142452026-08-15T00:45:39.3426457Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 13973 bytes. Artifact ID is 92386142452026-08-15T00:45:39.3433572Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/31854176039/artifacts/92386142452026-08-15T00:45:39.3636718Z Post job cleanup.2026-08-15T00:45:39.4974273Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-15T00:45:39.5025454Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-15T00:45:39.5059785Z /home/runner/go/pkg/mod2026-08-15T00:45:39.5089930Z /home/runner/.cache/go-build2026-08-15T00:45:39.5099258Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-15T00:45:39.5274795Z Post job cleanup.2026-08-15T00:45:39.6387930Z [command]/usr/bin/git version2026-08-15T00:45:39.6435435Z git version 2.54.02026-08-15T00:45:39.6476964Z Temporarily overriding HOME='/home/runner/work/_temp/1cc84b16-bc44-4cff-bcfb-713babcbdf71' before making global git config changes2026-08-15T00:45:39.6478461Z Adding repository directory to the temporary git global config as a safe directory2026-08-15T00:45:39.6483115Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-15T00:45:39.6532754Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-15T00:45:39.6586522Z [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-15T00:45:39.6901657Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-15T00:45:39.6932760Z http.https://github.com/.extraheader2026-08-15T00:45:39.6946718Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-15T00:45:39.6998620Z [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-15T00:45:39.7316539Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-15T00:45:39.7364289Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-15T00:45:39.7816412Z Cleaning up orphan processes2026-08-15T00:45:39.8662695Z ##[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/