2026-09-01T02:14:36.7278428Z Current runner version: '2.337.0'2026-09-01T02:14:36.7302996Z ##[group]Runner Image Provisioner2026-09-01T02:14:36.7303891Z Hosted Compute Agent2026-09-01T02:14:36.7304413Z Version: 20260819.5862026-09-01T02:14:36.7304980Z Commit: 3cc4a88dfa507ef76119ad1bb3eccc6378bb2b762026-09-01T02:14:36.7305859Z Build Date: 2026-08-18T23:20:18Z2026-09-01T02:14:36.7306482Z Worker ID: {ecfec75e-0833-4c02-9345-318fdac539ca}2026-09-01T02:14:36.7307144Z Azure Region: westus32026-09-01T02:14:36.7307665Z ##[endgroup]2026-09-01T02:14:36.7309179Z ##[group]Operating System2026-09-01T02:14:36.7309793Z Ubuntu2026-09-01T02:14:36.7310287Z 22.04.52026-09-01T02:14:36.7310841Z LTS2026-09-01T02:14:36.7311306Z ##[endgroup]2026-09-01T02:14:36.7311794Z ##[group]Runner Image2026-09-01T02:14:36.7312341Z Image: ubuntu-22.042026-09-01T02:14:36.7312852Z Version: 20260824.273.32026-09-01T02:14:36.7314026Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260824.273/images/ubuntu/Ubuntu2204-Readme.md2026-09-01T02:14:36.7315563Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260824.2732026-09-01T02:14:36.7316464Z ##[endgroup]2026-09-01T02:14:36.7319244Z ##[group]GITHUB_TOKEN Permissions2026-09-01T02:14:36.7322024Z Actions: write2026-09-01T02:14:36.7322569Z ArtifactMetadata: write2026-09-01T02:14:36.7323160Z Attestations: write2026-09-01T02:14:36.7323673Z Checks: write2026-09-01T02:14:36.7324178Z CodeQuality: write2026-09-01T02:14:36.7324698Z Contents: write2026-09-01T02:14:36.7325197Z CopilotRequests: write2026-09-01T02:14:36.7325887Z Deployments: write2026-09-01T02:14:36.7326429Z Discussions: write2026-09-01T02:14:36.7326943Z Drives: write2026-09-01T02:14:36.7327480Z Issues: write2026-09-01T02:14:36.7327958Z Metadata: read2026-09-01T02:14:36.7328528Z Models: read2026-09-01T02:14:36.7329044Z Packages: write2026-09-01T02:14:36.7329539Z Pages: write2026-09-01T02:14:36.7330029Z PullRequests: write2026-09-01T02:14:36.7330592Z RepositoryProjects: write2026-09-01T02:14:36.7331159Z SecurityEvents: write2026-09-01T02:14:36.7331697Z Statuses: write2026-09-01T02:14:36.7332248Z VulnerabilityAlerts: read2026-09-01T02:14:36.7332791Z ##[endgroup]2026-09-01T02:14:36.7334921Z Secret source: Actions2026-09-01T02:14:36.7336039Z Prepare workflow directory2026-09-01T02:14:36.7664987Z Prepare all required actions2026-09-01T02:14:36.7714518Z Getting action download info2026-09-01T02:14:37.1257711Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-09-01T02:14:37.2578393Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-09-01T02:14:37.8163753Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-09-01T02:14:38.3811817Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-09-01T02:14:38.6462380Z Uses: osg-htc/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-09-01T02:14:38.6468990Z ##[group] Inputs2026-09-01T02:14:38.6470018Z testName: TestOSPoolEP2026-09-01T02:14:38.6471739Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-09-01T02:14:38.6473515Z setupScript: 2026-09-01T02:14:38.6474418Z setupEnv: {}2026-09-01T02:14:38.6475308Z ##[endgroup]2026-09-01T02:14:38.6476760Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-09-01T02:14:38.7366667Z ##[group]Run actions/checkout@v52026-09-01T02:14:38.7367944Z with:2026-09-01T02:14:38.7368746Z repository: osg-htc/k8s-integration-tests2026-09-01T02:14:38.7376335Z token: ***2026-09-01T02:14:38.7377080Z ssh-strict: true2026-09-01T02:14:38.7377841Z ssh-user: git2026-09-01T02:14:38.7378610Z persist-credentials: true2026-09-01T02:14:38.7379444Z clean: true2026-09-01T02:14:38.7380203Z sparse-checkout-cone-mode: true2026-09-01T02:14:38.7381381Z fetch-depth: 12026-09-01T02:14:38.7382119Z fetch-tags: false2026-09-01T02:14:38.7382886Z show-progress: true2026-09-01T02:14:38.7383653Z lfs: false2026-09-01T02:14:38.7384360Z submodules: false2026-09-01T02:14:38.7385142Z set-safe-directory: true2026-09-01T02:14:38.7386116Z allow-unsafe-pr-checkout: false2026-09-01T02:14:38.7387432Z ##[endgroup]2026-09-01T02:14:38.8479166Z Syncing repository: osg-htc/k8s-integration-tests2026-09-01T02:14:38.8482063Z ##[group]Getting Git version info2026-09-01T02:14:38.8483693Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-09-01T02:14:38.8485968Z [command]/usr/bin/git version2026-09-01T02:14:38.8567669Z git version 2.55.02026-09-01T02:14:38.8591370Z ##[endgroup]2026-09-01T02:14:38.8602411Z Temporarily overriding HOME='/home/runner/work/_temp/d90f7b5d-3486-48db-a4d8-e709c6b8e14b' before making global git config changes2026-09-01T02:14:38.8614193Z Adding repository directory to the temporary git global config as a safe directory2026-09-01T02:14:38.8617277Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-09-01T02:14:38.8647216Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-09-01T02:14:38.8651445Z ##[group]Initializing the repository2026-09-01T02:14:38.8656539Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-09-01T02:14:38.8755284Z hint: Using 'master' as the name for the initial branch. This default branch name2026-09-01T02:14:38.8759224Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-09-01T02:14:38.8764956Z hint: to use in all of your new repositories, which will suppress this warning,2026-09-01T02:14:38.8767608Z hint: call:2026-09-01T02:14:38.8769058Z hint:2026-09-01T02:14:38.8770720Z hint: git config --global init.defaultBranch <name>2026-09-01T02:14:38.8772646Z hint:2026-09-01T02:14:38.8774579Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-09-01T02:14:38.8777455Z hint: 'development'. The just-created branch can be renamed via this command:2026-09-01T02:14:38.8778889Z hint:2026-09-01T02:14:38.8779701Z hint: git branch -m <name>2026-09-01T02:14:38.8780627Z hint:2026-09-01T02:14:38.8781812Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-09-01T02:14:38.8784353Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-09-01T02:14:38.8789675Z [command]/usr/bin/git remote add origin https://github.com/osg-htc/k8s-integration-tests2026-09-01T02:14:38.8814488Z ##[endgroup]2026-09-01T02:14:38.8817540Z ##[group]Disabling automatic garbage collection2026-09-01T02:14:38.8819722Z [command]/usr/bin/git config --local gc.auto 02026-09-01T02:14:38.8851266Z ##[endgroup]2026-09-01T02:14:38.8854019Z ##[group]Setting up auth2026-09-01T02:14:38.8857888Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-09-01T02:14:38.8894396Z [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-09-01T02:14:38.9243082Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-09-01T02:14:38.9280640Z [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-09-01T02:14:38.9497713Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-09-01T02:14:38.9533042Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-09-01T02:14:38.9729402Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-09-01T02:14:38.9764535Z ##[endgroup]2026-09-01T02:14:38.9766536Z ##[group]Fetching the repository2026-09-01T02:14:38.9777198Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-09-01T02:14:39.5097470Z From https://github.com/osg-htc/k8s-integration-tests2026-09-01T02:14:39.5098742Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-09-01T02:14:39.5102176Z ##[endgroup]2026-09-01T02:14:39.5103204Z ##[group]Determining the checkout info2026-09-01T02:14:39.5105086Z ##[endgroup]2026-09-01T02:14:39.5110770Z [command]/usr/bin/git sparse-checkout disable2026-09-01T02:14:39.5149287Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-09-01T02:14:39.5174570Z ##[group]Checking out the ref2026-09-01T02:14:39.5178638Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-09-01T02:14:39.5243239Z Switched to a new branch 'main'2026-09-01T02:14:39.5244308Z branch 'main' set up to track 'origin/main'.2026-09-01T02:14:39.5249230Z ##[endgroup]2026-09-01T02:14:39.5283448Z [command]/usr/bin/git log -1 --format=%H2026-09-01T02:14:39.5305702Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-09-01T02:14:39.5514167Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-09-01T02:14:39.5515083Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-09-01T02:14:39.5516090Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-09-01T02:14:39.7123485Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-09-01T02:14:39.7124257Z env:2026-09-01T02:14:39.7125173Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-09-01T02:14:39.7126311Z SETUP_ENV_YAML: {}2026-09-01T02:14:39.7126648Z ##[endgroup]2026-09-01T02:14:40.1829117Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-09-01T02:14:40.1829879Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-09-01T02:14:40.1873834Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-09-01T02:14:40.1874282Z env:2026-09-01T02:14:40.1874840Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-09-01T02:14:40.1875550Z ##[endgroup]2026-09-01T02:14:40.1999596Z ##[group]Run true2026-09-01T02:14:40.1999982Z [36;1mtrue[0m2026-09-01T02:14:40.2046598Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-09-01T02:14:40.2047081Z ##[endgroup]2026-09-01T02:14:40.2210718Z ##[group]Run medyagh/setup-minikube@latest2026-09-01T02:14:40.2211156Z with:2026-09-01T02:14:40.2211457Z start: true2026-09-01T02:14:40.2211761Z cache: true2026-09-01T02:14:40.2212102Z minikube-version: latest2026-09-01T02:14:40.2212446Z wait: all2026-09-01T02:14:40.2212739Z ##[endgroup]2026-09-01T02:14:40.3463073Z (node:1814) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-09-01T02:14:40.3464417Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-09-01T02:14:41.9193806Z [command]/usr/bin/chmod +x /home/runner/work/_temp/e048db76-c2c1-4a3f-8f18-9f3f76e967032026-09-01T02:14:42.0311577Z [command]/home/runner/bin/minikube version --short2026-09-01T02:14:42.0694382Z v1.38.12026-09-01T02:14:42.2911519Z Cache hit for: kic-v1.38.1-x642026-09-01T02:14:42.3014095Z (node:1814) [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-09-01T02:14:42.3022960Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-09-01T02:14:43.5126902Z Received 54525952 of 544082637 (10.0%), 51.9 MBs/sec2026-09-01T02:14:43.5251265Z Received 54525952 of 224218830 (24.3%), 52.1 MBs/sec2026-09-01T02:14:44.5103615Z Received 192937984 of 544082637 (35.5%), 91.9 MBs/sec2026-09-01T02:14:44.5258258Z Received 167772160 of 224218830 (74.8%), 80.0 MBs/sec2026-09-01T02:14:44.9544373Z Received 224218830 of 224218830 (100.0%), 88.0 MBs/sec2026-09-01T02:14:44.9545590Z Cache Size: ~214 MB (224218830 B)2026-09-01T02:14:44.9701785Z [command]/usr/bin/tar -xf /home/runner/work/_temp/f13fca52-db45-47e8-90ca-bd5206f8002d/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-09-01T02:14:45.5146782Z Received 331350016 of 544082637 (60.9%), 105.2 MBs/sec2026-09-01T02:14:45.6191611Z Cache restored successfully2026-09-01T02:14:46.5128659Z Received 515899392 of 544082637 (94.8%), 122.8 MBs/sec2026-09-01T02:14:46.8714029Z Received 544082637 of 544082637 (100.0%), 118.9 MBs/sec2026-09-01T02:14:46.8714884Z Cache Size: ~519 MB (544082637 B)2026-09-01T02:14:46.8884715Z [command]/usr/bin/tar -xf /home/runner/work/_temp/b38dc8f8-5449-4e8a-af5f-3b844bc6516a/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-09-01T02:14:47.4765344Z Cache restored successfully2026-09-01T02:14:47.5504681Z [command]/home/runner/bin/minikube start --wait all2026-09-01T02:14:47.5983729Z * minikube v1.38.1 on Ubuntu 22.042026-09-01T02:14:51.5462836Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-09-01T02:14:51.6100857Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-09-01T02:14:51.6108288Z * Using Docker driver with root privileges2026-09-01T02:14:51.6117830Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-09-01T02:14:51.6123959Z * Pulling base image v0.0.50 ...2026-09-01T02:15:19.6976115Z * Configuring bridge CNI (Container Networking Interface) ...2026-09-01T02:15:24.3552495Z * Verifying Kubernetes components...2026-09-01T02:15:24.3818709Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-09-01T02:15:24.8737699Z * Enabled addons: storage-provisioner, default-storageclass2026-09-01T02:16:04.4695000Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-09-01T02:16:04.4789466Z [command]/home/runner/bin/minikube version --short2026-09-01T02:16:04.5187913Z v1.38.12026-09-01T02:16:04.5430519Z ##[group]Run actions/setup-go@v62026-09-01T02:16:04.5430797Z with:2026-09-01T02:16:04.5430995Z go-version: 1.262026-09-01T02:16:04.5431198Z cache: true2026-09-01T02:16:04.5431416Z cache-dependency-path: go.sum2026-09-01T02:16:04.5431661Z check-latest: false2026-09-01T02:16:04.5434085Z token: ***2026-09-01T02:16:04.5434284Z ##[endgroup]2026-09-01T02:16:04.6724727Z Setup go version spec 1.262026-09-01T02:16:04.6939192Z Found in cache @ /opt/hostedtoolcache/go/1.26.7/x642026-09-01T02:16:04.6942129Z Added go to the path2026-09-01T02:16:04.6945984Z Successfully set up Go version 1.262026-09-01T02:16:05.1986378Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOMODCACHE2026-09-01T02:16:05.2021090Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOCACHE2026-09-01T02:16:05.2050940Z /home/runner/go/pkg/mod2026-09-01T02:16:05.2068705Z /home/runner/.cache/go-build2026-09-01T02:16:05.4306608Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.7-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-09-01T02:16:06.6571067Z Received 37748736 of 251715014 (15.0%), 36.0 MBs/sec2026-09-01T02:16:07.6581020Z Received 180355072 of 251715014 (71.7%), 86.0 MBs/sec2026-09-01T02:16:08.1481592Z Received 251715014 of 251715014 (100.0%), 96.4 MBs/sec2026-09-01T02:16:08.1482883Z Cache Size: ~240 MB (251715014 B)2026-09-01T02:16:08.1610770Z [command]/usr/bin/tar -xf /home/runner/work/_temp/53091e31-eb86-40e8-ac66-eef78f8992e7/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-09-01T02:16:10.1777013Z Cache restored successfully2026-09-01T02:16:10.2116536Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.7-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-09-01T02:16:10.2147022Z go version go1.26.7 linux/amd642026-09-01T02:16:10.2147358Z 2026-09-01T02:16:10.2147710Z ##[group]go env2026-09-01T02:16:10.2777674Z AR='ar'2026-09-01T02:16:10.2778340Z CC='gcc'2026-09-01T02:16:10.2778863Z CGO_CFLAGS='-O2 -g'2026-09-01T02:16:10.2779239Z CGO_CPPFLAGS=''2026-09-01T02:16:10.2779582Z CGO_CXXFLAGS='-O2 -g'2026-09-01T02:16:10.2779931Z CGO_ENABLED='1'2026-09-01T02:16:10.2780244Z CGO_FFLAGS='-O2 -g'2026-09-01T02:16:10.2780587Z CGO_LDFLAGS='-O2 -g'2026-09-01T02:16:10.2780935Z CXX='g++'2026-09-01T02:16:10.2781241Z GCCGO='gccgo'2026-09-01T02:16:10.2781563Z GO111MODULE=''2026-09-01T02:16:10.2781891Z GOAMD64='v1'2026-09-01T02:16:10.2782241Z GOARCH='amd64'2026-09-01T02:16:10.2782585Z GOAUTH='netrc'2026-09-01T02:16:10.2782936Z GOBIN=''2026-09-01T02:16:10.2783304Z GOCACHE='/home/runner/.cache/go-build'2026-09-01T02:16:10.2783761Z GOCACHEPROG=''2026-09-01T02:16:10.2784100Z GODEBUG=''2026-09-01T02:16:10.2784482Z GOENV='/home/runner/.config/go/env'2026-09-01T02:16:10.2784922Z GOEXE=''2026-09-01T02:16:10.2785246Z GOEXPERIMENT=''2026-09-01T02:16:10.2785928Z GOFIPS140='off'2026-09-01T02:16:10.2786269Z GOFLAGS=''2026-09-01T02:16:10.2787396Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build3643531868=/tmp/go-build -gno-record-gcc-switches'2026-09-01T02:16:10.2788493Z GOHOSTARCH='amd64'2026-09-01T02:16:10.2788861Z GOHOSTOS='linux'2026-09-01T02:16:10.2789217Z GOINSECURE=''2026-09-01T02:16:10.2789803Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-09-01T02:16:10.2790488Z GOMODCACHE='/home/runner/go/pkg/mod'2026-09-01T02:16:10.2790962Z GONOPROXY=''2026-09-01T02:16:10.2791288Z GONOSUMDB=''2026-09-01T02:16:10.2791610Z GOOS='linux'2026-09-01T02:16:10.2791934Z GOPATH='/home/runner/go'2026-09-01T02:16:10.2792323Z GOPRIVATE=''2026-09-01T02:16:10.2792728Z GOPROXY='https://proxy.golang.org,direct'2026-09-01T02:16:10.2793278Z GOROOT='/opt/hostedtoolcache/go/1.26.7/x64'2026-09-01T02:16:10.2793750Z GOSUMDB='sum.golang.org'2026-09-01T02:16:10.2794129Z GOTELEMETRY='local'2026-09-01T02:16:10.2794563Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-09-01T02:16:10.2795022Z GOTMPDIR=''2026-09-01T02:16:10.2795942Z GOTOOLCHAIN='local'2026-09-01T02:16:10.2796504Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.7/x64/pkg/tool/linux_amd64'2026-09-01T02:16:10.2797061Z GOVCS=''2026-09-01T02:16:10.2797353Z GOVERSION='go1.26.7'2026-09-01T02:16:10.2797686Z GOWORK=''2026-09-01T02:16:10.2797993Z PKG_CONFIG='pkg-config'2026-09-01T02:16:10.2798228Z 2026-09-01T02:16:10.2798721Z ##[endgroup]2026-09-01T02:16:10.2939757Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-09-01T02:16:10.2940242Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-09-01T02:16:10.2980051Z shell: /usr/bin/bash -e {0}2026-09-01T02:16:10.2980315Z env:2026-09-01T02:16:10.2980526Z GOTOOLCHAIN: local2026-09-01T02:16:10.2980769Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-09-01T02:16:10.2981047Z OSPOOL_EP_CMTag: 25.x-el92026-09-01T02:16:10.2981296Z OSPOOL_EP_CvmfsType: cvmfsexec2026-09-01T02:16:10.2981553Z ##[endgroup]2026-09-01T02:16:15.4560527Z === RUN TestOSPoolEP2026-09-01T02:16:15.4563293Z === PAUSE TestOSPoolEP2026-09-01T02:16:15.4563743Z === CONT TestOSPoolEP2026-09-01T02:16:15.4564743Z TestOSPoolEP 2026-09-01T02:16:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:15.4676088Z TestOSPoolEP 2026-09-01T02:16:15Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 apply -f /tmp/TestOSPoolEP3805615011]2026-09-01T02:16:16.0497377Z TestOSPoolEP 2026-09-01T02:16:16Z logger.go:67: pod/idtoken-generator created2026-09-01T02:16:16.1578763Z TestOSPoolEP 2026-09-01T02:16:16Z logger.go:67: secret/pool-passwd created2026-09-01T02:16:16.1609942Z TestOSPoolEP 2026-09-01T02:16:16Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-09-01T02:16:16.1611617Z TestOSPoolEP 2026-09-01T02:16:16Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:16.2110355Z TestOSPoolEP 2026-09-01T02:16:16Z 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-09-01T02:16:26.2116636Z TestOSPoolEP 2026-09-01T02:16:26Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-09-01T02:16:26.2118154Z TestOSPoolEP 2026-09-01T02:16:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:26.2143260Z TestOSPoolEP 2026-09-01T02:16:26Z 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-09-01T02:16:36.2148838Z TestOSPoolEP 2026-09-01T02:16:36Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-09-01T02:16:36.2150255Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:36.2176543Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Pod is now available2026-09-01T02:16:36.5924541Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-09-01T02:16:36.5929049Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: ***2026-09-01T02:16:36.5967760Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 apply -f /tmp/TestOSPoolEP1081022953]2026-09-01T02:16:36.6960993Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: secret/pool-token created2026-09-01T02:16:36.6980318Z TestOSPoolEP 2026-09-01T02:16:36Z retry.go:91: Wait for secret pool-token to be provisioned.2026-09-01T02:16:36.6981253Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:36.6999603Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Secret is now available2026-09-01T02:16:36.7025706Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 apply -k /tmp/kustomize-template-2429597508]2026-09-01T02:16:36.8306273Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: service/test-cm created2026-09-01T02:16:36.8385188Z TestOSPoolEP 2026-09-01T02:16:36Z 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-09-01T02:16:36.8387300Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: deployment.apps/ospool-ep created2026-09-01T02:16:36.8486819Z TestOSPoolEP 2026-09-01T02:16:36Z logger.go:67: deployment.apps/test-cm created2026-09-01T02:16:36.8501703Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-09-01T02:16:36.8503687Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:36.8569988Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-09-01T02:16:36.8576462Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:36.8578489Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-09-01T02:16:36.8586111Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:36.8653791Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z 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-09-01T02:16:36.8698506Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:36Z 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-09-01T02:16:46.8659911Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:46Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-09-01T02:16:46.8661620Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:46Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:46.8688829Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:46Z logger.go:67: Deployment is now available2026-09-01T02:16:46.8711715Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:46Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-09-01T02:16:46.8713837Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:46Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:46.8726476Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:46Z 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-09-01T02:16:56.8728979Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:56Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-09-01T02:16:56.8730717Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:56Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:16:56.8758652Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:16:56Z 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-09-01T02:17:06.8759690Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:17:06Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-09-01T02:17:06.8761453Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8803541Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-09-01T02:17:06Z logger.go:67: Deployment is now available2026-09-01T02:17:06.8804772Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-09-01T02:17:06.8805662Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-09-01T02:17:06.8806427Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-09-01T02:17:06.8807252Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-09-01T02:17:06.8808122Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-09-01T02:17:06.8809251Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-09-01T02:17:06.8810151Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-09-01T02:17:06.8811053Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-09-01T02:17:06.8812503Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8814353Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-09-01T02:17:06.8816005Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8818228Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8832245Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8834912Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8838761Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:06Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:17:06.8860703Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:17:06Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:17:06.8883615Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:06Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)']2026-09-01T02:17:06.8897109Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-09-01T02:17:06Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_SINGULARITY]2026-09-01T02:17:07.0324967Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-09-01T02:17:07.0329097Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)']' in pod test-cm-5bb545475c-wslwl failed. Retrying in 10s.2026-09-01T02:17:07.0351053Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-09-01T02:17:07.0353176Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:17:07.0378597Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-09-01T02:17:07.0379813Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 10s.2026-09-01T02:17:17.0329727Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:17Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)']2026-09-01T02:17:17.0397723Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-09-01T02:17:17Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_SINGULARITY]2026-09-01T02:17:17.1565344Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-09-01T02:17:17.1568069Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)']' in pod test-cm-5bb545475c-wslwl failed. Retrying in 10s.2026-09-01T02:17:17.1878094Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-09-01T02:17:17.1880169Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 10s.2026-09-01T02:17:27.1568986Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)']2026-09-01T02:17:27.1883286Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-09-01T02:17:27Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_SINGULARITY]2026-09-01T02:17:27.2637637Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-09-01T02:17:27.2640737Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: 2026-09-01T02:17:27.2642409Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-sjfm4 LINUX X86_64 Unclaimed Idle 0.000 15987 0+00:00:002026-09-01T02:17:27.2644197Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: 2026-09-01T02:17:27.2645644Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-09-01T02:17:27.2647369Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: 2026-09-01T02:17:27.2648914Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-09-01T02:17:27.2668931Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: 2026-09-01T02:17:27.2672226Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-09-01T02:17:27Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-09-01T02:17:27.3038938Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-09-01T02:17:27Z logger.go:67: true2026-09-01T02:17:37.0361441Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:17:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:17:37.1401721Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:17:37Z logger.go:67: undefined2026-09-01T02:17:37.1438118Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-09-01T02:17:37.1439480Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:18:07.1450189Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:18:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:18:07.2409422Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:18:07Z logger.go:67: undefined2026-09-01T02:18:07.2446756Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:18:37.2450943Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:18:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:18:37.3448818Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:18:37Z logger.go:67: undefined2026-09-01T02:18:37.3491325Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:19:07.3495213Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:19:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:19:07.4475642Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:19:07Z logger.go:67: undefined2026-09-01T02:19:07.4517669Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:19:37.4531651Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:19:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:19:37.5553365Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:19:37Z logger.go:67: undefined2026-09-01T02:19:37.5594330Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:20:07.5612713Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:20:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:20:07.6580871Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:20:07Z logger.go:67: undefined2026-09-01T02:20:07.6635490Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:20:37.6637897Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:20:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:20:37.7649970Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:20:37Z logger.go:67: undefined2026-09-01T02:20:37.7692304Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:21:07.7698554Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:21:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:21:07.8632142Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:21:07Z logger.go:67: undefined2026-09-01T02:21:07.8670499Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:21:37.8695836Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:21:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:21:37.9657684Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:21:37Z logger.go:67: undefined2026-09-01T02:21:37.9697620Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:22:07.9719355Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:22:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:22:08.0710917Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:22:08Z logger.go:67: undefined2026-09-01T02:22:08.0753156Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wslwl failed. Retrying in 30s.2026-09-01T02:22:38.0769655Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:22:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 exec test-cm-5bb545475c-wslwl -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-sjfm4",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-09-01T02:22:38.1913989Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-09-01T02:22:38Z logger.go:67: true2026-09-01T02:22:38.1956560Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.2030926Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.2869256Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.3737013Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.4480747Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.4507895Z === NAME TestOSPoolEP2026-09-01T02:22:38.4508553Z ospool_ep_test.go:101: ---2026-09-01T02:22:38.4509170Z Events for pod idtoken-generator:2026-09-01T02:22:38.4510434Z 2026-09-01 02:16:16 +0000 UTC Normal Successfully assigned test-ospool-ep-qtvv02/idtoken-generator to minikube2026-09-01T02:22:38.4511607Z 2026-09-01 02:16:18 +0000 UTC Normal Pulling image "htcondor/base"2026-09-01T02:22:38.4513113Z 2026-09-01 02:16:26 +0000 UTC Normal Successfully pulled image "htcondor/base" in 8.249s (8.249s including waiting). Image size: 522365028 bytes.2026-09-01T02:22:38.4514341Z 2026-09-01 02:16:26 +0000 UTC Normal Container created2026-09-01T02:22:38.4515135Z 2026-09-01 02:16:26 +0000 UTC Normal Container started2026-09-01T02:22:38.4515972Z 2026-09-01T02:22:38.4516665Z ---2026-09-01T02:22:38.4517593Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.4534400Z ospool_ep_test.go:101: ---2026-09-01T02:22:38.4535216Z Events for pod ospool-ep-65c844ddbb-sjfm4:2026-09-01T02:22:38.4536920Z 2026-09-01 02:16:36 +0000 UTC Normal Successfully assigned test-ospool-ep-qtvv02/ospool-ep-65c844ddbb-sjfm4 to minikube2026-09-01T02:22:38.4538742Z 2026-09-01 02:16:37 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-09-01T02:22:38.4540714Z 2026-09-01 02:16:39 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.445s (2.445s including waiting). Image size: 5590942 bytes.2026-09-01T02:22:38.4541737Z 2026-09-01 02:16:39 +0000 UTC Normal Container created2026-09-01T02:22:38.4542272Z 2026-09-01 02:16:39 +0000 UTC Normal Container started2026-09-01T02:22:38.4543077Z 2026-09-01 02:16:40 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-09-01T02:22:38.4544476Z 2026-09-01 02:16:57 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 16.031s (16.657s including waiting). Image size: 1290542464 bytes.2026-09-01T02:22:38.4545294Z 2026-09-01 02:16:57 +0000 UTC Normal Container created2026-09-01T02:22:38.4546076Z 2026-09-01 02:16:57 +0000 UTC Normal Container started2026-09-01T02:22:38.4546546Z 2026-09-01T02:22:38.4546767Z ---2026-09-01T02:22:38.4547511Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.4560300Z ospool_ep_test.go:101: ---2026-09-01T02:22:38.4560999Z Events for pod test-cm-5bb545475c-wslwl:2026-09-01T02:22:38.4562348Z 2026-09-01 02:16:36 +0000 UTC Normal Successfully assigned test-ospool-ep-qtvv02/test-cm-5bb545475c-wslwl to minikube2026-09-01T02:22:38.4563675Z 2026-09-01 02:16:37 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-09-01T02:22:38.4565284Z 2026-09-01 02:16:41 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 1.254s (3.682s including waiting). Image size: 522365054 bytes.2026-09-01T02:22:38.4567687Z 2026-09-01 02:16:41 +0000 UTC Normal Container created2026-09-01T02:22:38.4568620Z 2026-09-01 02:16:41 +0000 UTC Normal Container started2026-09-01T02:22:38.4569234Z 2026-09-01T02:22:38.4569652Z ---2026-09-01T02:22:38.4570700Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-09-01T02:22:38.4602278Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 delete -f /tmp/TestOSPoolEP1337278030]2026-09-01T02:22:38.5096038Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-qtvv02 namespace2026-09-01T02:22:38.5132673Z TestOSPoolEP 2026-09-01T02:22:38Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-qtvv02 namespace2026-09-01T02:22:40.5132404Z TestOSPoolEP 2026-09-01T02:22:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 delete -f /tmp/TestOSPoolEP2533532875]2026-09-01T02:22:40.5774717Z TestOSPoolEP 2026-09-01T02:22:40Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-qtvv02 namespace2026-09-01T02:22:40.5808941Z TestOSPoolEP 2026-09-01T02:22:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-qtvv02 delete -k /tmp/kustomize-template-2429597508]2026-09-01T02:22:40.6466883Z TestOSPoolEP 2026-09-01T02:22:40Z logger.go:67: service "test-cm" deleted from test-ospool-ep-qtvv02 namespace2026-09-01T02:22:40.6537807Z TestOSPoolEP 2026-09-01T02:22:40Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-qtvv02 namespace2026-09-01T02:22:40.6584302Z TestOSPoolEP 2026-09-01T02:22:40Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-qtvv02 namespace2026-09-01T02:22:40.6692368Z --- PASS: TestOSPoolEP (53.90s)2026-09-01T02:22:40.6695159Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-09-01T02:22:40.6697806Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.39s)2026-09-01T02:22:40.6700464Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.43s)2026-09-01T02:22:40.6701978Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (331.32s)2026-09-01T02:22:40.6704199Z PASS2026-09-01T02:22:40.6753952Z ok github.com/osg-htc/k8s-integration-tests/test 385.227s2026-09-01T02:22:40.7354948Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-09-01T02:22:40.7356222Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-09-01T02:22:40.7396394Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-09-01T02:22:40.7396750Z env:2026-09-01T02:22:40.7396968Z GOTOOLCHAIN: local2026-09-01T02:22:40.7397414Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-09-01T02:22:40.7397884Z ##[endgroup]2026-09-01T02:22:40.7556607Z 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-09-01T02:22:40.7558867Z ##[group]Run actions/upload-artifact@v42026-09-01T02:22:40.7559296Z with:2026-09-01T02:22:40.7559919Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-09-01T02:22:40.7560605Z path: /tmp/k8s-tests2026-09-01T02:22:40.7560963Z retention-days: 52026-09-01T02:22:40.7561302Z if-no-files-found: warn2026-09-01T02:22:40.7561677Z compression-level: 62026-09-01T02:22:40.7562011Z overwrite: false2026-09-01T02:22:40.7562356Z include-hidden-files: false2026-09-01T02:22:40.7562765Z env:2026-09-01T02:22:40.7563045Z GOTOOLCHAIN: local2026-09-01T02:22:40.7563387Z ##[endgroup]2026-09-01T02:22:40.9580497Z (node:17405) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-09-01T02:22:40.9581706Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-09-01T02:22:40.9690984Z With the provided path, there will be 6 files uploaded2026-09-01T02:22:40.9703400Z Artifact name is valid!2026-09-01T02:22:40.9705076Z Root directory input is valid!2026-09-01T02:22:41.3312965Z Beginning upload of artifact content to blob storage2026-09-01T02:22:41.3647696Z (node:17405) [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-09-01T02:22:41.6347774Z Uploaded bytes 141172026-09-01T02:22:41.6987318Z Finished uploading artifact content to blob storage!2026-09-01T02:22:41.6988465Z SHA256 digest of uploaded artifact zip is 4655784a08113e2279aa794383ea76f5f957ddc9074e6ac1cd6013120fc2f86b2026-09-01T02:22:41.6989378Z Finalizing artifact upload2026-09-01T02:22:41.9543745Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 97835865432026-09-01T02:22:41.9545838Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 14117 bytes. Artifact ID is 97835865432026-09-01T02:22:41.9551917Z Artifact download URL: https://github.com/osg-htc/k8s-integration-tests/actions/runs/33461797843/artifacts/97835865432026-09-01T02:22:41.9697835Z Post job cleanup.2026-09-01T02:22:42.0962930Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOMODCACHE2026-09-01T02:22:42.1016688Z [command]/opt/hostedtoolcache/go/1.26.7/x64/bin/go env GOCACHE2026-09-01T02:22:42.1049255Z /home/runner/go/pkg/mod2026-09-01T02:22:42.1072081Z /home/runner/.cache/go-build2026-09-01T02:22:42.1080526Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.7-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-09-01T02:22:42.1237773Z Post job cleanup.2026-09-01T02:22:42.2267711Z [command]/usr/bin/git version2026-09-01T02:22:42.2316616Z git version 2.55.02026-09-01T02:22:42.2363479Z Temporarily overriding HOME='/home/runner/work/_temp/7ef92289-c958-457c-b160-5530c955e0ad' before making global git config changes2026-09-01T02:22:42.2364529Z Adding repository directory to the temporary git global config as a safe directory2026-09-01T02:22:42.2365704Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-09-01T02:22:42.2412740Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-09-01T02:22:42.2454201Z [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-09-01T02:22:42.2693504Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-09-01T02:22:42.2720475Z http.https://github.com/.extraheader2026-09-01T02:22:42.2732868Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-09-01T02:22:42.2765178Z [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-09-01T02:22:42.2979026Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-09-01T02:22:42.3012250Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-09-01T02:22:42.3387052Z Cleaning up orphan processes2026-09-01T02:22:42.3976626Z ##[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/