2026-08-13T01:03:44.0227719Z Current runner version: '2.336.0'2026-08-13T01:03:44.0254302Z ##[group]Runner Image Provisioner2026-08-13T01:03:44.0255201Z Hosted Compute Agent2026-08-13T01:03:44.0255937Z Version: 20260707.5632026-08-13T01:03:44.0256695Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-13T01:03:44.0257494Z Build Date: 2026-07-07T19:33:50Z2026-08-13T01:03:44.0258558Z Worker ID: {f155874e-b4fa-4b19-a957-25502a77dcc6}2026-08-13T01:03:44.0259374Z Azure Region: westus2026-08-13T01:03:44.0260053Z ##[endgroup]2026-08-13T01:03:44.0261645Z ##[group]Operating System2026-08-13T01:03:44.0262312Z Ubuntu2026-08-13T01:03:44.0263002Z 22.04.52026-08-13T01:03:44.0263542Z LTS2026-08-13T01:03:44.0264128Z ##[endgroup]2026-08-13T01:03:44.0264745Z ##[group]Runner Image2026-08-13T01:03:44.0265389Z Image: ubuntu-22.042026-08-13T01:03:44.0266102Z Version: 20260720.234.22026-08-13T01:03:44.0267433Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-13T01:03:44.0269503Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-13T01:03:44.0270516Z ##[endgroup]2026-08-13T01:03:44.0272000Z ##[group]GITHUB_TOKEN Permissions2026-08-13T01:03:44.0274349Z Contents: read2026-08-13T01:03:44.0275094Z Metadata: read2026-08-13T01:03:44.0275752Z Packages: read2026-08-13T01:03:44.0276285Z ##[endgroup]2026-08-13T01:03:44.0279262Z Secret source: Actions2026-08-13T01:03:44.0280429Z Prepare workflow directory2026-08-13T01:03:44.0624951Z Prepare all required actions2026-08-13T01:03:44.0676220Z Getting action download info2026-08-13T01:03:44.4262584Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-13T01:03:44.8431658Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-13T01:03:45.1790927Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-13T01:03:45.5207004Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-13T01:03:45.7813846Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-13T01:03:45.7819718Z ##[group] Inputs2026-08-13T01:03:45.7820331Z testName: TestOSPoolEP2026-08-13T01:03:45.7821639Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-13T01:03:45.7823091Z setupScript: 2026-08-13T01:03:45.7823589Z setupEnv: {}2026-08-13T01:03:45.7824077Z ##[endgroup]2026-08-13T01:03:45.7825020Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-08-13T01:03:45.8664377Z ##[group]Run actions/checkout@v52026-08-13T01:03:45.8665384Z with:2026-08-13T01:03:45.8665951Z repository: mwestphall/k8s-integration-tests2026-08-13T01:03:45.8675085Z token: ***2026-08-13T01:03:45.8675583Z ssh-strict: true2026-08-13T01:03:45.8676115Z ssh-user: git2026-08-13T01:03:45.8676621Z persist-credentials: true2026-08-13T01:03:45.8677217Z clean: true2026-08-13T01:03:45.8677726Z sparse-checkout-cone-mode: true2026-08-13T01:03:45.8678624Z fetch-depth: 12026-08-13T01:03:45.8679111Z fetch-tags: false2026-08-13T01:03:45.8679613Z show-progress: true2026-08-13T01:03:45.8680129Z lfs: false2026-08-13T01:03:45.8680585Z submodules: false2026-08-13T01:03:45.8681101Z set-safe-directory: true2026-08-13T01:03:45.8681723Z allow-unsafe-pr-checkout: false2026-08-13T01:03:45.8682694Z ##[endgroup]2026-08-13T01:03:45.9815131Z Syncing repository: mwestphall/k8s-integration-tests2026-08-13T01:03:45.9817380Z ##[group]Getting Git version info2026-08-13T01:03:45.9818937Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-13T01:03:45.9820718Z [command]/usr/bin/git version2026-08-13T01:03:45.9939603Z git version 2.54.02026-08-13T01:03:45.9984106Z ##[endgroup]2026-08-13T01:03:45.9993188Z Temporarily overriding HOME='/home/runner/work/_temp/5d413926-a8ed-4768-9157-f89cbddc1d96' before making global git config changes2026-08-13T01:03:45.9997211Z Adding repository directory to the temporary git global config as a safe directory2026-08-13T01:03:46.0001204Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-13T01:03:46.0029432Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-13T01:03:46.0033474Z ##[group]Initializing the repository2026-08-13T01:03:46.0039477Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-13T01:03:46.0149365Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-13T01:03:46.0153040Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-13T01:03:46.0157892Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-13T01:03:46.0160324Z hint: call:2026-08-13T01:03:46.0161544Z hint:2026-08-13T01:03:46.0162638Z hint: git config --global init.defaultBranch <name>2026-08-13T01:03:46.0163558Z hint:2026-08-13T01:03:46.0164355Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-13T01:03:46.0165804Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-13T01:03:46.0167111Z hint:2026-08-13T01:03:46.0167626Z hint: git branch -m <name>2026-08-13T01:03:46.0168424Z hint:2026-08-13T01:03:46.0169342Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-13T01:03:46.0172334Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-13T01:03:46.0177155Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-13T01:03:46.0206184Z ##[endgroup]2026-08-13T01:03:46.0207140Z ##[group]Disabling automatic garbage collection2026-08-13T01:03:46.0210710Z [command]/usr/bin/git config --local gc.auto 02026-08-13T01:03:46.0244882Z ##[endgroup]2026-08-13T01:03:46.0245861Z ##[group]Setting up auth2026-08-13T01:03:46.0253154Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-13T01:03:46.0293747Z [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-13T01:03:46.0713441Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-13T01:03:46.0758169Z [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-13T01:03:46.1025881Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-13T01:03:46.1073695Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-13T01:03:46.1352020Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-13T01:03:46.1396432Z ##[endgroup]2026-08-13T01:03:46.1404706Z ##[group]Fetching the repository2026-08-13T01:03:46.1411356Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-13T01:03:46.6571225Z From https://github.com/mwestphall/k8s-integration-tests2026-08-13T01:03:46.6574070Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-13T01:03:46.6600775Z ##[endgroup]2026-08-13T01:03:46.6603213Z ##[group]Determining the checkout info2026-08-13T01:03:46.6605847Z ##[endgroup]2026-08-13T01:03:46.6610215Z [command]/usr/bin/git sparse-checkout disable2026-08-13T01:03:46.6657842Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-13T01:03:46.6690389Z ##[group]Checking out the ref2026-08-13T01:03:46.6695921Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-13T01:03:46.6791021Z Switched to a new branch 'main'2026-08-13T01:03:46.6795580Z branch 'main' set up to track 'origin/main'.2026-08-13T01:03:46.6812963Z ##[endgroup]2026-08-13T01:03:46.6854713Z [command]/usr/bin/git log -1 --format=%H2026-08-13T01:03:46.6883292Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-13T01:03:46.7239127Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-13T01:03:46.7241681Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-13T01:03:46.7244026Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-13T01:03:46.7370401Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-13T01:03:46.7372329Z env:2026-08-13T01:03:46.7374423Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-13T01:03:46.7376709Z SETUP_ENV_YAML: {}2026-08-13T01:03:46.7377772Z ##[endgroup]2026-08-13T01:03:46.8571498Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-13T01:03:46.8573863Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-13T01:03:46.8638338Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-13T01:03:46.8639741Z env:2026-08-13T01:03:46.8641494Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-13T01:03:46.8643576Z ##[endgroup]2026-08-13T01:03:46.8837622Z ##[group]Run true2026-08-13T01:03:46.8838880Z [36;1mtrue[0m2026-08-13T01:03:46.8901235Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-13T01:03:46.8902587Z ##[endgroup]2026-08-13T01:03:46.9190804Z ##[group]Run medyagh/setup-minikube@latest2026-08-13T01:03:46.9191962Z with:2026-08-13T01:03:46.9192742Z start: true2026-08-13T01:03:46.9193555Z cache: true2026-08-13T01:03:46.9194394Z minikube-version: latest2026-08-13T01:03:46.9195343Z wait: all2026-08-13T01:03:46.9196132Z ##[endgroup]2026-08-13T01:03:47.0551263Z (node:2063) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-13T01:03:47.0555244Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-13T01:03:48.1301627Z [command]/usr/bin/chmod +x /home/runner/work/_temp/fe37167b-e3e6-43f4-af20-c4179f5baf0d2026-08-13T01:03:48.2976134Z [command]/home/runner/bin/minikube version --short2026-08-13T01:03:48.3390455Z v1.38.12026-08-13T01:03:48.6094019Z Cache hit for: kic-v1.38.1-x642026-08-13T01:03:48.6111717Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-13T01:03:48.6220036Z (node:2063) [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-13T01:03:49.9106782Z Received 4194304 of 544082657 (0.8%), 4.0 MBs/sec2026-08-13T01:03:49.9301868Z Received 4194304 of 224218863 (1.9%), 4.0 MBs/sec2026-08-13T01:03:50.9116862Z Received 104857600 of 544082657 (19.3%), 50.0 MBs/sec2026-08-13T01:03:50.9317425Z Received 88080384 of 224218863 (39.3%), 42.0 MBs/sec2026-08-13T01:03:51.9134042Z Received 188743680 of 544082657 (34.7%), 59.9 MBs/sec2026-08-13T01:03:51.9314328Z Received 150994944 of 224218863 (67.3%), 48.0 MBs/sec2026-08-13T01:03:52.7838720Z Received 224218863 of 224218863 (100.0%), 55.5 MBs/sec2026-08-13T01:03:52.7839756Z Cache Size: ~214 MB (224218863 B)2026-08-13T01:03:52.7885574Z [command]/usr/bin/tar -xf /home/runner/work/_temp/d241a521-6932-4299-a0cb-d5fe472d4389/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-13T01:03:52.9154024Z Received 264241152 of 544082657 (48.6%), 62.9 MBs/sec2026-08-13T01:03:53.3618697Z Cache restored successfully2026-08-13T01:03:53.9145533Z Received 331350016 of 544082657 (60.9%), 63.1 MBs/sec2026-08-13T01:03:54.9147678Z Received 398458880 of 544082657 (73.2%), 63.3 MBs/sec2026-08-13T01:03:55.9145264Z Received 398458880 of 544082657 (73.2%), 54.3 MBs/sec2026-08-13T01:03:56.9150500Z Received 452984832 of 544082657 (83.3%), 54.0 MBs/sec2026-08-13T01:03:57.9156124Z Received 528482304 of 544082657 (97.1%), 56.0 MBs/sec2026-08-13T01:03:58.9152853Z Received 536870912 of 544082657 (98.7%), 51.2 MBs/sec2026-08-13T01:03:59.8080053Z Received 544082657 of 544082657 (100.0%), 47.6 MBs/sec2026-08-13T01:03:59.8081479Z Cache Size: ~519 MB (544082657 B)2026-08-13T01:03:59.8233236Z [command]/usr/bin/tar -xf /home/runner/work/_temp/e3e24d53-6130-4b60-aa5e-df8701be5070/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-13T01:04:00.7676400Z Cache restored successfully2026-08-13T01:04:00.8845529Z [command]/home/runner/bin/minikube start --wait all2026-08-13T01:04:00.9306373Z * minikube v1.38.1 on Ubuntu 22.042026-08-13T01:04:01.3472481Z ! The minimum required version for podman is "4.9.0". your version is "3.4.4". minikube might not work. use at your own risk. To install latest version please see https://podman.io/getting-started/installation.html2026-08-13T01:04:01.3480569Z * Automatically selected the docker driver. Other choices: podman, ssh, none2026-08-13T01:04:01.4040331Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-13T01:04:01.4052845Z * Using Docker driver with root privileges2026-08-13T01:04:01.4064416Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-13T01:04:01.4076223Z * Pulling base image v0.0.50 ...2026-08-13T01:04:34.2404998Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-13T01:04:39.4186563Z * Verifying Kubernetes components...2026-08-13T01:04:39.4492937Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-13T01:04:40.1910135Z * Enabled addons: storage-provisioner, default-storageclass2026-08-13T01:05:16.6526679Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-13T01:05:16.6657129Z [command]/home/runner/bin/minikube version --short2026-08-13T01:05:16.7033467Z v1.38.12026-08-13T01:05:16.7346396Z ##[group]Run actions/setup-go@v62026-08-13T01:05:16.7346694Z with:2026-08-13T01:05:16.7346893Z go-version: 1.262026-08-13T01:05:16.7347116Z cache: true2026-08-13T01:05:16.7347328Z cache-dependency-path: go.sum2026-08-13T01:05:16.7347583Z check-latest: false2026-08-13T01:05:16.7350318Z token: ***2026-08-13T01:05:16.7350540Z ##[endgroup]2026-08-13T01:05:16.8739426Z Setup go version spec 1.262026-08-13T01:05:16.8799058Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-13T01:05:16.8801521Z Added go to the path2026-08-13T01:05:16.8804808Z Successfully set up Go version 1.262026-08-13T01:05:16.9509738Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-13T01:05:16.9559726Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-13T01:05:16.9620289Z /home/runner/go/pkg/mod2026-08-13T01:05:16.9654849Z /home/runner/.cache/go-build2026-08-13T01:05:17.2283378Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-13T01:05:18.5064737Z Received 4194304 of 251839808 (1.7%), 4.0 MBs/sec2026-08-13T01:05:19.5469495Z Received 134217728 of 251839808 (53.3%), 62.7 MBs/sec2026-08-13T01:05:20.5503793Z Received 251839808 of 251839808 (100.0%), 78.9 MBs/sec2026-08-13T01:05:20.5505417Z Cache Size: ~240 MB (251839808 B)2026-08-13T01:05:20.5579216Z [command]/usr/bin/tar -xf /home/runner/work/_temp/d6891b80-e539-4986-97dc-a1cabf6d1f59/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-13T01:05:23.6625441Z Cache restored successfully2026-08-13T01:05:23.7163128Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-13T01:05:23.7197132Z go version go1.26.5 linux/amd642026-08-13T01:05:23.7197376Z 2026-08-13T01:05:23.7197759Z ##[group]go env2026-08-13T01:05:23.7373418Z AR='ar'2026-08-13T01:05:23.7373866Z CC='gcc'2026-08-13T01:05:23.7374274Z CGO_CFLAGS='-O2 -g'2026-08-13T01:05:23.7374728Z CGO_CPPFLAGS=''2026-08-13T01:05:23.7375149Z CGO_CXXFLAGS='-O2 -g'2026-08-13T01:05:23.7375581Z CGO_ENABLED='1'2026-08-13T01:05:23.7375980Z CGO_FFLAGS='-O2 -g'2026-08-13T01:05:23.7376383Z CGO_LDFLAGS='-O2 -g'2026-08-13T01:05:23.7376776Z CXX='g++'2026-08-13T01:05:23.7377109Z GCCGO='gccgo'2026-08-13T01:05:23.7377475Z GO111MODULE=''2026-08-13T01:05:23.7377832Z GOAMD64='v1'2026-08-13T01:05:23.7378474Z GOARCH='amd64'2026-08-13T01:05:23.7378910Z GOAUTH='netrc'2026-08-13T01:05:23.7379278Z GOBIN=''2026-08-13T01:05:23.7379652Z GOCACHE='/home/runner/.cache/go-build'2026-08-13T01:05:23.7380181Z GOCACHEPROG=''2026-08-13T01:05:23.7380560Z GODEBUG=''2026-08-13T01:05:23.7381011Z GOENV='/home/runner/.config/go/env'2026-08-13T01:05:23.7381496Z GOEXE=''2026-08-13T01:05:23.7381866Z GOEXPERIMENT=''2026-08-13T01:05:23.7382251Z GOFIPS140='off'2026-08-13T01:05:23.7382660Z GOFLAGS=''2026-08-13T01:05:23.7383942Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build3788964038=/tmp/go-build -gno-record-gcc-switches'2026-08-13T01:05:23.7385264Z GOHOSTARCH='amd64'2026-08-13T01:05:23.7385681Z GOHOSTOS='linux'2026-08-13T01:05:23.7386082Z GOINSECURE=''2026-08-13T01:05:23.7386680Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-13T01:05:23.7387356Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-13T01:05:23.7387779Z GONOPROXY=''2026-08-13T01:05:23.7388090Z GONOSUMDB=''2026-08-13T01:05:23.7388750Z GOOS='linux'2026-08-13T01:05:23.7389093Z GOPATH='/home/runner/go'2026-08-13T01:05:23.7389460Z GOPRIVATE=''2026-08-13T01:05:23.7389863Z GOPROXY='https://proxy.golang.org,direct'2026-08-13T01:05:23.7390383Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-13T01:05:23.7390850Z GOSUMDB='sum.golang.org'2026-08-13T01:05:23.7391246Z GOTELEMETRY='local'2026-08-13T01:05:23.7391669Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-13T01:05:23.7392155Z GOTMPDIR=''2026-08-13T01:05:23.7392903Z GOTOOLCHAIN='local'2026-08-13T01:05:23.7393455Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-13T01:05:23.7394022Z GOVCS=''2026-08-13T01:05:23.7394337Z GOVERSION='go1.26.5'2026-08-13T01:05:23.7394699Z GOWORK=''2026-08-13T01:05:23.7395042Z PKG_CONFIG='pkg-config'2026-08-13T01:05:23.7395277Z 2026-08-13T01:05:23.7395792Z ##[endgroup]2026-08-13T01:05:23.7603530Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-13T01:05:23.7604205Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-13T01:05:23.7691721Z shell: /usr/bin/bash -e {0}2026-08-13T01:05:23.7692251Z env:2026-08-13T01:05:23.7692623Z GOTOOLCHAIN: local2026-08-13T01:05:23.7693043Z OSPOOL_EP_OSPoolEPTag: 25-release2026-08-13T01:05:23.7693536Z OSPOOL_EP_CMTag: 25.0-el92026-08-13T01:05:23.7693985Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-13T01:05:23.7694479Z ##[endgroup]2026-08-13T01:05:26.5416751Z === RUN TestOSPoolEP2026-08-13T01:05:26.5417353Z === PAUSE TestOSPoolEP2026-08-13T01:05:26.5417639Z === CONT TestOSPoolEP2026-08-13T01:05:26.5431609Z TestOSPoolEP 2026-08-13T01:05:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:26.5515810Z TestOSPoolEP 2026-08-13T01:05:26Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll apply -f /tmp/TestOSPoolEP3903563820]2026-08-13T01:05:27.0160543Z TestOSPoolEP 2026-08-13T01:05:27Z logger.go:67: pod/idtoken-generator created2026-08-13T01:05:27.0267719Z TestOSPoolEP 2026-08-13T01:05:27Z logger.go:67: secret/pool-passwd created2026-08-13T01:05:27.0321567Z TestOSPoolEP 2026-08-13T01:05:27Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-13T01:05:27.0323015Z TestOSPoolEP 2026-08-13T01:05:27Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:27.0355344Z TestOSPoolEP 2026-08-13T01:05:27Z 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-13T01:05:37.0355076Z TestOSPoolEP 2026-08-13T01:05:37Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-13T01:05:37.0356241Z TestOSPoolEP 2026-08-13T01:05:37Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:37.0400320Z TestOSPoolEP 2026-08-13T01:05:37Z 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-13T01:05:47.0404036Z TestOSPoolEP 2026-08-13T01:05:47Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-13T01:05:47.0405869Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:47.0429956Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Pod is now available2026-08-13T01:05:47.0432230Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-13T01:05:47.5292045Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: ***2026-08-13T01:05:47.5352302Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll apply -f /tmp/TestOSPoolEP2396323974]2026-08-13T01:05:47.7196318Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: secret/pool-token created2026-08-13T01:05:47.7229813Z TestOSPoolEP 2026-08-13T01:05:47Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-13T01:05:47.7231203Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:47.7250546Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Secret is now available2026-08-13T01:05:47.7271346Z TestOSPoolEP 2026-08-13T01:05:47Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll apply -k /tmp/kustomize-template-250624566]2026-08-13T01:05:48.0115481Z TestOSPoolEP 2026-08-13T01:05:48Z logger.go:67: service/test-cm created2026-08-13T01:05:48.0201098Z TestOSPoolEP 2026-08-13T01:05:48Z 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-13T01:05:48.0212813Z TestOSPoolEP 2026-08-13T01:05:48Z logger.go:67: deployment.apps/ospool-ep created2026-08-13T01:05:48.0300577Z TestOSPoolEP 2026-08-13T01:05:48Z logger.go:67: deployment.apps/test-cm created2026-08-13T01:05:48.0349010Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-13T01:05:48.0382085Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:48.0498544Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-13T01:05:48.0516712Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:48.0519742Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-13T01:05:48.0523286Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:48.0554986Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z 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-54b7d49f85". Sleeping for 10s and will try again.2026-08-13T01:05:48.0565968Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:48Z 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-6c7d796845". Sleeping for 10s and will try again.2026-08-13T01:05:58.0562671Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:58Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-13T01:05:58.0565095Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:58.0571206Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:58Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-13T01:05:58.0573020Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:58Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:05:58.0596593Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05: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: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-13T01:05:58.0598969Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:05:58Z logger.go:67: Deployment is now available2026-08-13T01:06:08.0605491Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:06:08Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-13T01:06:08.0607008Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:06:08Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:08.0636542Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:06: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-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-13T01:06:18.0641429Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:06:18Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-13T01:06:18.0642892Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0673141Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-13T01:06:18Z logger.go:67: Deployment is now available2026-08-13T01:06:18.0684887Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-13T01:06:18.0687769Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-13T01:06:18.0689288Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-13T01:06:18.0690538Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-13T01:06:18.0691369Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-13T01:06:18.0692701Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-13T01:06:18.0693434Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-13T01:06:18.0694733Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0696143Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-13T01:06:18.0697671Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0699506Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-13T01:06:18.0700963Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0707748Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0710958Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0713917Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:06:18Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:06:18.0731544Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:18Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)']2026-08-13T01:06:18.0735284Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-13T01:06:18Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_SINGULARITY]2026-08-13T01:06:18.0739219Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:06:18Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:06:18.3177278Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-13T01:06:18.3181739Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)']' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 10s.2026-08-13T01:06:18.3237500Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-13T01:06:18.3239889Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:06:18.3259512Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-13T01:06:18.3260938Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 10s.2026-08-13T01:06:28.3187606Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)']2026-08-13T01:06:28.3290335Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-13T01:06:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_SINGULARITY]2026-08-13T01:06:28.5756451Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 10s.2026-08-13T01:06:28.5777837Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-13T01:06:28.5780336Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)']' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 10s.2026-08-13T01:06:38.5758105Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-13T01:06:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_SINGULARITY]2026-08-13T01:06:38.5781198Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)']2026-08-13T01:06:38.7435743Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-13T01:06:38Z logger.go:67: true2026-08-13T01:06:38.7443691Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-13T01:06:38.7445384Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: 2026-08-13T01:06:38.7447358Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-rfdbw LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-08-13T01:06:38.7449474Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: 2026-08-13T01:06:38.7451101Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-13T01:06:38.7452679Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: 2026-08-13T01:06:38.7454203Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-13T01:06:38.7460645Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: 2026-08-13T01:06:38.7463054Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-13T01:06:38Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-13T01:06:48.3247547Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:06:48Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:06:48.4852091Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:06:48Z logger.go:67: undefined2026-08-13T01:06:48.4904545Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-13T01:06:48.4905822Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:07:18.4911873Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:07:18Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:07:18.6362061Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:07:18Z logger.go:67: undefined2026-08-13T01:07:18.6418122Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:07:48.6423231Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:07:48Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:07:48.7914814Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:07:48Z logger.go:67: undefined2026-08-13T01:07:48.7985129Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:08:18.7989669Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:08:18Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:08:18.9479287Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:08:18Z logger.go:67: undefined2026-08-13T01:08:18.9537909Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:08:48.9560966Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:08:48Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:08:49.1048737Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:08:49Z logger.go:67: undefined2026-08-13T01:08:49.1107330Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:09:19.1133212Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:09:19Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:09:19.2629141Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:09:19Z logger.go:67: undefined2026-08-13T01:09:19.2686062Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:09:49.2708541Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:09:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:09:49.4190883Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:09:49Z logger.go:67: undefined2026-08-13T01:09:49.4254152Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:10:19.4281382Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:10:19Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:10:19.5882038Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:10:19Z logger.go:67: undefined2026-08-13T01:10:19.5969467Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:10:49.5982536Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:10:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:10:49.7479443Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:10:49Z logger.go:67: undefined2026-08-13T01:10:49.7539197Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:11:19.7565734Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:11:19Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:11:19.9007292Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:11:19Z logger.go:67: undefined2026-08-13T01:11:19.9063976Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5mg58 failed. Retrying in 30s.2026-08-13T01:11:49.9092608Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:11:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll exec test-cm-54b7d49f85-5mg58 -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-rfdbw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-13T01:11:50.0726444Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-13T01:11:50Z logger.go:67: true2026-08-13T01:11:50.0787503Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.0882013Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.3216892Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.4596610Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.5792840Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.5817841Z === NAME TestOSPoolEP2026-08-13T01:11:50.5818643Z ospool_ep_test.go:101: ---2026-08-13T01:11:50.5819086Z Events for pod idtoken-generator:2026-08-13T01:11:50.5820194Z 2026-08-13 01:05:27 +0000 UTC Normal Successfully assigned test-ospool-ep-exrcll/idtoken-generator to minikube2026-08-13T01:11:50.5821132Z 2026-08-13 01:05:30 +0000 UTC Normal Pulling image "htcondor/base"2026-08-13T01:11:50.5822282Z 2026-08-13 01:05:39 +0000 UTC Normal Successfully pulled image "htcondor/base" in 9.46s (9.46s including waiting). Image size: 560910730 bytes.2026-08-13T01:11:50.5823213Z 2026-08-13 01:05:39 +0000 UTC Normal Container created2026-08-13T01:11:50.5823742Z 2026-08-13 01:05:39 +0000 UTC Normal Container started2026-08-13T01:11:50.5824343Z 2026-08-13T01:11:50.5824734Z ---2026-08-13T01:11:50.5825933Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.5842980Z ospool_ep_test.go:101: ---2026-08-13T01:11:50.5843676Z Events for pod ospool-ep-6c7d796845-rfdbw:2026-08-13T01:11:50.5844808Z 2026-08-13 01:05:48 +0000 UTC Normal Successfully assigned test-ospool-ep-exrcll/ospool-ep-6c7d796845-rfdbw to minikube2026-08-13T01:11:50.5845699Z 2026-08-13 01:05:48 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-13T01:11:50.5846868Z 2026-08-13 01:05:50 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.208s (2.208s including waiting). Image size: 5590942 bytes.2026-08-13T01:11:50.5847783Z 2026-08-13 01:05:50 +0000 UTC Normal Container created2026-08-13T01:11:50.5848789Z 2026-08-13 01:05:50 +0000 UTC Normal Container started2026-08-13T01:11:50.5850094Z 2026-08-13 01:05:51 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-08-13T01:11:50.5852506Z 2026-08-13 01:06:12 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 15.999s (21.548s including waiting). Image size: 1273000231 bytes.2026-08-13T01:11:50.5854152Z 2026-08-13 01:06:12 +0000 UTC Normal Container created2026-08-13T01:11:50.5855019Z 2026-08-13 01:06:12 +0000 UTC Normal Container started2026-08-13T01:11:50.5855569Z 2026-08-13T01:11:50.5855923Z ---2026-08-13T01:11:50.5856925Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.5871731Z ospool_ep_test.go:101: ---2026-08-13T01:11:50.5872584Z Events for pod test-cm-54b7d49f85-5mg58:2026-08-13T01:11:50.5874042Z 2026-08-13 01:05:48 +0000 UTC Normal Successfully assigned test-ospool-ep-exrcll/test-cm-54b7d49f85-5mg58 to minikube2026-08-13T01:11:50.5875597Z 2026-08-13 01:05:48 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-08-13T01:11:50.5908479Z 2026-08-13 01:05:56 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 5.922s (8.104s including waiting). Image size: 573055668 bytes.2026-08-13T01:11:50.5909877Z 2026-08-13 01:05:56 +0000 UTC Normal Container created2026-08-13T01:11:50.5910786Z 2026-08-13 01:05:56 +0000 UTC Normal Container started2026-08-13T01:11:50.5911382Z 2026-08-13T01:11:50.5911758Z ---2026-08-13T01:11:50.5912662Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-13T01:11:50.5939019Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll delete -f /tmp/TestOSPoolEP1012738280]2026-08-13T01:11:50.6949712Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-exrcll namespace2026-08-13T01:11:50.7009657Z TestOSPoolEP 2026-08-13T01:11:50Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-exrcll namespace2026-08-13T01:11:52.1984895Z TestOSPoolEP 2026-08-13T01:11:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll delete -f /tmp/TestOSPoolEP3979433208]2026-08-13T01:11:52.3055743Z TestOSPoolEP 2026-08-13T01:11:52Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-exrcll namespace2026-08-13T01:11:52.3104503Z TestOSPoolEP 2026-08-13T01:11:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-exrcll delete -k /tmp/kustomize-template-250624566]2026-08-13T01:11:52.4328235Z TestOSPoolEP 2026-08-13T01:11:52Z logger.go:67: service "test-cm" deleted from test-ospool-ep-exrcll namespace2026-08-13T01:11:52.4401732Z TestOSPoolEP 2026-08-13T01:11:52Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-exrcll namespace2026-08-13T01:11:52.4481584Z TestOSPoolEP 2026-08-13T01:11:52Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-exrcll namespace2026-08-13T01:11:52.4604432Z --- PASS: TestOSPoolEP (53.91s)2026-08-13T01:11:52.4605616Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-08-13T01:11:52.4608101Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.68s)2026-08-13T01:11:52.4610216Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.69s)2026-08-13T01:11:52.4611191Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.01s)2026-08-13T01:11:52.4611780Z PASS2026-08-13T01:11:52.4653443Z ok github.com/osg-htc/k8s-integration-tests/test 385.935s2026-08-13T01:11:52.5911882Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-13T01:11:52.5913313Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-13T01:11:52.5991333Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-13T01:11:52.5991939Z env:2026-08-13T01:11:52.5992292Z GOTOOLCHAIN: local2026-08-13T01:11:52.5992764Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-13T01:11:52.5993267Z ##[endgroup]2026-08-13T01:11:52.6192118Z 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-13T01:11:52.6193496Z ##[group]Run actions/upload-artifact@v42026-08-13T01:11:52.6193778Z with:2026-08-13T01:11:52.6194148Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-08-13T01:11:52.6194610Z path: /tmp/k8s-tests2026-08-13T01:11:52.6194837Z retention-days: 52026-08-13T01:11:52.6195056Z if-no-files-found: warn2026-08-13T01:11:52.6195300Z compression-level: 62026-08-13T01:11:52.6195519Z overwrite: false2026-08-13T01:11:52.6195742Z include-hidden-files: false2026-08-13T01:11:52.6195978Z env:2026-08-13T01:11:52.6196165Z GOTOOLCHAIN: local2026-08-13T01:11:52.6196389Z ##[endgroup]2026-08-13T01:11:52.8632408Z (node:17635) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-13T01:11:52.8633978Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-13T01:11:52.8796471Z With the provided path, there will be 6 files uploaded2026-08-13T01:11:52.8797287Z Artifact name is valid!2026-08-13T01:11:52.8797703Z Root directory input is valid!2026-08-13T01:11:53.2322796Z Beginning upload of artifact content to blob storage2026-08-13T01:11:53.2674526Z (node:17635) [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-13T01:11:53.6307915Z Uploaded bytes 140242026-08-13T01:11:53.7345605Z Finished uploading artifact content to blob storage!2026-08-13T01:11:53.7347081Z SHA256 digest of uploaded artifact zip is ed214c4fec40f3991e3200960a4bde21be121b969cf839dd089f1c3efca8598d2026-08-13T01:11:53.7349387Z Finalizing artifact upload2026-08-13T01:11:53.9577541Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 91647206172026-08-13T01:11:53.9580616Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14024 bytes. Artifact ID is 91647206172026-08-13T01:11:53.9585395Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/31656485328/artifacts/91647206172026-08-13T01:11:53.9764750Z Post job cleanup.2026-08-13T01:11:54.1103200Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-13T01:11:54.1150919Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-13T01:11:54.1186118Z /home/runner/go/pkg/mod2026-08-13T01:11:54.1211059Z /home/runner/.cache/go-build2026-08-13T01:11:54.1219440Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-13T01:11:54.1424644Z Post job cleanup.2026-08-13T01:11:54.2536418Z [command]/usr/bin/git version2026-08-13T01:11:54.2584816Z git version 2.54.02026-08-13T01:11:54.2629727Z Temporarily overriding HOME='/home/runner/work/_temp/a7e97739-7594-4c5e-bb04-ab217d3e9009' before making global git config changes2026-08-13T01:11:54.2631099Z Adding repository directory to the temporary git global config as a safe directory2026-08-13T01:11:54.2635659Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-13T01:11:54.2678838Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-13T01:11:54.2723763Z [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-13T01:11:54.3106181Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-13T01:11:54.3143328Z http.https://github.com/.extraheader2026-08-13T01:11:54.3174274Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-13T01:11:54.3251130Z [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-13T01:11:54.3600965Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-13T01:11:54.3662884Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-13T01:11:54.4164607Z Cleaning up orphan processes2026-08-13T01:11:54.4939466Z ##[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/