2026-07-30T01:23:23.5711627Z Current runner version: '2.336.0'2026-07-30T01:23:23.5732307Z ##[group]Runner Image Provisioner2026-07-30T01:23:23.5733044Z Hosted Compute Agent2026-07-30T01:23:23.5733525Z Version: 20260707.5632026-07-30T01:23:23.5734029Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-07-30T01:23:23.5734682Z Build Date: 2026-07-07T19:33:50Z2026-07-30T01:23:23.5735253Z Worker ID: {2391c376-ac48-44a7-bcfd-f197f6bb9ad5}2026-07-30T01:23:23.5736115Z Azure Region: westus32026-07-30T01:23:23.5736602Z ##[endgroup]2026-07-30T01:23:23.5737760Z ##[group]Operating System2026-07-30T01:23:23.5738321Z Ubuntu2026-07-30T01:23:23.5738763Z 22.04.52026-07-30T01:23:23.5739160Z LTS2026-07-30T01:23:23.5739647Z ##[endgroup]2026-07-30T01:23:23.5740103Z ##[group]Runner Image2026-07-30T01:23:23.5740619Z Image: ubuntu-22.042026-07-30T01:23:23.5741109Z Version: 20260720.234.22026-07-30T01:23:23.5742148Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-07-30T01:23:23.5743373Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-07-30T01:23:23.5744097Z ##[endgroup]2026-07-30T01:23:23.5745129Z ##[group]GITHUB_TOKEN Permissions2026-07-30T01:23:23.5747023Z Contents: read2026-07-30T01:23:23.5747604Z Metadata: read2026-07-30T01:23:23.5748056Z Packages: read2026-07-30T01:23:23.5748801Z ##[endgroup]2026-07-30T01:23:23.5750438Z Secret source: Actions2026-07-30T01:23:23.5751405Z Prepare workflow directory2026-07-30T01:23:23.6974638Z Prepare all required actions2026-07-30T01:23:23.7014117Z Getting action download info2026-07-30T01:23:23.9951134Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-07-30T01:23:24.8865017Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-07-30T01:23:25.6014529Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-07-30T01:23:26.3538038Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-07-30T01:23:27.0527547Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-07-30T01:23:27.0530605Z ##[group] Inputs2026-07-30T01:23:27.0530841Z testName: TestOSPoolEP2026-07-30T01:23:27.0531215Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-30T01:23:27.0531591Z setupScript: 2026-07-30T01:23:27.0531756Z setupEnv: {}2026-07-30T01:23:27.0531915Z ##[endgroup]2026-07-30T01:23:27.0532194Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-07-30T01:23:27.1717051Z ##[group]Run actions/checkout@v52026-07-30T01:23:27.1717474Z with:2026-07-30T01:23:27.1717688Z repository: mwestphall/k8s-integration-tests2026-07-30T01:23:27.1719607Z token: ***2026-07-30T01:23:27.1719793Z ssh-strict: true2026-07-30T01:23:27.1719975Z ssh-user: git2026-07-30T01:23:27.1720148Z persist-credentials: true2026-07-30T01:23:27.1720352Z clean: true2026-07-30T01:23:27.1720524Z sparse-checkout-cone-mode: true2026-07-30T01:23:27.1720720Z fetch-depth: 12026-07-30T01:23:27.1720874Z fetch-tags: false2026-07-30T01:23:27.1721044Z show-progress: true2026-07-30T01:23:27.1721211Z lfs: false2026-07-30T01:23:27.1721588Z submodules: false2026-07-30T01:23:27.1721756Z set-safe-directory: true2026-07-30T01:23:27.1721949Z allow-unsafe-pr-checkout: false2026-07-30T01:23:27.1722280Z ##[endgroup]2026-07-30T01:23:27.2533617Z Syncing repository: mwestphall/k8s-integration-tests2026-07-30T01:23:27.2535308Z ##[group]Getting Git version info2026-07-30T01:23:27.2536184Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-07-30T01:23:27.2549442Z [command]/usr/bin/git version2026-07-30T01:23:27.2968262Z git version 2.54.02026-07-30T01:23:27.2985966Z ##[endgroup]2026-07-30T01:23:27.2999680Z Temporarily overriding HOME='/home/runner/work/_temp/3818c3d3-d841-4bf5-b94b-2253bf7edaf9' before making global git config changes2026-07-30T01:23:27.3000874Z Adding repository directory to the temporary git global config as a safe directory2026-07-30T01:23:27.3016907Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-30T01:23:27.3498785Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-07-30T01:23:27.3502324Z ##[group]Initializing the repository2026-07-30T01:23:27.3507781Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-30T01:23:27.4491590Z hint: Using 'master' as the name for the initial branch. This default branch name2026-07-30T01:23:27.4492078Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-07-30T01:23:27.4492747Z hint: to use in all of your new repositories, which will suppress this warning,2026-07-30T01:23:27.4493242Z hint: call:2026-07-30T01:23:27.4493463Z hint:2026-07-30T01:23:27.4493829Z hint: git config --global init.defaultBranch <name>2026-07-30T01:23:27.4494213Z hint:2026-07-30T01:23:27.4494577Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-07-30T01:23:27.4495204Z hint: 'development'. The just-created branch can be renamed via this command:2026-07-30T01:23:27.4495797Z hint:2026-07-30T01:23:27.4496041Z hint: git branch -m <name>2026-07-30T01:23:27.4496245Z hint:2026-07-30T01:23:27.4496496Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-07-30T01:23:27.4497024Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-07-30T01:23:27.4513685Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-07-30T01:23:27.5010763Z ##[endgroup]2026-07-30T01:23:27.5016469Z ##[group]Disabling automatic garbage collection2026-07-30T01:23:27.5016989Z [command]/usr/bin/git config --local gc.auto 02026-07-30T01:23:27.5073304Z ##[endgroup]2026-07-30T01:23:27.5073985Z ##[group]Setting up auth2026-07-30T01:23:27.5081268Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-07-30T01:23:27.5117872Z [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-07-30T01:23:27.5911019Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-07-30T01:23:27.5940785Z [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-07-30T01:23:27.6144355Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-07-30T01:23:27.6174222Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-07-30T01:23:27.6389693Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-07-30T01:23:27.6895980Z ##[endgroup]2026-07-30T01:23:27.6896665Z ##[group]Fetching the repository2026-07-30T01:23:27.6904946Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-07-30T01:23:28.8959591Z From https://github.com/mwestphall/k8s-integration-tests2026-07-30T01:23:28.8960666Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-07-30T01:23:28.8980635Z ##[endgroup]2026-07-30T01:23:28.8981144Z ##[group]Determining the checkout info2026-07-30T01:23:28.8983292Z ##[endgroup]2026-07-30T01:23:28.8989663Z [command]/usr/bin/git sparse-checkout disable2026-07-30T01:23:29.0906036Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-07-30T01:23:29.1313162Z ##[group]Checking out the ref2026-07-30T01:23:29.1318297Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-07-30T01:23:29.3707199Z Switched to a new branch 'main'2026-07-30T01:23:29.3707731Z branch 'main' set up to track 'origin/main'.2026-07-30T01:23:29.3709509Z ##[endgroup]2026-07-30T01:23:29.3744276Z [command]/usr/bin/git log -1 --format=%H2026-07-30T01:23:29.3768206Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-07-30T01:23:29.4981640Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-07-30T01:23:29.4982262Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-07-30T01:23:29.4982724Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-07-30T01:23:29.6080522Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-30T01:23:29.6081020Z env:2026-07-30T01:23:29.6081555Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-30T01:23:29.6082177Z SETUP_ENV_YAML: {}2026-07-30T01:23:29.6082433Z ##[endgroup]2026-07-30T01:23:29.8316365Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-07-30T01:23:29.8316878Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-07-30T01:23:29.8364282Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-30T01:23:29.8364562Z env:2026-07-30T01:23:29.8364912Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-07-30T01:23:29.8365308Z ##[endgroup]2026-07-30T01:23:29.8555884Z ##[group]Run true2026-07-30T01:23:29.8556126Z [36;1mtrue[0m2026-07-30T01:23:29.8599390Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-30T01:23:29.8599690Z ##[endgroup]2026-07-30T01:23:29.8749179Z ##[group]Run medyagh/setup-minikube@latest2026-07-30T01:23:29.8749424Z with:2026-07-30T01:23:29.8749581Z start: true2026-07-30T01:23:29.8749738Z cache: true2026-07-30T01:23:29.8749902Z minikube-version: latest2026-07-30T01:23:29.8750087Z wait: all2026-07-30T01:23:29.8750240Z ##[endgroup]2026-07-30T01:23:29.9766299Z (node:1893) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-07-30T01:23:29.9766914Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-07-30T01:23:31.0440773Z [command]/usr/bin/chmod +x /home/runner/work/_temp/88d30ccc-2a56-4167-8d29-7227a12dfed42026-07-30T01:23:31.8512587Z [command]/home/runner/bin/minikube version --short2026-07-30T01:23:31.9054799Z v1.38.12026-07-30T01:23:32.1085301Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-07-30T01:23:32.1186839Z (node:1893) [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-07-30T01:23:32.1193424Z Cache hit for: kic-v1.38.1-x642026-07-30T01:23:33.3342558Z Received 41943040 of 544082657 (7.7%), 40.0 MBs/sec2026-07-30T01:23:33.3487607Z Received 37748736 of 224218863 (16.8%), 36.0 MBs/sec2026-07-30T01:23:34.3377070Z Received 167772160 of 544082657 (30.8%), 80.0 MBs/sec2026-07-30T01:23:34.3491218Z Received 171966464 of 224218863 (76.7%), 82.0 MBs/sec2026-07-30T01:23:34.7083245Z Received 224218863 of 224218863 (100.0%), 90.6 MBs/sec2026-07-30T01:23:34.7083829Z Cache Size: ~214 MB (224218863 B)2026-07-30T01:23:34.7337670Z [command]/usr/bin/tar -xf /home/runner/work/_temp/88c6d6fe-5012-4561-8c66-fe86d68b95ef/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-30T01:23:35.1653147Z Cache restored successfully2026-07-30T01:23:35.3386698Z Received 310378496 of 544082657 (57.0%), 98.6 MBs/sec2026-07-30T01:23:36.3365065Z Received 440401920 of 544082657 (80.9%), 104.9 MBs/sec2026-07-30T01:23:37.3187561Z Received 544082657 of 544082657 (100.0%), 104.1 MBs/sec2026-07-30T01:23:37.3189566Z Cache Size: ~519 MB (544082657 B)2026-07-30T01:23:37.3283912Z [command]/usr/bin/tar -xf /home/runner/work/_temp/6dff98e0-abe8-43ce-a2e0-c9b0d3186347/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-30T01:23:37.9623029Z Cache restored successfully2026-07-30T01:23:38.1096203Z [command]/home/runner/bin/minikube start --wait all2026-07-30T01:23:38.1911897Z * minikube v1.38.1 on Ubuntu 22.042026-07-30T01:23:38.5234410Z ! 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-07-30T01:23:38.5244464Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-07-30T01:23:38.5601286Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-07-30T01:23:38.5610673Z * Using Docker driver with root privileges2026-07-30T01:23:38.5623645Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-07-30T01:23:38.5631236Z * Pulling base image v0.0.50 ...2026-07-30T01:24:12.0464506Z * Configuring bridge CNI (Container Networking Interface) ...2026-07-30T01:24:16.6881695Z * Verifying Kubernetes components...2026-07-30T01:24:16.7016535Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-07-30T01:24:17.1716918Z * Enabled addons: storage-provisioner, default-storageclass2026-07-30T01:24:52.9700994Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-07-30T01:24:52.9791089Z [command]/home/runner/bin/minikube version --short2026-07-30T01:24:53.0089634Z v1.38.12026-07-30T01:24:53.0322945Z ##[group]Run actions/setup-go@v62026-07-30T01:24:53.0323179Z with:2026-07-30T01:24:53.0323338Z go-version: 1.262026-07-30T01:24:53.0323509Z cache: true2026-07-30T01:24:53.0323696Z cache-dependency-path: go.sum2026-07-30T01:24:53.0323907Z check-latest: false2026-07-30T01:24:53.0325895Z token: ***2026-07-30T01:24:53.0326065Z ##[endgroup]2026-07-30T01:24:53.1316350Z Setup go version spec 1.262026-07-30T01:24:53.1359851Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-07-30T01:24:53.1361439Z Added go to the path2026-07-30T01:24:53.1363826Z Successfully set up Go version 1.262026-07-30T01:24:53.3457509Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-07-30T01:24:53.3484244Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-07-30T01:24:53.3507257Z /home/runner/go/pkg/mod2026-07-30T01:24:53.3524115Z /home/runner/.cache/go-build2026-07-30T01:24:53.5631749Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-07-30T01:24:54.7726088Z Received 54525952 of 251839808 (21.7%), 51.9 MBs/sec2026-07-30T01:24:55.7723916Z Received 184549376 of 251839808 (73.3%), 88.0 MBs/sec2026-07-30T01:24:56.1276372Z Received 251839808 of 251839808 (100.0%), 101.9 MBs/sec2026-07-30T01:24:56.1277459Z Cache Size: ~240 MB (251839808 B)2026-07-30T01:24:56.1330376Z [command]/usr/bin/tar -xf /home/runner/work/_temp/1e64a291-21b4-46ea-90bd-7c551f42bf17/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-30T01:24:58.4073052Z Cache restored successfully2026-07-30T01:24:58.4515703Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-07-30T01:24:58.4538963Z go version go1.26.5 linux/amd642026-07-30T01:24:58.4539130Z 2026-07-30T01:24:58.4539395Z ##[group]go env2026-07-30T01:24:58.4654505Z AR='ar'2026-07-30T01:24:58.4654760Z CC='gcc'2026-07-30T01:24:58.4655163Z CGO_CFLAGS='-O2 -g'2026-07-30T01:24:58.4655854Z CGO_CPPFLAGS=''2026-07-30T01:24:58.4656155Z CGO_CXXFLAGS='-O2 -g'2026-07-30T01:24:58.4656458Z CGO_ENABLED='1'2026-07-30T01:24:58.4656723Z CGO_FFLAGS='-O2 -g'2026-07-30T01:24:58.4656986Z CGO_LDFLAGS='-O2 -g'2026-07-30T01:24:58.4657880Z CXX='g++'2026-07-30T01:24:58.4658138Z GCCGO='gccgo'2026-07-30T01:24:58.4658394Z GO111MODULE=''2026-07-30T01:24:58.4658648Z GOAMD64='v1'2026-07-30T01:24:58.4658902Z GOARCH='amd64'2026-07-30T01:24:58.4678362Z GOAUTH='netrc'2026-07-30T01:24:58.4678817Z GOBIN=''2026-07-30T01:24:58.4679119Z GOCACHE='/home/runner/.cache/go-build'2026-07-30T01:24:58.4679513Z GOCACHEPROG=''2026-07-30T01:24:58.4679780Z GODEBUG=''2026-07-30T01:24:58.4680058Z GOENV='/home/runner/.config/go/env'2026-07-30T01:24:58.4680414Z GOEXE=''2026-07-30T01:24:58.4680652Z GOEXPERIMENT=''2026-07-30T01:24:58.4680878Z GOFIPS140='off'2026-07-30T01:24:58.4681049Z GOFLAGS=''2026-07-30T01:24:58.4681625Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build8203858=/tmp/go-build -gno-record-gcc-switches'2026-07-30T01:24:58.4682145Z GOHOSTARCH='amd64'2026-07-30T01:24:58.4682361Z GOHOSTOS='linux'2026-07-30T01:24:58.4682536Z GOINSECURE=''2026-07-30T01:24:58.4682818Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-07-30T01:24:58.4683160Z GOMODCACHE='/home/runner/go/pkg/mod'2026-07-30T01:24:58.4683467Z GONOPROXY=''2026-07-30T01:24:58.4683658Z GONOSUMDB=''2026-07-30T01:24:58.4683822Z GOOS='linux'2026-07-30T01:24:58.4684022Z GOPATH='/home/runner/go'2026-07-30T01:24:58.4684217Z GOPRIVATE=''2026-07-30T01:24:58.4684443Z GOPROXY='https://proxy.golang.org,direct'2026-07-30T01:24:58.4684730Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-07-30T01:24:58.4684981Z GOSUMDB='sum.golang.org'2026-07-30T01:24:58.4685185Z GOTELEMETRY='local'2026-07-30T01:24:58.4685411Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-07-30T01:24:58.4685833Z GOTMPDIR=''2026-07-30T01:24:58.4686209Z GOTOOLCHAIN='local'2026-07-30T01:24:58.4686489Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-07-30T01:24:58.4686773Z GOVCS=''2026-07-30T01:24:58.4686936Z GOVERSION='go1.26.5'2026-07-30T01:24:58.4687141Z GOWORK=''2026-07-30T01:24:58.4687314Z PKG_CONFIG='pkg-config'2026-07-30T01:24:58.4687434Z 2026-07-30T01:24:58.4687673Z ##[endgroup]2026-07-30T01:24:58.4822544Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-07-30T01:24:58.4822976Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-07-30T01:24:58.4872063Z shell: /usr/bin/bash -e {0}2026-07-30T01:24:58.4872280Z env:2026-07-30T01:24:58.4872453Z GOTOOLCHAIN: local2026-07-30T01:24:58.4872668Z OSPOOL_EP_OSPoolEPTag: 25-release2026-07-30T01:24:58.4872892Z OSPOOL_EP_CMTag: 25.0-el92026-07-30T01:24:58.4873097Z OSPOOL_EP_CvmfsType: cvmfsexec2026-07-30T01:24:58.4873308Z ##[endgroup]2026-07-30T01:25:03.6501844Z === RUN TestOSPoolEP2026-07-30T01:25:03.6502281Z === PAUSE TestOSPoolEP2026-07-30T01:25:03.6502585Z === CONT TestOSPoolEP2026-07-30T01:25:03.6507217Z TestOSPoolEP 2026-07-30T01:25:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:03.6582334Z TestOSPoolEP 2026-07-30T01:25:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy apply -f /tmp/TestOSPoolEP3941565667]2026-07-30T01:25:03.9080858Z TestOSPoolEP 2026-07-30T01:25:03Z logger.go:67: pod/idtoken-generator created2026-07-30T01:25:03.9149290Z TestOSPoolEP 2026-07-30T01:25:03Z logger.go:67: secret/pool-passwd created2026-07-30T01:25:03.9191687Z TestOSPoolEP 2026-07-30T01:25:03Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-30T01:25:03.9192572Z TestOSPoolEP 2026-07-30T01:25:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:03.9211460Z TestOSPoolEP 2026-07-30T01:25:03Z 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-07-30T01:25:13.9232202Z TestOSPoolEP 2026-07-30T01:25:13Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-30T01:25:13.9236923Z TestOSPoolEP 2026-07-30T01:25:13Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:13.9249579Z TestOSPoolEP 2026-07-30T01:25:13Z 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-07-30T01:25:23.9254520Z TestOSPoolEP 2026-07-30T01:25:23Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-30T01:25:23.9255284Z TestOSPoolEP 2026-07-30T01:25:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:23.9275941Z TestOSPoolEP 2026-07-30T01:25:23Z logger.go:67: Pod is now available2026-07-30T01:25:23.9277313Z TestOSPoolEP 2026-07-30T01:25:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-07-30T01:25:24.0389997Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: ***2026-07-30T01:25:24.0434604Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy apply -f /tmp/TestOSPoolEP1594961198]2026-07-30T01:25:24.2769792Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: secret/pool-token created2026-07-30T01:25:24.2794434Z TestOSPoolEP 2026-07-30T01:25:24Z retry.go:91: Wait for secret pool-token to be provisioned.2026-07-30T01:25:24.2795872Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:24.2812462Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: Secret is now available2026-07-30T01:25:24.2831912Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy apply -k /tmp/kustomize-template-739230332]2026-07-30T01:25:24.4648281Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: service/test-cm created2026-07-30T01:25:24.4728445Z TestOSPoolEP 2026-07-30T01:25:24Z 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-07-30T01:25:24.4729896Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: deployment.apps/ospool-ep created2026-07-30T01:25:24.4783455Z TestOSPoolEP 2026-07-30T01:25:24Z logger.go:67: deployment.apps/test-cm created2026-07-30T01:25:24.4815825Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-07-30T01:25:24.4819008Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:24.4889861Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-30T01:25:24.4916620Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:24.4918969Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-30T01:25:24.4922267Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:24.4941539Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z 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-07-30T01:25:24.4956496Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:24Z 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-07-30T01:25:34.4948940Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:34Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-30T01:25:34.4949995Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:34Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:34.4958683Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:34Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-30T01:25:34.4960111Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:34Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:34.4971029Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:34Z 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: ReplicaSetUpdated, message: ReplicaSet "test-cm-54b7d49f85" is progressing.. Sleeping for 10s and will try again.2026-07-30T01:25:34.4974057Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:34Z 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-07-30T01:25:44.4981441Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:44Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-30T01:25:44.4982883Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:44Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:44.4997023Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:44Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-30T01:25:44.4998418Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:44Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:44.5022948Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:44Z 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-07-30T01:25:44.5028615Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:44Z logger.go:67: Deployment is now available2026-07-30T01:25:54.5027988Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:54Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-30T01:25:54.5029397Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:25:54.5049465Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:25:54Z 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-07-30T01:26:04.5058459Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:26:04Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-30T01:26:04.5059385Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5084593Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-30T01:26:04Z logger.go:67: Deployment is now available2026-07-30T01:26:04.5085877Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-30T01:26:04.5090850Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-30T01:26:04.5091464Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-30T01:26:04.5092082Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-30T01:26:04.5092645Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-30T01:26:04.5093210Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-30T01:26:04.5093724Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-30T01:26:04.5094750Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5095964Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-30T01:26:04.5097025Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5098097Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-30T01:26:04.5099156Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5105233Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5120055Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5122096Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-30T01:26:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:26:04.5129465Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)']2026-07-30T01:26:04.5132207Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:26:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:26:04.5135132Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-30T01:26:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_SINGULARITY]2026-07-30T01:26:04.6891326Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 10s.2026-07-30T01:26:04.6928955Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-30T01:26:04.6930945Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:26:04.6954160Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-30T01:26:04.6955171Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)']' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 10s.2026-07-30T01:26:14.6885242Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-30T01:26:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_SINGULARITY]2026-07-30T01:26:14.6957728Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)']2026-07-30T01:26:14.8477087Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-30T01:26:14.8478636Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 10s.2026-07-30T01:26:14.8509720Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-30T01:26:14.8510804Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)']' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 10s.2026-07-30T01:26:24.8477554Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-30T01:26:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_SINGULARITY]2026-07-30T01:26:24.8519461Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)']2026-07-30T01:26:24.9825878Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-07-30T01:26:24.9827712Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-30T01:26:24Z logger.go:67: true2026-07-30T01:26:24.9829130Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: 2026-07-30T01:26:24.9830699Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-97ffw LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-07-30T01:26:24.9831966Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: 2026-07-30T01:26:24.9833074Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-07-30T01:26:24.9834139Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: 2026-07-30T01:26:24.9835138Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-07-30T01:26:24.9836243Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: 2026-07-30T01:26:24.9837171Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-30T01:26:24Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-07-30T01:26:34.6940207Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:26:34Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:26:34.8071101Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:26:34Z logger.go:67: undefined2026-07-30T01:26:34.8113524Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-30T01:26:34.8114595Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:27:04.8125067Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:27:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:27:04.9309068Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:27:04Z logger.go:67: undefined2026-07-30T01:27:04.9351926Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:27:34.9353910Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:27:34Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:27:35.0486139Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:27:35Z logger.go:67: undefined2026-07-30T01:27:35.0527927Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:28:05.0532945Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:28:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:28:05.1658768Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:28:05Z logger.go:67: undefined2026-07-30T01:28:05.1707308Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:28:35.1716418Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:28:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:28:35.2866493Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:28:35Z logger.go:67: undefined2026-07-30T01:28:35.2911917Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:29:05.2914792Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:29:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:29:05.4083099Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:29:05Z logger.go:67: undefined2026-07-30T01:29:05.4130971Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:29:35.4145011Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:29:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:29:35.5290849Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:29:35Z logger.go:67: undefined2026-07-30T01:29:35.5331910Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:30:05.5335084Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:30:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:30:05.6676242Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:30:05Z logger.go:67: undefined2026-07-30T01:30:05.6718309Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:30:35.6724014Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:30:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:30:35.7908140Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:30:35Z logger.go:67: undefined2026-07-30T01:30:35.7948638Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:31:05.7965443Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:31:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:31:05.9456331Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:31:05Z logger.go:67: undefined2026-07-30T01:31:05.9496694Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-5qx4f failed. Retrying in 30s.2026-07-30T01:31:35.9523968Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:31:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy exec test-cm-54b7d49f85-5qx4f -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-97ffw",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-30T01:31:36.1214957Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-30T01:31:36Z logger.go:67: true2026-07-30T01:31:36.1271132Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.1349127Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.2391190Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.3389625Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.4300471Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.4320140Z === NAME TestOSPoolEP2026-07-30T01:31:36.4320575Z ospool_ep_test.go:101: ---2026-07-30T01:31:36.4321038Z Events for pod idtoken-generator:2026-07-30T01:31:36.4323469Z 2026-07-30 01:25:03 +0000 UTC Normal Successfully assigned test-ospool-ep-cty0jy/idtoken-generator to minikube2026-07-30T01:31:36.4324466Z 2026-07-30 01:25:11 +0000 UTC Normal Pulling image "htcondor/base"2026-07-30T01:31:36.4325268Z 2026-07-30 01:25:21 +0000 UTC Normal Successfully pulled image "htcondor/base" in 10.003s (10.003s including waiting). Image size: 560910730 bytes.2026-07-30T01:31:36.4326094Z 2026-07-30 01:25:21 +0000 UTC Normal Container created2026-07-30T01:31:36.4326474Z 2026-07-30 01:25:21 +0000 UTC Normal Container started2026-07-30T01:31:36.4326727Z 2026-07-30T01:31:36.4326903Z ---2026-07-30T01:31:36.4327343Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.4339175Z ospool_ep_test.go:101: ---2026-07-30T01:31:36.4339696Z Events for pod ospool-ep-6c7d796845-97ffw:2026-07-30T01:31:36.4340749Z 2026-07-30 01:25:24 +0000 UTC Normal Successfully assigned test-ospool-ep-cty0jy/ospool-ep-6c7d796845-97ffw to minikube2026-07-30T01:31:36.4341882Z 2026-07-30 01:25:24 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-07-30T01:31:36.4343408Z 2026-07-30 01:25:27 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.271s (2.271s including waiting). Image size: 5590942 bytes.2026-07-30T01:31:36.4344437Z 2026-07-30 01:25:27 +0000 UTC Normal Container created2026-07-30T01:31:36.4344889Z 2026-07-30 01:25:27 +0000 UTC Normal Container started2026-07-30T01:31:36.4345468Z 2026-07-30 01:25:27 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-07-30T01:31:36.4346717Z 2026-07-30 01:25:54 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 20.797s (27.258s including waiting). Image size: 1383210994 bytes.2026-07-30T01:31:36.4347811Z 2026-07-30 01:25:54 +0000 UTC Normal Container created2026-07-30T01:31:36.4348325Z 2026-07-30 01:25:54 +0000 UTC Normal Container started2026-07-30T01:31:36.4348714Z 2026-07-30T01:31:36.4349012Z ---2026-07-30T01:31:36.4349989Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.4357348Z ospool_ep_test.go:101: ---2026-07-30T01:31:36.4357880Z Events for pod test-cm-54b7d49f85-5qx4f:2026-07-30T01:31:36.4358828Z 2026-07-30 01:25:24 +0000 UTC Normal Successfully assigned test-ospool-ep-cty0jy/test-cm-54b7d49f85-5qx4f to minikube2026-07-30T01:31:36.4359517Z 2026-07-30 01:25:24 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-07-30T01:31:36.4360441Z 2026-07-30 01:25:34 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 6.811s (9.076s including waiting). Image size: 573055668 bytes.2026-07-30T01:31:36.4361323Z 2026-07-30 01:25:34 +0000 UTC Normal Container created2026-07-30T01:31:36.4361856Z 2026-07-30 01:25:34 +0000 UTC Normal Container started2026-07-30T01:31:36.4362267Z 2026-07-30T01:31:36.4362586Z ---2026-07-30T01:31:36.4363173Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-30T01:31:36.4390568Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy delete -f /tmp/TestOSPoolEP1669865073]2026-07-30T01:31:36.5100297Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-cty0jy namespace2026-07-30T01:31:36.5141946Z TestOSPoolEP 2026-07-30T01:31:36Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-cty0jy namespace2026-07-30T01:31:37.5862865Z TestOSPoolEP 2026-07-30T01:31:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy delete -f /tmp/TestOSPoolEP585120896]2026-07-30T01:31:37.6532218Z TestOSPoolEP 2026-07-30T01:31:37Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-cty0jy namespace2026-07-30T01:31:37.6566158Z TestOSPoolEP 2026-07-30T01:31:37Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cty0jy delete -k /tmp/kustomize-template-739230332]2026-07-30T01:31:37.7426811Z TestOSPoolEP 2026-07-30T01:31:37Z logger.go:67: service "test-cm" deleted from test-ospool-ep-cty0jy namespace2026-07-30T01:31:37.7454613Z TestOSPoolEP 2026-07-30T01:31:37Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-cty0jy namespace2026-07-30T01:31:37.7482260Z TestOSPoolEP 2026-07-30T01:31:37Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-cty0jy namespace2026-07-30T01:31:37.7555760Z --- PASS: TestOSPoolEP (62.49s)2026-07-30T01:31:37.7569376Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (40.03s)2026-07-30T01:31:37.7570905Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.48s)2026-07-30T01:31:37.7573891Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.48s)2026-07-30T01:31:37.7574806Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (331.62s)2026-07-30T01:31:37.7576366Z PASS2026-07-30T01:31:37.7588029Z ok github.com/osg-htc/k8s-integration-tests/test 394.117s2026-07-30T01:31:37.8564666Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-07-30T01:31:37.8565181Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-07-30T01:31:37.8614684Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-30T01:31:37.8614973Z env:2026-07-30T01:31:37.8615139Z GOTOOLCHAIN: local2026-07-30T01:31:37.8615616Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-30T01:31:37.8616005Z ##[endgroup]2026-07-30T01:31:37.8837681Z 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-07-30T01:31:37.8839422Z ##[group]Run actions/upload-artifact@v42026-07-30T01:31:37.8839977Z with:2026-07-30T01:31:37.8851591Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-07-30T01:31:37.8852135Z path: /tmp/k8s-tests2026-07-30T01:31:37.8852385Z retention-days: 52026-07-30T01:31:37.8852639Z if-no-files-found: warn2026-07-30T01:31:37.8852910Z compression-level: 62026-07-30T01:31:37.8853162Z overwrite: false2026-07-30T01:31:37.8853407Z include-hidden-files: false2026-07-30T01:31:37.8853683Z env:2026-07-30T01:31:37.8853844Z GOTOOLCHAIN: local2026-07-30T01:31:37.8854019Z ##[endgroup]2026-07-30T01:31:38.0432770Z (node:18096) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-07-30T01:31:38.0433717Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-07-30T01:31:38.0494918Z With the provided path, there will be 6 files uploaded2026-07-30T01:31:38.0496202Z Artifact name is valid!2026-07-30T01:31:38.0496707Z Root directory input is valid!2026-07-30T01:31:38.3468564Z Beginning upload of artifact content to blob storage2026-07-30T01:31:38.3683832Z (node:18096) [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-07-30T01:31:38.6702209Z Uploaded bytes 142152026-07-30T01:31:38.7394484Z Finished uploading artifact content to blob storage!2026-07-30T01:31:38.7395367Z SHA256 digest of uploaded artifact zip is f3b6537de6b6ddf93101505f04d7635ae89ab0d370df1d7fa7b29db1e09c57c22026-07-30T01:31:38.7396657Z Finalizing artifact upload2026-07-30T01:31:38.9566881Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 87452723302026-07-30T01:31:38.9568257Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14215 bytes. Artifact ID is 87452723302026-07-30T01:31:38.9572449Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30505493403/artifacts/87452723302026-07-30T01:31:38.9723063Z Post job cleanup.2026-07-30T01:31:39.0711738Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-07-30T01:31:39.0745924Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-07-30T01:31:39.0781224Z /home/runner/go/pkg/mod2026-07-30T01:31:39.0796539Z /home/runner/.cache/go-build2026-07-30T01:31:39.0803722Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-07-30T01:31:39.0919289Z Post job cleanup.2026-07-30T01:31:39.1714786Z [command]/usr/bin/git version2026-07-30T01:31:39.1781855Z git version 2.54.02026-07-30T01:31:39.1796563Z Temporarily overriding HOME='/home/runner/work/_temp/e5f87e7a-4683-4ac4-bce1-dfa53cb09dc5' before making global git config changes2026-07-30T01:31:39.1797762Z Adding repository directory to the temporary git global config as a safe directory2026-07-30T01:31:39.1801994Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-30T01:31:39.1837945Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-07-30T01:31:39.1875640Z [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-07-30T01:31:39.2109885Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-07-30T01:31:39.2160961Z http.https://github.com/.extraheader2026-07-30T01:31:39.2200642Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-07-30T01:31:39.2210068Z [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-07-30T01:31:39.2487657Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-07-30T01:31:39.2525066Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-07-30T01:31:39.2896757Z Cleaning up orphan processes2026-07-30T01:31:39.3549449Z ##[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/