2026-08-30T01:56:52.9239471Z Current runner version: '2.336.0'2026-08-30T01:56:52.9263856Z ##[group]Runner Image Provisioner2026-08-30T01:56:52.9265143Z Hosted Compute Agent2026-08-30T01:56:52.9265906Z Version: 20260819.5862026-08-30T01:56:52.9266627Z Commit: 3cc4a88dfa507ef76119ad1bb3eccc6378bb2b762026-08-30T01:56:52.9267397Z Build Date: 2026-08-18T23:20:18Z2026-08-30T01:56:52.9268236Z Worker ID: {5c31730f-2cd4-438a-88db-84b69943d799}2026-08-30T01:56:52.9269017Z Azure Region: westus2026-08-30T01:56:52.9269678Z ##[endgroup]2026-08-30T01:56:52.9271313Z ##[group]Operating System2026-08-30T01:56:52.9271979Z Ubuntu2026-08-30T01:56:52.9272709Z 22.04.52026-08-30T01:56:52.9273272Z LTS2026-08-30T01:56:52.9273871Z ##[endgroup]2026-08-30T01:56:52.9274502Z ##[group]Runner Image2026-08-30T01:56:52.9275530Z Image: ubuntu-22.042026-08-30T01:56:52.9276273Z Version: 20260824.273.32026-08-30T01:56:52.9277638Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260824.273/images/ubuntu/Ubuntu2204-Readme.md2026-08-30T01:56:52.9279371Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260824.2732026-08-30T01:56:52.9280432Z ##[endgroup]2026-08-30T01:56:52.9283769Z ##[group]GITHUB_TOKEN Permissions2026-08-30T01:56:52.9286608Z Actions: write2026-08-30T01:56:52.9287759Z ArtifactMetadata: write2026-08-30T01:56:52.9288465Z Attestations: write2026-08-30T01:56:52.9289154Z Checks: write2026-08-30T01:56:52.9289788Z CodeQuality: write2026-08-30T01:56:52.9290426Z Contents: write2026-08-30T01:56:52.9291109Z CopilotRequests: write2026-08-30T01:56:52.9291825Z Deployments: write2026-08-30T01:56:52.9292569Z Discussions: write2026-08-30T01:56:52.9293203Z Drives: write2026-08-30T01:56:52.9293815Z Issues: write2026-08-30T01:56:52.9294457Z Metadata: read2026-08-30T01:56:52.9295487Z Models: read2026-08-30T01:56:52.9296089Z Packages: write2026-08-30T01:56:52.9296823Z Pages: write2026-08-30T01:56:52.9297455Z PullRequests: write2026-08-30T01:56:52.9298229Z RepositoryProjects: write2026-08-30T01:56:52.9298943Z SecurityEvents: write2026-08-30T01:56:52.9299567Z Statuses: write2026-08-30T01:56:52.9300301Z VulnerabilityAlerts: read2026-08-30T01:56:52.9300962Z ##[endgroup]2026-08-30T01:56:52.9303091Z Secret source: Actions2026-08-30T01:56:52.9304351Z Prepare workflow directory2026-08-30T01:56:52.9649938Z Prepare all required actions2026-08-30T01:56:52.9699582Z Getting action download info2026-08-30T01:56:53.3163431Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-30T01:56:53.4250323Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-30T01:56:53.7046233Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-30T01:56:54.0978538Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-30T01:56:54.3424207Z Uses: osg-htc/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-30T01:56:54.3429141Z ##[group] Inputs2026-08-30T01:56:54.3429713Z testName: TestOSPoolEP2026-08-30T01:56:54.3430593Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-30T01:56:54.3431593Z setupScript: 2026-08-30T01:56:54.3432055Z setupEnv: {}2026-08-30T01:56:54.3432504Z ##[endgroup]2026-08-30T01:56:54.3433195Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-08-30T01:56:54.4185281Z ##[group]Run actions/checkout@v52026-08-30T01:56:54.4186232Z with:2026-08-30T01:56:54.4186727Z repository: osg-htc/k8s-integration-tests2026-08-30T01:56:54.4190883Z token: ***2026-08-30T01:56:54.4191346Z ssh-strict: true2026-08-30T01:56:54.4191806Z ssh-user: git2026-08-30T01:56:54.4192303Z persist-credentials: true2026-08-30T01:56:54.4192823Z clean: true2026-08-30T01:56:54.4193302Z sparse-checkout-cone-mode: true2026-08-30T01:56:54.4194225Z fetch-depth: 12026-08-30T01:56:54.4195121Z fetch-tags: false2026-08-30T01:56:54.4195614Z show-progress: true2026-08-30T01:56:54.4196079Z lfs: false2026-08-30T01:56:54.4196525Z submodules: false2026-08-30T01:56:54.4197000Z set-safe-directory: true2026-08-30T01:56:54.4197522Z allow-unsafe-pr-checkout: false2026-08-30T01:56:54.4198375Z ##[endgroup]2026-08-30T01:56:54.5305708Z Syncing repository: osg-htc/k8s-integration-tests2026-08-30T01:56:54.5308711Z ##[group]Getting Git version info2026-08-30T01:56:54.5310228Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-30T01:56:54.5312155Z [command]/usr/bin/git version2026-08-30T01:56:54.5356019Z git version 2.55.02026-08-30T01:56:54.5378813Z ##[endgroup]2026-08-30T01:56:54.5393556Z Temporarily overriding HOME='/home/runner/work/_temp/f28fe289-773d-4884-bf50-0df3784b4c1e' before making global git config changes2026-08-30T01:56:54.5396417Z Adding repository directory to the temporary git global config as a safe directory2026-08-30T01:56:54.5399807Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-30T01:56:54.5440093Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-30T01:56:54.5444171Z ##[group]Initializing the repository2026-08-30T01:56:54.5465260Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-30T01:56:54.5575105Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-30T01:56:54.5577056Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-30T01:56:54.5579542Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-30T01:56:54.5581373Z hint: call:2026-08-30T01:56:54.5582676Z hint:2026-08-30T01:56:54.5584120Z hint: git config --global init.defaultBranch <name>2026-08-30T01:56:54.5585515Z hint:2026-08-30T01:56:54.5586610Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-30T01:56:54.5588278Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-30T01:56:54.5589510Z hint:2026-08-30T01:56:54.5590173Z hint: git branch -m <name>2026-08-30T01:56:54.5590989Z hint:2026-08-30T01:56:54.5592308Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-30T01:56:54.5594224Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-30T01:56:54.5599176Z [command]/usr/bin/git remote add origin https://github.com/osg-htc/k8s-integration-tests2026-08-30T01:56:54.5644839Z ##[endgroup]2026-08-30T01:56:54.5646092Z ##[group]Disabling automatic garbage collection2026-08-30T01:56:54.5650234Z [command]/usr/bin/git config --local gc.auto 02026-08-30T01:56:54.5686208Z ##[endgroup]2026-08-30T01:56:54.5687398Z ##[group]Setting up auth2026-08-30T01:56:54.5694378Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-30T01:56:54.5731935Z [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-30T01:56:54.6127641Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-30T01:56:54.6168384Z [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-30T01:56:54.6444575Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-30T01:56:54.6487177Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-30T01:56:54.6751135Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-30T01:56:54.6794503Z ##[endgroup]2026-08-30T01:56:54.6796199Z ##[group]Fetching the repository2026-08-30T01:56:54.6805939Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-30T01:56:55.1167964Z From https://github.com/osg-htc/k8s-integration-tests2026-08-30T01:56:55.1169199Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-30T01:56:55.1172669Z ##[endgroup]2026-08-30T01:56:55.1173660Z ##[group]Determining the checkout info2026-08-30T01:56:55.1175386Z ##[endgroup]2026-08-30T01:56:55.1180885Z [command]/usr/bin/git sparse-checkout disable2026-08-30T01:56:55.1230012Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-30T01:56:55.1264828Z ##[group]Checking out the ref2026-08-30T01:56:55.1268573Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-30T01:56:55.1366370Z Switched to a new branch 'main'2026-08-30T01:56:55.1370206Z branch 'main' set up to track 'origin/main'.2026-08-30T01:56:55.1377295Z ##[endgroup]2026-08-30T01:56:55.1431682Z [command]/usr/bin/git log -1 --format=%H2026-08-30T01:56:55.1463158Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-30T01:56:55.1787211Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-30T01:56:55.1788500Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-30T01:56:55.1789616Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-30T01:56:55.2144229Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-30T01:56:55.2145140Z env:2026-08-30T01:56:55.2145970Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-30T01:56:55.2146954Z SETUP_ENV_YAML: {}2026-08-30T01:56:55.2147451Z ##[endgroup]2026-08-30T01:56:55.8678923Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-30T01:56:55.8681833Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-30T01:56:55.8760917Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-30T01:56:55.8762619Z env:2026-08-30T01:56:55.8764974Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-30T01:56:55.8767571Z ##[endgroup]2026-08-30T01:56:55.8983152Z ##[group]Run true2026-08-30T01:56:55.8984127Z [36;1mtrue[0m2026-08-30T01:56:55.9054471Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-30T01:56:55.9056128Z ##[endgroup]2026-08-30T01:56:55.9380661Z ##[group]Run medyagh/setup-minikube@latest2026-08-30T01:56:55.9381923Z with:2026-08-30T01:56:55.9382749Z start: true2026-08-30T01:56:55.9383610Z cache: true2026-08-30T01:56:55.9384545Z minikube-version: latest2026-08-30T01:56:55.9385886Z wait: all2026-08-30T01:56:55.9386737Z ##[endgroup]2026-08-30T01:56:56.0709894Z (node:1820) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-30T01:56:56.0714342Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-30T01:56:57.0390311Z [command]/usr/bin/chmod +x /home/runner/work/_temp/1f78305e-2a66-4b95-8698-061cae950fcb2026-08-30T01:56:57.2088077Z [command]/home/runner/bin/minikube version --short2026-08-30T01:56:57.2650718Z v1.38.12026-08-30T01:56:57.5119115Z Cache hit for: kic-v1.38.1-x642026-08-30T01:56:57.5203918Z (node:1820) [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-30T01:56:57.5211207Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-30T01:56:58.7793506Z Received 12582912 of 544082637 (2.3%), 12.0 MBs/sec2026-08-30T01:56:58.8035664Z Received 25165824 of 224218830 (11.2%), 23.9 MBs/sec2026-08-30T01:56:59.7814482Z Received 134217728 of 544082637 (24.7%), 63.8 MBs/sec2026-08-30T01:56:59.8043877Z Received 130023424 of 224218830 (58.0%), 61.8 MBs/sec2026-08-30T01:57:00.7454939Z Received 224218830 of 224218830 (100.0%), 72.6 MBs/sec2026-08-30T01:57:00.7457918Z Cache Size: ~214 MB (224218830 B)2026-08-30T01:57:00.7709454Z [command]/usr/bin/tar -xf /home/runner/work/_temp/d9b7edc5-77d7-4d38-877c-9d810175666f/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-30T01:57:00.7959792Z Received 255852544 of 544082637 (47.0%), 80.8 MBs/sec2026-08-30T01:57:01.3966297Z Cache restored successfully2026-08-30T01:57:01.8015786Z Received 398458880 of 544082637 (73.2%), 94.5 MBs/sec2026-08-30T01:57:02.8015040Z Received 536870912 of 544082637 (98.7%), 101.9 MBs/sec2026-08-30T01:57:03.0160865Z Received 544082637 of 544082637 (100.0%), 99.0 MBs/sec2026-08-30T01:57:03.0165237Z Cache Size: ~519 MB (544082637 B)2026-08-30T01:57:03.0280194Z [command]/usr/bin/tar -xf /home/runner/work/_temp/9b0a4dc0-3513-4ac8-bf00-a7321b242b80/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-30T01:57:03.8679907Z Cache restored successfully2026-08-30T01:57:03.9890798Z [command]/home/runner/bin/minikube start --wait all2026-08-30T01:57:04.0379150Z * minikube v1.38.1 on Ubuntu 22.042026-08-30T01:57:08.4092421Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-30T01:57:08.4724143Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-30T01:57:08.4733048Z * Using Docker driver with root privileges2026-08-30T01:57:08.4743555Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-30T01:57:08.4751340Z * Pulling base image v0.0.50 ...2026-08-30T01:57:40.3194290Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-30T01:57:45.4975738Z * Verifying Kubernetes components...2026-08-30T01:57:45.5344473Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-30T01:57:46.2694822Z * Enabled addons: storage-provisioner, default-storageclass2026-08-30T01:58:22.6522578Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-30T01:58:22.6665117Z [command]/home/runner/bin/minikube version --short2026-08-30T01:58:22.7192310Z v1.38.12026-08-30T01:58:22.7504581Z ##[group]Run actions/setup-go@v62026-08-30T01:58:22.7505254Z with:2026-08-30T01:58:22.7505470Z go-version: 1.262026-08-30T01:58:22.7505685Z cache: true2026-08-30T01:58:22.7505915Z cache-dependency-path: go.sum2026-08-30T01:58:22.7506184Z check-latest: false2026-08-30T01:58:22.7508783Z token: ***2026-08-30T01:58:22.7509001Z ##[endgroup]2026-08-30T01:58:22.8889245Z Setup go version spec 1.262026-08-30T01:58:22.8948707Z Found in cache @ /opt/hostedtoolcache/go/1.26.7/x642026-08-30T01:58:22.8950454Z Added go to the path2026-08-30T01:58:22.8953681Z Successfully set up Go version 1.262026-08-30T01:58:23.9890989Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOMODCACHE2026-08-30T01:58:23.9937213Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOCACHE2026-08-30T01:58:23.9977774Z /home/runner/go/pkg/mod2026-08-30T01:58:24.0007486Z /home/runner/.cache/go-build2026-08-30T01:58:24.2571766Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.7-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-30T01:58:25.5725890Z Received 0 of 251715014 (0.0%), 0.0 MBs/sec2026-08-30T01:58:26.5716253Z Received 130023424 of 251715014 (51.7%), 61.9 MBs/sec2026-08-30T01:58:27.5736024Z Received 247520710 of 251715014 (98.3%), 78.6 MBs/sec2026-08-30T01:58:27.7211188Z Received 251715014 of 251715014 (100.0%), 76.1 MBs/sec2026-08-30T01:58:27.7212582Z Cache Size: ~240 MB (251715014 B)2026-08-30T01:58:27.7250697Z [command]/usr/bin/tar -xf /home/runner/work/_temp/6faa6bca-d48d-47ac-a117-c49c0208b3cd/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-30T01:58:30.9409319Z Cache restored successfully2026-08-30T01:58:30.9973663Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.7-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-30T01:58:30.9996833Z go version go1.26.7 linux/amd642026-08-30T01:58:30.9997063Z 2026-08-30T01:58:30.9997380Z ##[group]go env2026-08-30T01:58:31.0305283Z AR='ar'2026-08-30T01:58:31.0305668Z CC='gcc'2026-08-30T01:58:31.0306060Z CGO_CFLAGS='-O2 -g'2026-08-30T01:58:31.0306471Z CGO_CPPFLAGS=''2026-08-30T01:58:31.0306871Z CGO_CXXFLAGS='-O2 -g'2026-08-30T01:58:31.0307281Z CGO_ENABLED='1'2026-08-30T01:58:31.0307662Z CGO_FFLAGS='-O2 -g'2026-08-30T01:58:31.0308051Z CGO_LDFLAGS='-O2 -g'2026-08-30T01:58:31.0308432Z CXX='g++'2026-08-30T01:58:31.0308777Z GCCGO='gccgo'2026-08-30T01:58:31.0309161Z GO111MODULE=''2026-08-30T01:58:31.0309517Z GOAMD64='v1'2026-08-30T01:58:31.0309874Z GOARCH='amd64'2026-08-30T01:58:31.0310245Z GOAUTH='netrc'2026-08-30T01:58:31.0310462Z GOBIN=''2026-08-30T01:58:31.0310709Z GOCACHE='/home/runner/.cache/go-build'2026-08-30T01:58:31.0311014Z GOCACHEPROG=''2026-08-30T01:58:31.0311247Z GODEBUG=''2026-08-30T01:58:31.0311478Z GOENV='/home/runner/.config/go/env'2026-08-30T01:58:31.0311780Z GOEXE=''2026-08-30T01:58:31.0312011Z GOEXPERIMENT=''2026-08-30T01:58:31.0312260Z GOFIPS140='off'2026-08-30T01:58:31.0312489Z GOFLAGS=''2026-08-30T01:58:31.0313214Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build3218932099=/tmp/go-build -gno-record-gcc-switches'2026-08-30T01:58:31.0313959Z GOHOSTARCH='amd64'2026-08-30T01:58:31.0314201Z GOHOSTOS='linux'2026-08-30T01:58:31.0314429Z GOINSECURE=''2026-08-30T01:58:31.0315109Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-30T01:58:31.0315609Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-30T01:58:31.0315922Z GONOPROXY=''2026-08-30T01:58:31.0316143Z GONOSUMDB=''2026-08-30T01:58:31.0316368Z GOOS='linux'2026-08-30T01:58:31.0316590Z GOPATH='/home/runner/go'2026-08-30T01:58:31.0316859Z GOPRIVATE=''2026-08-30T01:58:31.0317292Z GOPROXY='https://proxy.golang.org,direct'2026-08-30T01:58:31.0317725Z GOROOT='/opt/hostedtoolcache/go/1.26.7/x64'2026-08-30T01:58:31.0318154Z GOSUMDB='sum.golang.org'2026-08-30T01:58:31.0318431Z GOTELEMETRY='local'2026-08-30T01:58:31.0319013Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-30T01:58:31.0319537Z GOTMPDIR=''2026-08-30T01:58:31.0319904Z GOTOOLCHAIN='local'2026-08-30T01:58:31.0320512Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.7/x64/pkg/tool/linux_amd64'2026-08-30T01:58:31.0321191Z GOVCS=''2026-08-30T01:58:31.0321560Z GOVERSION='go1.26.7'2026-08-30T01:58:31.0321930Z GOWORK=''2026-08-30T01:58:31.0322277Z PKG_CONFIG='pkg-config'2026-08-30T01:58:31.0322517Z 2026-08-30T01:58:31.0323003Z ##[endgroup]2026-08-30T01:58:31.0637092Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-30T01:58:31.0637666Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-30T01:58:31.0709514Z shell: /usr/bin/bash -e {0}2026-08-30T01:58:31.0709789Z env:2026-08-30T01:58:31.0710010Z GOTOOLCHAIN: local2026-08-30T01:58:31.0710273Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-08-30T01:58:31.0710581Z OSPOOL_EP_CMTag: 25.x-el92026-08-30T01:58:31.0710852Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-30T01:58:31.0711135Z ##[endgroup]2026-08-30T01:58:36.7929627Z === RUN TestOSPoolEP2026-08-30T01:58:36.7930496Z === PAUSE TestOSPoolEP2026-08-30T01:58:36.7931314Z === CONT TestOSPoolEP2026-08-30T01:58:36.7946576Z TestOSPoolEP 2026-08-30T01:58:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:36.8103599Z TestOSPoolEP 2026-08-30T01:58:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx apply -f /tmp/TestOSPoolEP2247179786]2026-08-30T01:58:38.0312136Z TestOSPoolEP 2026-08-30T01:58:38Z logger.go:67: pod/idtoken-generator created2026-08-30T01:58:38.0378579Z TestOSPoolEP 2026-08-30T01:58:38Z logger.go:67: secret/pool-passwd created2026-08-30T01:58:38.0429270Z TestOSPoolEP 2026-08-30T01:58:38Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-30T01:58:38.0431077Z TestOSPoolEP 2026-08-30T01:58:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:38.0454267Z TestOSPoolEP 2026-08-30T01:58:38Z 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-30T01:58:48.0462123Z TestOSPoolEP 2026-08-30T01:58:48Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-30T01:58:48.0463837Z TestOSPoolEP 2026-08-30T01:58:48Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:48.0490606Z TestOSPoolEP 2026-08-30T01:58:48Z 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-30T01:58:58.0501442Z TestOSPoolEP 2026-08-30T01:58:58Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-30T01:58:58.0503461Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:58.0528799Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Pod is now available2026-08-30T01:58:58.0530538Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-30T01:58:58.4449597Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: ***2026-08-30T01:58:58.4508224Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx apply -f /tmp/TestOSPoolEP3616210987]2026-08-30T01:58:58.5566257Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: secret/pool-token created2026-08-30T01:58:58.5600213Z TestOSPoolEP 2026-08-30T01:58:58Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-30T01:58:58.5601734Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:58.5621616Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Secret is now available2026-08-30T01:58:58.5656082Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx apply -k /tmp/kustomize-template-3888256574]2026-08-30T01:58:58.7112197Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: service/test-cm created2026-08-30T01:58:58.7194061Z TestOSPoolEP 2026-08-30T01:58:58Z 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-30T01:58:58.7196852Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: deployment.apps/ospool-ep created2026-08-30T01:58:58.7252069Z TestOSPoolEP 2026-08-30T01:58:58Z logger.go:67: deployment.apps/test-cm created2026-08-30T01:58:58.7302627Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-30T01:58:58.7323663Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:58.7406299Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-30T01:58:58.7446093Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:58.7449573Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-30T01:58:58.7452055Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:58:58.7456480Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z 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-30T01:58:58.7462349Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:58:58Z 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-30T01:59:08.7476313Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:08Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-30T01:59:08.7478092Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:08Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:08.7479211Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:08Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-30T01:59:08.7480490Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:08Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:08.7516190Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:08Z 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-30T01:59:08.7518688Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:08Z logger.go:67: Deployment is now available2026-08-30T01:59:18.7520287Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:18Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-30T01:59:18.7522600Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:18.7559879Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:18Z 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-30T01:59:28.7569188Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:28Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-30T01:59:28.7570634Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7594793Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-30T01:59:28Z logger.go:67: Deployment is now available2026-08-30T01:59:28.7595931Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-30T01:59:28.7597117Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-30T01:59:28.7598578Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-30T01:59:28.7601501Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-30T01:59:28.7602802Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-30T01:59:28.7603920Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-30T01:59:28.7605209Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-30T01:59:28.7606931Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7608616Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-30T01:59:28.7610024Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7611357Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-30T01:59:28.7613070Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7637526Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7639873Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7644173Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-30T01:59:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T01:59:28.7658027Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T01:59:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T01:59:28.7666973Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-30T01:59:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_SINGULARITY]2026-08-30T01:59:28.7680351Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)']2026-08-30T01:59:28.9314075Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-30T01:59:28.9318301Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T01:59:28.9321693Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-30T01:59:28.9324160Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 10s.2026-08-30T01:59:28.9372293Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-30T01:59:28.9373477Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)']' in pod test-cm-5bb545475c-r86qw failed. Retrying in 10s.2026-08-30T01:59:38.9332539Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-30T01:59:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_SINGULARITY]2026-08-30T01:59:38.9375178Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)']2026-08-30T01:59:39.0672535Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)']' in pod test-cm-5bb545475c-r86qw failed. Retrying in 10s.2026-08-30T01:59:39.0674003Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-30T01:59:39.0675640Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 10s.2026-08-30T01:59:49.0675246Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-30T01:59:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_SINGULARITY]2026-08-30T01:59:49.0679068Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)']2026-08-30T01:59:49.1921019Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-30T01:59:49.1924694Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: 2026-08-30T01:59:49.1927457Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-p4xn7 LINUX X86_64 Unclaimed Idle 0.000 15988 0+00:00:002026-08-30T01:59:49.1929966Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: 2026-08-30T01:59:49.1932237Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-30T01:59:49.1933614Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: 2026-08-30T01:59:49.1935458Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-30T01:59:49.1937073Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: 2026-08-30T01:59:49.1938648Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-30T01:59:49Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-30T01:59:49.1940296Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-30T01:59:49Z logger.go:67: true2026-08-30T01:59:58.9323927Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T01:59:58Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T01:59:59.0369765Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T01:59:59Z logger.go:67: undefined2026-08-30T01:59:59.0416614Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-30T01:59:59.0418194Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:00:29.0428725Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:00:29Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:00:29.1475570Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:00:29Z logger.go:67: undefined2026-08-30T02:00:29.1537663Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:00:59.1547595Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:00:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:00:59.2609567Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:00:59Z logger.go:67: undefined2026-08-30T02:00:59.2676225Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:01:29.2678109Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:01:29Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:01:29.3752532Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:01:29Z logger.go:67: undefined2026-08-30T02:01:29.3806528Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:01:59.3829344Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:01:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:01:59.4898354Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:01:59Z logger.go:67: undefined2026-08-30T02:01:59.4940899Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:02:29.4959481Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:02:29Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:02:29.6016958Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:02:29Z logger.go:67: undefined2026-08-30T02:02:29.6073077Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:02:59.6081253Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:02:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:02:59.7216710Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:02:59Z logger.go:67: undefined2026-08-30T02:02:59.7273497Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:03:29.7278536Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:03:29Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:03:29.8486368Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:03:29Z logger.go:67: undefined2026-08-30T02:03:29.8547322Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:03:59.8569474Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:03:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:03:59.9626618Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:03:59Z logger.go:67: undefined2026-08-30T02:03:59.9689053Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:04:29.9695057Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:04:29Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:04:30.0772938Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:04:30Z logger.go:67: undefined2026-08-30T02:04:30.0826992Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r86qw failed. Retrying in 30s.2026-08-30T02:05:00.0851344Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:05:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx exec test-cm-5bb545475c-r86qw -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-p4xn7",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-30T02:05:00.2011711Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-30T02:05:00Z logger.go:67: true2026-08-30T02:05:00.2068901Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.2154127Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.3355827Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.4329991Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.5069708Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.5091000Z === NAME TestOSPoolEP2026-08-30T02:05:00.5091775Z ospool_ep_test.go:101: ---2026-08-30T02:05:00.5092435Z Events for pod idtoken-generator:2026-08-30T02:05:00.5093845Z 2026-08-30 01:58:38 +0000 UTC Normal Successfully assigned test-ospool-ep-rchplx/idtoken-generator to minikube2026-08-30T02:05:00.5095397Z 2026-08-30 01:58:38 +0000 UTC Normal Pulling image "htcondor/base"2026-08-30T02:05:00.5103210Z 2026-08-30 01:58:47 +0000 UTC Normal Successfully pulled image "htcondor/base" in 8.872s (8.872s including waiting). Image size: 522365028 bytes.2026-08-30T02:05:00.5104897Z 2026-08-30 01:58:47 +0000 UTC Normal Container created2026-08-30T02:05:00.5105871Z 2026-08-30 01:58:47 +0000 UTC Normal Container started2026-08-30T02:05:00.5106413Z 2026-08-30T02:05:00.5106776Z ---2026-08-30T02:05:00.5108020Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.5112859Z ospool_ep_test.go:101: ---2026-08-30T02:05:00.5113905Z Events for pod ospool-ep-65c844ddbb-p4xn7:2026-08-30T02:05:00.5115914Z 2026-08-30 01:58:58 +0000 UTC Normal Successfully assigned test-ospool-ep-rchplx/ospool-ep-65c844ddbb-p4xn7 to minikube2026-08-30T02:05:00.5117573Z 2026-08-30 01:58:59 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-30T02:05:00.5119751Z 2026-08-30 01:59:02 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.783s (2.783s including waiting). Image size: 5590942 bytes.2026-08-30T02:05:00.5121318Z 2026-08-30 01:59:02 +0000 UTC Normal Container created2026-08-30T02:05:00.5122180Z 2026-08-30 01:59:02 +0000 UTC Normal Container started2026-08-30T02:05:00.5123496Z 2026-08-30 01:59:02 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-08-30T02:05:00.5126392Z 2026-08-30 01:59:19 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 15.78s (16.991s including waiting). Image size: 1288058081 bytes.2026-08-30T02:05:00.5128055Z 2026-08-30 01:59:19 +0000 UTC Normal Container created2026-08-30T02:05:00.5128920Z 2026-08-30 01:59:19 +0000 UTC Normal Container started2026-08-30T02:05:00.5129502Z 2026-08-30T02:05:00.5129862Z ---2026-08-30T02:05:00.5130832Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.5136805Z ospool_ep_test.go:101: ---2026-08-30T02:05:00.5137638Z Events for pod test-cm-5bb545475c-r86qw:2026-08-30T02:05:00.5139645Z 2026-08-30 01:58:58 +0000 UTC Normal Successfully assigned test-ospool-ep-rchplx/test-cm-5bb545475c-r86qw to minikube2026-08-30T02:05:00.5142176Z 2026-08-30 01:58:59 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-08-30T02:05:00.5144805Z 2026-08-30 01:59:03 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 1.49s (4.268s including waiting). Image size: 522365054 bytes.2026-08-30T02:05:00.5150830Z 2026-08-30 01:59:03 +0000 UTC Normal Container created2026-08-30T02:05:00.5151822Z 2026-08-30 01:59:03 +0000 UTC Normal Container started2026-08-30T02:05:00.5152426Z 2026-08-30T02:05:00.5152833Z ---2026-08-30T02:05:00.5153930Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-30T02:05:00.5183066Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx delete -f /tmp/TestOSPoolEP2229580237]2026-08-30T02:05:00.5786916Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-rchplx namespace2026-08-30T02:05:00.5845042Z TestOSPoolEP 2026-08-30T02:05:00Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-rchplx namespace2026-08-30T02:05:02.3520165Z TestOSPoolEP 2026-08-30T02:05:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx delete -f /tmp/TestOSPoolEP84832026]2026-08-30T02:05:02.4043650Z TestOSPoolEP 2026-08-30T02:05:02Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-rchplx namespace2026-08-30T02:05:02.4083344Z TestOSPoolEP 2026-08-30T02:05:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-rchplx delete -k /tmp/kustomize-template-3888256574]2026-08-30T02:05:02.4740707Z TestOSPoolEP 2026-08-30T02:05:02Z logger.go:67: service "test-cm" deleted from test-ospool-ep-rchplx namespace2026-08-30T02:05:02.4775910Z TestOSPoolEP 2026-08-30T02:05:02Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-rchplx namespace2026-08-30T02:05:02.4811312Z TestOSPoolEP 2026-08-30T02:05:02Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-rchplx namespace2026-08-30T02:05:02.4920294Z --- PASS: TestOSPoolEP (54.25s)2026-08-30T02:05:02.4932608Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-08-30T02:05:02.4955196Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.44s)2026-08-30T02:05:02.4956593Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.44s)2026-08-30T02:05:02.4957956Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (331.45s)2026-08-30T02:05:02.4958859Z PASS2026-08-30T02:05:02.4959596Z ok github.com/osg-htc/k8s-integration-tests/test 385.719s2026-08-30T02:05:02.6032306Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-30T02:05:02.6033378Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-30T02:05:02.6133037Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-30T02:05:02.6133602Z env:2026-08-30T02:05:02.6133920Z GOTOOLCHAIN: local2026-08-30T02:05:02.6134841Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-30T02:05:02.6135625Z ##[endgroup]2026-08-30T02:05:02.6400547Z 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-30T02:05:02.6402925Z ##[group]Run actions/upload-artifact@v42026-08-30T02:05:02.6403443Z with:2026-08-30T02:05:02.6404108Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-08-30T02:05:02.6405224Z path: /tmp/k8s-tests2026-08-30T02:05:02.6405621Z retention-days: 52026-08-30T02:05:02.6406004Z if-no-files-found: warn2026-08-30T02:05:02.6406412Z compression-level: 62026-08-30T02:05:02.6406801Z overwrite: false2026-08-30T02:05:02.6407195Z include-hidden-files: false2026-08-30T02:05:02.6407618Z env:2026-08-30T02:05:02.6408001Z GOTOOLCHAIN: local2026-08-30T02:05:02.6408382Z ##[endgroup]2026-08-30T02:05:02.8734492Z (node:17845) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-30T02:05:02.8737368Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-30T02:05:02.8897331Z With the provided path, there will be 6 files uploaded2026-08-30T02:05:02.8898923Z Artifact name is valid!2026-08-30T02:05:02.8899638Z Root directory input is valid!2026-08-30T02:05:03.2915517Z Beginning upload of artifact content to blob storage2026-08-30T02:05:03.3260189Z (node:17845) [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-30T02:05:03.6878456Z Uploaded bytes 139182026-08-30T02:05:03.7705546Z Finished uploading artifact content to blob storage!2026-08-30T02:05:03.7706975Z SHA256 digest of uploaded artifact zip is 732063cd51324b7b18bf4fb1e7e4b09d75b3fc5d4a1b40e53a2087b96c2910ee2026-08-30T02:05:03.7708298Z Finalizing artifact upload2026-08-30T02:05:04.0437613Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 97247762192026-08-30T02:05:04.0440278Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 13918 bytes. Artifact ID is 97247762192026-08-30T02:05:04.0445851Z Artifact download URL: https://github.com/osg-htc/k8s-integration-tests/actions/runs/33286821905/artifacts/97247762192026-08-30T02:05:04.0627615Z Post job cleanup.2026-08-30T02:05:04.2010400Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOMODCACHE2026-08-30T02:05:04.2071882Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOCACHE2026-08-30T02:05:04.2132706Z /home/runner/go/pkg/mod2026-08-30T02:05:04.2146078Z /home/runner/.cache/go-build2026-08-30T02:05:04.2164223Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.7-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-30T02:05:04.2317706Z Post job cleanup.2026-08-30T02:05:04.3373328Z [command]/usr/bin/git version2026-08-30T02:05:04.3421207Z git version 2.55.02026-08-30T02:05:04.3466807Z Temporarily overriding HOME='/home/runner/work/_temp/ab8b9c00-1718-4379-a701-b1391c0759b5' before making global git config changes2026-08-30T02:05:04.3467985Z Adding repository directory to the temporary git global config as a safe directory2026-08-30T02:05:04.3473235Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-30T02:05:04.3516855Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-30T02:05:04.3558110Z [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-30T02:05:04.3950301Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-30T02:05:04.4006147Z http.https://github.com/.extraheader2026-08-30T02:05:04.4050194Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-30T02:05:04.4132314Z [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-30T02:05:04.4427828Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-30T02:05:04.4472533Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-30T02:05:04.4928828Z Cleaning up orphan processes2026-08-30T02:05:04.5734257Z ##[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/