2026-08-05T01:39:46.8824301Z Current runner version: '2.336.0'2026-08-05T01:39:46.8854521Z ##[group]Runner Image Provisioner2026-08-05T01:39:46.8856056Z Hosted Compute Agent2026-08-05T01:39:46.8856960Z Version: 20260707.5632026-08-05T01:39:46.8857851Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-05T01:39:46.8859099Z Build Date: 2026-07-07T19:33:50Z2026-08-05T01:39:46.8860135Z Worker ID: {99793b23-91ba-4881-abae-bcf1205de149}2026-08-05T01:39:46.8861320Z Azure Region: eastus22026-08-05T01:39:46.8862245Z ##[endgroup]2026-08-05T01:39:46.8864163Z ##[group]Operating System2026-08-05T01:39:46.8865158Z Ubuntu2026-08-05T01:39:46.8866450Z 22.04.52026-08-05T01:39:46.8867275Z LTS2026-08-05T01:39:46.8868184Z ##[endgroup]2026-08-05T01:39:46.8869032Z ##[group]Runner Image2026-08-05T01:39:46.8870013Z Image: ubuntu-22.042026-08-05T01:39:46.8870888Z Version: 20260720.234.22026-08-05T01:39:46.8873022Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-05T01:39:46.8875529Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-05T01:39:46.8877380Z ##[endgroup]2026-08-05T01:39:46.8879261Z ##[group]GITHUB_TOKEN Permissions2026-08-05T01:39:46.8882128Z Contents: read2026-08-05T01:39:46.8883017Z Metadata: read2026-08-05T01:39:46.8883869Z Packages: read2026-08-05T01:39:46.8885315Z ##[endgroup]2026-08-05T01:39:46.8888851Z Secret source: Actions2026-08-05T01:39:46.8890558Z Prepare workflow directory2026-08-05T01:39:46.9355375Z Prepare all required actions2026-08-05T01:39:46.9423742Z Getting action download info2026-08-05T01:39:47.1417535Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-05T01:39:47.3727368Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-05T01:39:47.5293428Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-05T01:39:47.7043460Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-05T01:39:47.9646418Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-05T01:39:47.9652767Z ##[group] Inputs2026-08-05T01:39:47.9653436Z testName: TestOSPoolEP2026-08-05T01:39:47.9654795Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-05T01:39:47.9656653Z setupScript: 2026-08-05T01:39:47.9657198Z setupEnv: {}2026-08-05T01:39:47.9657732Z ##[endgroup]2026-08-05T01:39:47.9659007Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-08-05T01:39:48.0453499Z ##[group]Run actions/checkout@v52026-08-05T01:39:48.0454494Z with:2026-08-05T01:39:48.0455081Z repository: mwestphall/k8s-integration-tests2026-08-05T01:39:48.0463556Z token: ***2026-08-05T01:39:48.0464065Z ssh-strict: true2026-08-05T01:39:48.0464628Z ssh-user: git2026-08-05T01:39:48.0465177Z persist-credentials: true2026-08-05T01:39:48.0465916Z clean: true2026-08-05T01:39:48.0466456Z sparse-checkout-cone-mode: true2026-08-05T01:39:48.0467140Z fetch-depth: 12026-08-05T01:39:48.0467644Z fetch-tags: false2026-08-05T01:39:48.0468186Z show-progress: true2026-08-05T01:39:48.0468726Z lfs: false2026-08-05T01:39:48.0469202Z submodules: false2026-08-05T01:39:48.0469747Z set-safe-directory: true2026-08-05T01:39:48.0470389Z allow-unsafe-pr-checkout: false2026-08-05T01:39:48.0471361Z ##[endgroup]2026-08-05T01:39:48.1574598Z Syncing repository: mwestphall/k8s-integration-tests2026-08-05T01:39:48.1577694Z ##[group]Getting Git version info2026-08-05T01:39:48.1579354Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-05T01:39:48.1581344Z [command]/usr/bin/git version2026-08-05T01:39:48.1632859Z git version 2.54.02026-08-05T01:39:48.1656566Z ##[endgroup]2026-08-05T01:39:48.1673364Z Temporarily overriding HOME='/home/runner/work/_temp/1d741621-b29f-4077-b7ec-4c1f39d93d06' before making global git config changes2026-08-05T01:39:48.1678667Z Adding repository directory to the temporary git global config as a safe directory2026-08-05T01:39:48.1681272Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-05T01:39:48.1723669Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-05T01:39:48.1727400Z ##[group]Initializing the repository2026-08-05T01:39:48.1734040Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-05T01:39:48.1853155Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-05T01:39:48.1855526Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-05T01:39:48.1858015Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-05T01:39:48.1860070Z hint: call:2026-08-05T01:39:48.1861331Z hint:2026-08-05T01:39:48.1862408Z hint: git config --global init.defaultBranch <name>2026-08-05T01:39:48.1863707Z hint:2026-08-05T01:39:48.1864874Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-05T01:39:48.1867321Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-05T01:39:48.1869137Z hint:2026-08-05T01:39:48.1869937Z hint: git branch -m <name>2026-08-05T01:39:48.1870893Z hint:2026-08-05T01:39:48.1872293Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-05T01:39:48.1875436Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-05T01:39:48.1880466Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-05T01:39:48.1895054Z ##[endgroup]2026-08-05T01:39:48.1896531Z ##[group]Disabling automatic garbage collection2026-08-05T01:39:48.1899281Z [command]/usr/bin/git config --local gc.auto 02026-08-05T01:39:48.1937079Z ##[endgroup]2026-08-05T01:39:48.1938679Z ##[group]Setting up auth2026-08-05T01:39:48.1944660Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-05T01:39:48.1984827Z [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-05T01:39:48.2368805Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-05T01:39:48.2414665Z [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-05T01:39:48.2711712Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-05T01:39:48.2751340Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-05T01:39:48.3017381Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-05T01:39:48.3060970Z ##[endgroup]2026-08-05T01:39:48.3061922Z ##[group]Fetching the repository2026-08-05T01:39:48.3072064Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-05T01:39:48.4951403Z From https://github.com/mwestphall/k8s-integration-tests2026-08-05T01:39:48.4953000Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-05T01:39:48.4978424Z ##[endgroup]2026-08-05T01:39:48.4979368Z ##[group]Determining the checkout info2026-08-05T01:39:48.4980959Z ##[endgroup]2026-08-05T01:39:48.4986135Z [command]/usr/bin/git sparse-checkout disable2026-08-05T01:39:48.5034440Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-05T01:39:48.5068948Z ##[group]Checking out the ref2026-08-05T01:39:48.5072573Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-05T01:39:48.5168032Z Switched to a new branch 'main'2026-08-05T01:39:48.5171148Z branch 'main' set up to track 'origin/main'.2026-08-05T01:39:48.5178120Z ##[endgroup]2026-08-05T01:39:48.5230450Z [command]/usr/bin/git log -1 --format=%H2026-08-05T01:39:48.5259815Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-05T01:39:48.5513863Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-05T01:39:48.5515847Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-05T01:39:48.5517511Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-05T01:39:48.5632291Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-05T01:39:48.5633252Z env:2026-08-05T01:39:48.5634385Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-05T01:39:48.5636025Z SETUP_ENV_YAML: {}2026-08-05T01:39:48.5636557Z ##[endgroup]2026-08-05T01:39:49.1415931Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-05T01:39:49.1417571Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-05T01:39:49.1483665Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-05T01:39:49.1484968Z env:2026-08-05T01:39:49.1486581Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-05T01:39:49.1488179Z ##[endgroup]2026-08-05T01:39:49.1671290Z ##[group]Run true2026-08-05T01:39:49.1672148Z [36;1mtrue[0m2026-08-05T01:39:49.1734724Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-05T01:39:49.1736000Z ##[endgroup]2026-08-05T01:39:49.1959022Z ##[group]Run medyagh/setup-minikube@latest2026-08-05T01:39:49.1959937Z with:2026-08-05T01:39:49.1960690Z start: true2026-08-05T01:39:49.1961453Z cache: true2026-08-05T01:39:49.1962541Z minikube-version: latest2026-08-05T01:39:49.1963478Z wait: all2026-08-05T01:39:49.1964275Z ##[endgroup]2026-08-05T01:39:49.3256763Z (node:2103) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-05T01:39:49.3258423Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-05T01:39:50.2453311Z [command]/usr/bin/chmod +x /home/runner/work/_temp/73106dae-cd0c-4fc1-b3c9-f09fd77c29f02026-08-05T01:39:50.4087138Z [command]/home/runner/bin/minikube version --short2026-08-05T01:39:50.4498485Z v1.38.12026-08-05T01:39:50.5230263Z Cache hit for: kic-v1.38.1-x642026-08-05T01:39:50.5345343Z (node:2103) [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-05T01:39:50.5354709Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-05T01:39:51.5920550Z Received 134217728 of 544082657 (24.7%), 126.4 MBs/sec2026-08-05T01:39:51.6239986Z Received 117440512 of 224218863 (52.4%), 111.8 MBs/sec2026-08-05T01:39:52.4261312Z Received 224218863 of 224218863 (100.0%), 118.5 MBs/sec2026-08-05T01:39:52.4263253Z Cache Size: ~214 MB (224218863 B)2026-08-05T01:39:52.5047495Z [command]/usr/bin/tar -xf /home/runner/work/_temp/46cb08fa-bd45-47e9-86e9-1fc93e895e32/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-05T01:39:52.5984288Z Received 310378496 of 544082657 (57.0%), 146.5 MBs/sec2026-08-05T01:39:53.2408032Z Cache restored successfully2026-08-05T01:39:53.5983230Z Received 536870912 of 544082657 (98.7%), 169.5 MBs/sec2026-08-05T01:39:53.6444175Z Received 544082657 of 544082657 (100.0%), 169.2 MBs/sec2026-08-05T01:39:53.6445239Z Cache Size: ~519 MB (544082657 B)2026-08-05T01:39:53.6608551Z [command]/usr/bin/tar -xf /home/runner/work/_temp/7ddfda41-d3ac-4653-b843-28d7390df9bd/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-05T01:39:54.6392351Z Cache restored successfully2026-08-05T01:39:54.7652554Z [command]/home/runner/bin/minikube start --wait all2026-08-05T01:39:54.9084826Z * minikube v1.38.1 on Ubuntu 22.042026-08-05T01:39:55.3769740Z ! 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-05T01:39:55.3774968Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-05T01:39:55.4278164Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-05T01:39:55.4287512Z * Using Docker driver with root privileges2026-08-05T01:39:55.4298887Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-05T01:39:55.4306966Z * Pulling base image v0.0.50 ...2026-08-05T01:40:26.7738894Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-05T01:40:31.9574226Z * Verifying Kubernetes components...2026-08-05T01:40:31.9807696Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-05T01:40:32.6517384Z * Enabled addons: storage-provisioner, default-storageclass2026-08-05T01:41:11.6561424Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-05T01:41:11.6693916Z [command]/home/runner/bin/minikube version --short2026-08-05T01:41:11.7102525Z v1.38.12026-08-05T01:41:11.7444743Z ##[group]Run actions/setup-go@v62026-08-05T01:41:11.7445021Z with:2026-08-05T01:41:11.7445227Z go-version: 1.262026-08-05T01:41:11.7445444Z cache: true2026-08-05T01:41:11.7445932Z cache-dependency-path: go.sum2026-08-05T01:41:11.7446203Z check-latest: false2026-08-05T01:41:11.7448780Z token: ***2026-08-05T01:41:11.7448991Z ##[endgroup]2026-08-05T01:41:11.8770726Z Setup go version spec 1.262026-08-05T01:41:11.8824428Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-05T01:41:11.8827213Z Added go to the path2026-08-05T01:41:11.8830948Z Successfully set up Go version 1.262026-08-05T01:41:12.5113074Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-05T01:41:12.5153674Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-05T01:41:12.5185399Z /home/runner/go/pkg/mod2026-08-05T01:41:12.5216180Z /home/runner/.cache/go-build2026-08-05T01:41:12.6107996Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-05T01:41:13.6572142Z Received 117440512 of 251839808 (46.6%), 111.8 MBs/sec2026-08-05T01:41:14.6527434Z Received 117440512 of 251839808 (46.6%), 55.9 MBs/sec2026-08-05T01:41:15.6540519Z Received 117440512 of 251839808 (46.6%), 37.3 MBs/sec2026-08-05T01:41:16.6552433Z Received 117440512 of 251839808 (46.6%), 28.0 MBs/sec2026-08-05T01:41:17.6564856Z Received 117440512 of 251839808 (46.6%), 22.4 MBs/sec2026-08-05T01:41:18.5127507Z Received 251839808 of 251839808 (100.0%), 41.0 MBs/sec2026-08-05T01:41:18.5128721Z Cache Size: ~240 MB (251839808 B)2026-08-05T01:41:18.5162913Z [command]/usr/bin/tar -xf /home/runner/work/_temp/63c6f2c0-def6-42c3-afb0-cf79619e3b80/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-05T01:41:21.4800509Z Cache restored successfully2026-08-05T01:41:21.5377233Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-05T01:41:21.5404065Z go version go1.26.5 linux/amd642026-08-05T01:41:21.5404305Z 2026-08-05T01:41:21.5404617Z ##[group]go env2026-08-05T01:41:21.5793674Z AR='ar'2026-08-05T01:41:21.5794446Z CC='gcc'2026-08-05T01:41:21.5795178Z CGO_CFLAGS='-O2 -g'2026-08-05T01:41:21.5795559Z CGO_CPPFLAGS=''2026-08-05T01:41:21.5796097Z CGO_CXXFLAGS='-O2 -g'2026-08-05T01:41:21.5796463Z CGO_ENABLED='1'2026-08-05T01:41:21.5796787Z CGO_FFLAGS='-O2 -g'2026-08-05T01:41:21.5797743Z CGO_LDFLAGS='-O2 -g'2026-08-05T01:41:21.5798067Z CXX='g++'2026-08-05T01:41:21.5798806Z GCCGO='gccgo'2026-08-05T01:41:21.5799112Z GO111MODULE=''2026-08-05T01:41:21.5799416Z GOAMD64='v1'2026-08-05T01:41:21.5799721Z GOARCH='amd64'2026-08-05T01:41:21.5800012Z GOAUTH='netrc'2026-08-05T01:41:21.5800302Z GOBIN=''2026-08-05T01:41:21.5800628Z GOCACHE='/home/runner/.cache/go-build'2026-08-05T01:41:21.5801045Z GOCACHEPROG=''2026-08-05T01:41:21.5801358Z GODEBUG=''2026-08-05T01:41:21.5801719Z GOENV='/home/runner/.config/go/env'2026-08-05T01:41:21.5802108Z GOEXE=''2026-08-05T01:41:21.5802389Z GOEXPERIMENT=''2026-08-05T01:41:21.5802713Z GOFIPS140='off'2026-08-05T01:41:21.5803018Z GOFLAGS=''2026-08-05T01:41:21.5804021Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build1105967969=/tmp/go-build -gno-record-gcc-switches'2026-08-05T01:41:21.5805013Z GOHOSTARCH='amd64'2026-08-05T01:41:21.5805326Z GOHOSTOS='linux'2026-08-05T01:41:21.5805638Z GOINSECURE=''2026-08-05T01:41:21.5806371Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-05T01:41:21.5807015Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-05T01:41:21.5807434Z GONOPROXY=''2026-08-05T01:41:21.5807735Z GONOSUMDB=''2026-08-05T01:41:21.5808026Z GOOS='linux'2026-08-05T01:41:21.5808339Z GOPATH='/home/runner/go'2026-08-05T01:41:21.5808720Z GOPRIVATE=''2026-08-05T01:41:21.5809112Z GOPROXY='https://proxy.golang.org,direct'2026-08-05T01:41:21.5809945Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-05T01:41:21.5810404Z GOSUMDB='sum.golang.org'2026-08-05T01:41:21.5810766Z GOTELEMETRY='local'2026-08-05T01:41:21.5811178Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-05T01:41:21.5811653Z GOTMPDIR=''2026-08-05T01:41:21.5811954Z GOTOOLCHAIN='local'2026-08-05T01:41:21.5812449Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-05T01:41:21.5812990Z GOVCS=''2026-08-05T01:41:21.5813295Z GOVERSION='go1.26.5'2026-08-05T01:41:21.5813596Z GOWORK=''2026-08-05T01:41:21.5813900Z PKG_CONFIG='pkg-config'2026-08-05T01:41:21.5814108Z 2026-08-05T01:41:21.5814601Z ##[endgroup]2026-08-05T01:41:21.6034695Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-05T01:41:21.6035246Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-05T01:41:21.6098163Z shell: /usr/bin/bash -e {0}2026-08-05T01:41:21.6098433Z env:2026-08-05T01:41:21.6098646Z GOTOOLCHAIN: local2026-08-05T01:41:21.6098895Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-08-05T01:41:21.6099186Z OSPOOL_EP_CMTag: 25.x-el92026-08-05T01:41:21.6099442Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-05T01:41:21.6099698Z ##[endgroup]2026-08-05T01:41:28.1311097Z === RUN TestOSPoolEP2026-08-05T01:41:28.1312068Z === PAUSE TestOSPoolEP2026-08-05T01:41:28.1312515Z === CONT TestOSPoolEP2026-08-05T01:41:28.1313567Z TestOSPoolEP 2026-08-05T01:41:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:28.2545852Z TestOSPoolEP 2026-08-05T01:41:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 apply -f /tmp/TestOSPoolEP904926722]2026-08-05T01:41:28.9559261Z TestOSPoolEP 2026-08-05T01:41:28Z logger.go:67: pod/idtoken-generator created2026-08-05T01:41:28.9642844Z TestOSPoolEP 2026-08-05T01:41:28Z logger.go:67: secret/pool-passwd created2026-08-05T01:41:28.9701022Z TestOSPoolEP 2026-08-05T01:41:28Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-05T01:41:28.9702274Z TestOSPoolEP 2026-08-05T01:41:28Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:28.9721582Z TestOSPoolEP 2026-08-05T01:41:28Z 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-05T01:41:38.9727964Z TestOSPoolEP 2026-08-05T01:41:38Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-05T01:41:38.9729130Z TestOSPoolEP 2026-08-05T01:41:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:38.9751589Z TestOSPoolEP 2026-08-05T01:41:38Z retry.go:103: Wait for pod idtoken-generator to be provisioned. returned an error: Pod idtoken-generator is not available, reason: , message: . Sleeping for 10s and will try again.2026-08-05T01:41:48.9761395Z TestOSPoolEP 2026-08-05T01:41:48Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-05T01:41:48.9762648Z TestOSPoolEP 2026-08-05T01:41:48Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:48.9786908Z TestOSPoolEP 2026-08-05T01:41:48Z logger.go:67: Pod is now available2026-08-05T01:41:48.9788453Z TestOSPoolEP 2026-08-05T01:41:48Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-05T01:41:49.4162960Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: ***2026-08-05T01:41:49.4229959Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 apply -f /tmp/TestOSPoolEP3068339105]2026-08-05T01:41:49.6038078Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: secret/pool-token created2026-08-05T01:41:49.6074048Z TestOSPoolEP 2026-08-05T01:41:49Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-05T01:41:49.6075386Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:49.6093995Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: Secret is now available2026-08-05T01:41:49.6122765Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 apply -k /tmp/kustomize-template-3772536906]2026-08-05T01:41:49.8880277Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: service/test-cm created2026-08-05T01:41:49.8973687Z TestOSPoolEP 2026-08-05T01:41:49Z 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-05T01:41:49.8976762Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: deployment.apps/ospool-ep created2026-08-05T01:41:49.9074842Z TestOSPoolEP 2026-08-05T01:41:49Z logger.go:67: deployment.apps/test-cm created2026-08-05T01:41:49.9124799Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-05T01:41:49.9130627Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:49.9257624Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-05T01:41:49.9260318Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:49.9262133Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-05T01:41:49.9263908Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:49.9310040Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "ospool-ep-65c844ddbb". Sleeping for 10s and will try again.2026-08-05T01:41:49.9318131Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:49Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "test-cm-5bb545475c". Sleeping for 10s and will try again.2026-08-05T01:41:59.9309804Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:59Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-05T01:41:59.9312125Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:59Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-05T01:41:59.9314604Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:59.9317544Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:41:59.9356849Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:59Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-65c844ddbb" is progressing.. Sleeping for 10s and will try again.2026-08-05T01:41:59.9359724Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:41:59Z logger.go:67: Deployment is now available2026-08-05T01:42:09.9360979Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:42:09Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-05T01:42:09.9362705Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9386524Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-05T01:42:09Z logger.go:67: Deployment is now available2026-08-05T01:42:09.9388018Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-05T01:42:09.9388644Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-05T01:42:09.9389317Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:09.9390058Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:09.9390744Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-05T01:42:09.9391406Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-05T01:42:09.9392023Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-05T01:42:09.9401374Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9405534Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-05T01:42:09.9406516Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:09.9407908Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9409874Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9424472Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9428271Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9430241Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:42:09.9451181Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:09Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]2026-08-05T01:42:09.9454433Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:42:09Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:42:09.9476259Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:09Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']2026-08-05T01:42:10.1751019Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-05T01:42:10.1753665Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:42:10.1920024Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:10.1922596Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:10.1948066Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-05T01:42:10.1949141Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:20.1930649Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:20Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]2026-08-05T01:42:20.1952801Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:20Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']2026-08-05T01:42:20.3727238Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:20.3728557Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:20.3730070Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:30.3734157Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]2026-08-05T01:42:30.3737099Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']2026-08-05T01:42:30.5616424Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-05T01:42:30.5618113Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:30.5620023Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:30.5621182Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:40.1762736Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:42:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:42:40.3483179Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-05T01:42:40.3484676Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:42:40.5628469Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']2026-08-05T01:42:40.5631696Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]2026-08-05T01:42:40.8104305Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-05T01:42:40.8105604Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:40.8296594Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-05T01:42:40.8297781Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 10s.2026-08-05T01:42:50.8128950Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)']2026-08-05T01:42:50.8306766Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:50Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_SINGULARITY]2026-08-05T01:42:50.9976153Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-05T01:42:50.9977864Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: 2026-08-05T01:42:50.9979449Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-f6d49 LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-08-05T01:42:50.9980728Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: 2026-08-05T01:42:50.9981784Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-05T01:42:50.9982946Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: 2026-08-05T01:42:50.9983818Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-05T01:42:50.9985015Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: 2026-08-05T01:42:50.9986937Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-05T01:42:50Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-05T01:42:51.0092685Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-05T01:42:51Z logger.go:67: true2026-08-05T01:43:10.3490685Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:43:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:43:10.5082196Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:43:10Z logger.go:67: undefined2026-08-05T01:43:10.5141205Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-05T01:43:10.5142491Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:43:40.5155021Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:43:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:43:40.6636889Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:43:40Z logger.go:67: undefined2026-08-05T01:43:40.6699249Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:44:10.6702295Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:44:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:44:10.8208705Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:44:10Z logger.go:67: undefined2026-08-05T01:44:10.8262388Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:44:40.8280555Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:44:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:44:40.9759655Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:44:40Z logger.go:67: undefined2026-08-05T01:44:40.9819091Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:45:10.9823230Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:45:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:45:11.1320957Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:45:11Z logger.go:67: undefined2026-08-05T01:45:11.1378966Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:45:41.1411005Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:45:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:45:41.3618215Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:45:41Z logger.go:67: undefined2026-08-05T01:45:41.3689216Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:46:11.3697250Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:46:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:46:11.5187168Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:46:11Z logger.go:67: undefined2026-08-05T01:46:11.5247836Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:46:41.5280002Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:46:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:46:41.6971877Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:46:41Z logger.go:67: undefined2026-08-05T01:46:41.7081287Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:47:11.7085512Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:47:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:47:11.8973196Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:47:11Z logger.go:67: undefined2026-08-05T01:47:11.9108968Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:47:41.9110901Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:47:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 exec test-cm-5bb545475c-r6xjr -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-05T01:47:42.0813081Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-05T01:47:42Z logger.go:67: undefined2026-08-05T01:47:42.0877566Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr failed. Retrying in 30s.2026-08-05T01:48:12.0881828Z test_utils.go:105: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-f6d49",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-r6xjr did not succeed within {12 30000000000} retries2026-08-05T01:48:12.0883575Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.0959133Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.2239823Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.3618805Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.4866255Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.4897454Z === NAME TestOSPoolEP2026-08-05T01:48:12.4901141Z ospool_ep_test.go:101: ---2026-08-05T01:48:12.4901743Z Events for pod idtoken-generator:2026-08-05T01:48:12.4903037Z 2026-08-05 01:41:28 +0000 UTC Normal Successfully assigned test-ospool-ep-als5w1/idtoken-generator to minikube2026-08-05T01:48:12.4904116Z 2026-08-05 01:41:29 +0000 UTC Normal Pulling image "htcondor/base"2026-08-05T01:48:12.4905627Z 2026-08-05 01:41:38 +0000 UTC Normal Successfully pulled image "htcondor/base" in 8.588s (8.588s including waiting). Image size: 560910730 bytes.2026-08-05T01:48:12.4907204Z 2026-08-05 01:41:38 +0000 UTC Normal Container created2026-08-05T01:48:12.4907914Z 2026-08-05 01:41:38 +0000 UTC Normal Container started2026-08-05T01:48:12.4908448Z 2026-08-05T01:48:12.4908738Z ---2026-08-05T01:48:12.4909581Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.4938487Z ospool_ep_test.go:101: ---2026-08-05T01:48:12.4939553Z Events for pod ospool-ep-65c844ddbb-f6d49:2026-08-05T01:48:12.4941349Z 2026-08-05 01:41:49 +0000 UTC Normal Successfully assigned test-ospool-ep-als5w1/ospool-ep-65c844ddbb-f6d49 to minikube2026-08-05T01:48:12.4943201Z 2026-08-05 01:41:50 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-05T01:48:12.4945460Z 2026-08-05 01:41:52 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.033s (2.033s including waiting). Image size: 5590942 bytes.2026-08-05T01:48:12.4954069Z 2026-08-05 01:41:52 +0000 UTC Normal Container created2026-08-05T01:48:12.4954901Z 2026-08-05 01:41:52 +0000 UTC Normal Container started2026-08-05T01:48:12.4956720Z 2026-08-05 01:41:52 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-08-05T01:48:12.4958818Z 2026-08-05 01:42:06 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 13.532s (13.723s including waiting). Image size: 1255889809 bytes.2026-08-05T01:48:12.4960259Z 2026-08-05 01:42:06 +0000 UTC Normal Container created2026-08-05T01:48:12.4961004Z 2026-08-05 01:42:06 +0000 UTC Normal Container started2026-08-05T01:48:12.4961501Z 2026-08-05T01:48:12.4961822Z ---2026-08-05T01:48:12.4962696Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.4969662Z ospool_ep_test.go:101: ---2026-08-05T01:48:12.4970694Z Events for pod test-cm-5bb545475c-r6xjr:2026-08-05T01:48:12.4971982Z 2026-08-05 01:41:49 +0000 UTC Normal Successfully assigned test-ospool-ep-als5w1/test-cm-5bb545475c-r6xjr to minikube2026-08-05T01:48:12.4973189Z 2026-08-05 01:41:50 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-08-05T01:48:12.4974761Z 2026-08-05 01:41:52 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 425ms (2.435s including waiting). Image size: 560910756 bytes.2026-08-05T01:48:12.4976123Z 2026-08-05 01:41:52 +0000 UTC Normal Container created2026-08-05T01:48:12.4976873Z 2026-08-05 01:41:52 +0000 UTC Normal Container started2026-08-05T01:48:12.4977361Z 2026-08-05T01:48:12.4977688Z ---2026-08-05T01:48:12.4978565Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-05T01:48:12.5012494Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 delete -f /tmp/TestOSPoolEP1001605772]2026-08-05T01:48:12.6185574Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-als5w1 namespace2026-08-05T01:48:12.6297345Z TestOSPoolEP 2026-08-05T01:48:12Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-als5w1 namespace2026-08-05T01:48:13.7768346Z TestOSPoolEP 2026-08-05T01:48:13Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 delete -f /tmp/TestOSPoolEP1919629400]2026-08-05T01:48:13.8654551Z TestOSPoolEP 2026-08-05T01:48:13Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-als5w1 namespace2026-08-05T01:48:13.8698715Z TestOSPoolEP 2026-08-05T01:48:13Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-als5w1 delete -k /tmp/kustomize-template-3772536906]2026-08-05T01:48:13.9791834Z TestOSPoolEP 2026-08-05T01:48:13Z logger.go:67: service "test-cm" deleted from test-ospool-ep-als5w1 namespace2026-08-05T01:48:13.9843969Z TestOSPoolEP 2026-08-05T01:48:13Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-als5w1 namespace2026-08-05T01:48:13.9881679Z TestOSPoolEP 2026-08-05T01:48:13Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-als5w1 namespace2026-08-05T01:48:13.9971484Z --- FAIL: TestOSPoolEP (43.72s)2026-08-05T01:48:13.9972585Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (20.03s)2026-08-05T01:48:13.9973420Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (41.06s)2026-08-05T01:48:13.9974295Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (41.08s)2026-08-05T01:48:13.9975150Z --- FAIL: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (362.15s)2026-08-05T01:48:13.9975963Z FAIL2026-08-05T01:48:13.9998684Z FAIL github.com/osg-htc/k8s-integration-tests/test 405.880s2026-08-05T01:48:14.0144447Z FAIL2026-08-05T01:48:14.1221246Z ##[error]Process completed with exit code 1.2026-08-05T01:48:14.1275925Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-05T01:48:14.1276583Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-05T01:48:14.1339818Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-05T01:48:14.1340195Z env:2026-08-05T01:48:14.1340405Z GOTOOLCHAIN: local2026-08-05T01:48:14.1340863Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-05T01:48:14.1341338Z ##[endgroup]2026-08-05T01:48:14.1569000Z 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-05T01:48:14.1570322Z ##[group]Run actions/upload-artifact@v42026-08-05T01:48:14.1570614Z with:2026-08-05T01:48:14.1570993Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-08-05T01:48:14.1571502Z path: /tmp/k8s-tests2026-08-05T01:48:14.1572014Z retention-days: 52026-08-05T01:48:14.1572242Z if-no-files-found: warn2026-08-05T01:48:14.1572489Z compression-level: 62026-08-05T01:48:14.1572720Z overwrite: false2026-08-05T01:48:14.1572947Z include-hidden-files: false2026-08-05T01:48:14.1573199Z env:2026-08-05T01:48:14.1573395Z GOTOOLCHAIN: local2026-08-05T01:48:14.1573619Z ##[endgroup]2026-08-05T01:48:14.3823344Z (node:18333) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-05T01:48:14.3824109Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-05T01:48:14.3952773Z With the provided path, there will be 6 files uploaded2026-08-05T01:48:14.3959529Z Artifact name is valid!2026-08-05T01:48:14.3960530Z Root directory input is valid!2026-08-05T01:48:14.5505180Z Beginning upload of artifact content to blob storage2026-08-05T01:48:14.5922031Z (node:18333) [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-05T01:48:19.6424635Z Uploaded bytes 140232026-08-05T01:48:19.6607632Z Finished uploading artifact content to blob storage!2026-08-05T01:48:19.6608534Z SHA256 digest of uploaded artifact zip is d348446e6bad9efff981c9cf3c9e37dcd7423b2873fd058aa3c627903ece6bde2026-08-05T01:48:19.6610666Z Finalizing artifact upload2026-08-05T01:48:19.8410973Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 89152756252026-08-05T01:48:19.8412994Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 14023 bytes. Artifact ID is 89152756252026-08-05T01:48:19.8418393Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30967038376/artifacts/89152756252026-08-05T01:48:19.8616982Z Post job cleanup.2026-08-05T01:48:19.9600516Z [command]/usr/bin/git version2026-08-05T01:48:19.9647033Z git version 2.54.02026-08-05T01:48:19.9690085Z Temporarily overriding HOME='/home/runner/work/_temp/d3c7ac66-18a4-4257-b9f7-754b55fc62ed' before making global git config changes2026-08-05T01:48:19.9691075Z Adding repository directory to the temporary git global config as a safe directory2026-08-05T01:48:19.9696323Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-05T01:48:19.9742370Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-05T01:48:19.9787006Z [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-05T01:48:20.0110343Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-05T01:48:20.0141548Z http.https://github.com/.extraheader2026-08-05T01:48:20.0161392Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-05T01:48:20.0193680Z [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-05T01:48:20.0505126Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-05T01:48:20.0570617Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-05T01:48:20.1125451Z Cleaning up orphan processes2026-08-05T01:48:20.1854917Z ##[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/