2026-07-28T01:30:06.1265530Z Current runner version: '2.336.0'2026-07-28T01:30:06.1290363Z ##[group]Runner Image Provisioner2026-07-28T01:30:06.1291196Z Hosted Compute Agent2026-07-28T01:30:06.1291917Z Version: 20260707.5632026-07-28T01:30:06.1292576Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-07-28T01:30:06.1293350Z Build Date: 2026-07-07T19:33:50Z2026-07-28T01:30:06.1294087Z Worker ID: {d3f87ca1-eb44-46bb-ae9a-0ba998155687}2026-07-28T01:30:06.1294825Z Azure Region: northcentralus2026-07-28T01:30:06.1295921Z ##[endgroup]2026-07-28T01:30:06.1297701Z ##[group]Operating System2026-07-28T01:30:06.1298436Z Ubuntu2026-07-28T01:30:06.1299026Z 22.04.52026-07-28T01:30:06.1299502Z LTS2026-07-28T01:30:06.1300124Z ##[endgroup]2026-07-28T01:30:06.1300662Z ##[group]Runner Image2026-07-28T01:30:06.1301294Z Image: ubuntu-22.042026-07-28T01:30:06.1301953Z Version: 20260720.234.22026-07-28T01:30:06.1303179Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-07-28T01:30:06.1304786Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-07-28T01:30:06.1306108Z ##[endgroup]2026-07-28T01:30:06.1307440Z ##[group]GITHUB_TOKEN Permissions2026-07-28T01:30:06.1309528Z Contents: read2026-07-28T01:30:06.1310243Z Metadata: read2026-07-28T01:30:06.1310818Z Packages: read2026-07-28T01:30:06.1311710Z ##[endgroup]2026-07-28T01:30:06.1313839Z Secret source: Actions2026-07-28T01:30:06.1315447Z Prepare workflow directory2026-07-28T01:30:06.1660381Z Prepare all required actions2026-07-28T01:30:06.1710157Z Getting action download info2026-07-28T01:30:06.4029888Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-07-28T01:30:06.7736074Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-07-28T01:30:06.9994568Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-07-28T01:30:07.2879752Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-07-28T01:30:07.5260859Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-07-28T01:30:07.5266300Z ##[group] Inputs2026-07-28T01:30:07.5266830Z testName: TestOSPoolEP2026-07-28T01:30:07.5267687Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-28T01:30:07.5268581Z setupScript: 2026-07-28T01:30:07.5268998Z setupEnv: {}2026-07-28T01:30:07.5269424Z ##[endgroup]2026-07-28T01:30:07.5270084Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-07-28T01:30:07.6116811Z ##[group]Run actions/checkout@v52026-07-28T01:30:07.6117998Z with:2026-07-28T01:30:07.6118495Z repository: mwestphall/k8s-integration-tests2026-07-28T01:30:07.6122223Z token: ***2026-07-28T01:30:07.6122645Z ssh-strict: true2026-07-28T01:30:07.6123098Z ssh-user: git2026-07-28T01:30:07.6123529Z persist-credentials: true2026-07-28T01:30:07.6124006Z clean: true2026-07-28T01:30:07.6124437Z sparse-checkout-cone-mode: true2026-07-28T01:30:07.6124950Z fetch-depth: 12026-07-28T01:30:07.6125595Z fetch-tags: false2026-07-28T01:30:07.6126026Z show-progress: true2026-07-28T01:30:07.6126449Z lfs: false2026-07-28T01:30:07.6126841Z submodules: false2026-07-28T01:30:07.6127268Z set-safe-directory: true2026-07-28T01:30:07.6127760Z allow-unsafe-pr-checkout: false2026-07-28T01:30:07.6128538Z ##[endgroup]2026-07-28T01:30:07.7193832Z Syncing repository: mwestphall/k8s-integration-tests2026-07-28T01:30:07.7196729Z ##[group]Getting Git version info2026-07-28T01:30:07.7197717Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-07-28T01:30:07.7198902Z [command]/usr/bin/git version2026-07-28T01:30:07.7276106Z git version 2.54.02026-07-28T01:30:07.7331367Z ##[endgroup]2026-07-28T01:30:07.7348281Z Temporarily overriding HOME='/home/runner/work/_temp/391798c7-6c34-4f94-9cbc-8d17403fc9b5' before making global git config changes2026-07-28T01:30:07.7350678Z Adding repository directory to the temporary git global config as a safe directory2026-07-28T01:30:07.7354353Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-28T01:30:07.7393854Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-07-28T01:30:07.7398549Z ##[group]Initializing the repository2026-07-28T01:30:07.7403479Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-28T01:30:07.7526603Z hint: Using 'master' as the name for the initial branch. This default branch name2026-07-28T01:30:07.7528488Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-07-28T01:30:07.7530156Z hint: to use in all of your new repositories, which will suppress this warning,2026-07-28T01:30:07.7539441Z hint: call:2026-07-28T01:30:07.7540402Z hint:2026-07-28T01:30:07.7541271Z hint: git config --global init.defaultBranch <name>2026-07-28T01:30:07.7542243Z hint:2026-07-28T01:30:07.7543142Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-07-28T01:30:07.7544639Z hint: 'development'. The just-created branch can be renamed via this command:2026-07-28T01:30:07.7546195Z hint:2026-07-28T01:30:07.7546909Z hint: git branch -m <name>2026-07-28T01:30:07.7547648Z hint:2026-07-28T01:30:07.7548624Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-07-28T01:30:07.7550750Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-07-28T01:30:07.7556053Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-07-28T01:30:07.7599449Z ##[endgroup]2026-07-28T01:30:07.7600653Z ##[group]Disabling automatic garbage collection2026-07-28T01:30:07.7601787Z [command]/usr/bin/git config --local gc.auto 02026-07-28T01:30:07.7626842Z ##[endgroup]2026-07-28T01:30:07.7628064Z ##[group]Setting up auth2026-07-28T01:30:07.7634683Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-07-28T01:30:07.7675986Z [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-28T01:30:07.8083871Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-07-28T01:30:07.8120724Z [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-28T01:30:07.8364514Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-07-28T01:30:07.8401193Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-07-28T01:30:07.8647754Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-07-28T01:30:07.8687617Z ##[endgroup]2026-07-28T01:30:07.8688394Z ##[group]Fetching the repository2026-07-28T01:30:07.8698150Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-07-28T01:30:08.1371720Z From https://github.com/mwestphall/k8s-integration-tests2026-07-28T01:30:08.1374113Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-07-28T01:30:08.1408490Z ##[endgroup]2026-07-28T01:30:08.1410227Z ##[group]Determining the checkout info2026-07-28T01:30:08.1412228Z ##[endgroup]2026-07-28T01:30:08.1420314Z [command]/usr/bin/git sparse-checkout disable2026-07-28T01:30:08.1474618Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-07-28T01:30:08.1512938Z ##[group]Checking out the ref2026-07-28T01:30:08.1518075Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-07-28T01:30:08.1618876Z Switched to a new branch 'main'2026-07-28T01:30:08.1622409Z branch 'main' set up to track 'origin/main'.2026-07-28T01:30:08.1630019Z ##[endgroup]2026-07-28T01:30:08.1676323Z [command]/usr/bin/git log -1 --format=%H2026-07-28T01:30:08.1707580Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-07-28T01:30:08.2080436Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-07-28T01:30:08.2083203Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-07-28T01:30:08.2086477Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-07-28T01:30:08.2215760Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-28T01:30:08.2217350Z env:2026-07-28T01:30:08.2219237Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-28T01:30:08.2221528Z SETUP_ENV_YAML: {}2026-07-28T01:30:08.2222530Z ##[endgroup]2026-07-28T01:30:08.9866523Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-07-28T01:30:08.9868022Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-07-28T01:30:08.9929692Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-28T01:30:08.9930692Z env:2026-07-28T01:30:08.9931836Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-07-28T01:30:08.9933067Z ##[endgroup]2026-07-28T01:30:09.0098407Z ##[group]Run true2026-07-28T01:30:09.0099196Z [36;1mtrue[0m2026-07-28T01:30:09.0156949Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-28T01:30:09.0157892Z ##[endgroup]2026-07-28T01:30:09.0388141Z ##[group]Run medyagh/setup-minikube@latest2026-07-28T01:30:09.0389061Z with:2026-07-28T01:30:09.0389804Z start: true2026-07-28T01:30:09.0390556Z cache: true2026-07-28T01:30:09.0391338Z minikube-version: latest2026-07-28T01:30:09.0392174Z wait: all2026-07-28T01:30:09.0392920Z ##[endgroup]2026-07-28T01:30:09.1659086Z (node:2059) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-07-28T01:30:09.1660959Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-07-28T01:30:09.9561426Z [command]/usr/bin/chmod +x /home/runner/work/_temp/1fd450d9-b48d-4080-9520-48093d07ebc92026-07-28T01:30:10.1197142Z [command]/home/runner/bin/minikube version --short2026-07-28T01:30:10.1663511Z v1.38.12026-07-28T01:30:10.2846445Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-07-28T01:30:10.2960964Z (node:2059) [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-28T01:30:10.2968437Z Cache hit for: kic-v1.38.1-x642026-07-28T01:30:11.3808664Z Received 134217728 of 224218863 (59.9%), 127.7 MBs/sec2026-07-28T01:30:11.4020786Z Received 100663296 of 544082657 (18.5%), 95.6 MBs/sec2026-07-28T01:30:11.9429238Z Received 224218863 of 224218863 (100.0%), 136.7 MBs/sec2026-07-28T01:30:11.9430448Z Cache Size: ~214 MB (224218863 B)2026-07-28T01:30:12.0136138Z [command]/usr/bin/tar -xf /home/runner/work/_temp/7df1dc73-ee14-4a00-b574-c4b31703a278/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-28T01:30:12.5346817Z Received 268435456 of 544082657 (49.3%), 119.9 MBs/sec2026-07-28T01:30:12.6066329Z Cache restored successfully2026-07-28T01:30:13.5340350Z Received 473956352 of 544082657 (87.1%), 144.1 MBs/sec2026-07-28T01:30:13.9689776Z Received 544082657 of 544082657 (100.0%), 145.3 MBs/sec2026-07-28T01:30:13.9691040Z Cache Size: ~519 MB (544082657 B)2026-07-28T01:30:13.9811475Z [command]/usr/bin/tar -xf /home/runner/work/_temp/8b927675-ffa2-4bd3-b484-62600b0382f0/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-28T01:30:14.8670524Z Cache restored successfully2026-07-28T01:30:14.9800043Z [command]/home/runner/bin/minikube start --wait all2026-07-28T01:30:15.0186295Z * minikube v1.38.1 on Ubuntu 22.042026-07-28T01:30:15.5402536Z ! 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-28T01:30:15.5409066Z * Automatically selected the docker driver. Other choices: podman, ssh, none2026-07-28T01:30:15.5973900Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-07-28T01:30:15.5986175Z * Using Docker driver with root privileges2026-07-28T01:30:15.5998530Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-07-28T01:30:15.6007716Z * Pulling base image v0.0.50 ...2026-07-28T01:30:47.7484535Z * Configuring bridge CNI (Container Networking Interface) ...2026-07-28T01:30:52.8940212Z * Verifying Kubernetes components...2026-07-28T01:30:52.9176287Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-07-28T01:30:53.6080276Z * Enabled addons: storage-provisioner, default-storageclass2026-07-28T01:31:35.8365796Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-07-28T01:31:35.8505456Z [command]/home/runner/bin/minikube version --short2026-07-28T01:31:35.8886122Z v1.38.12026-07-28T01:31:35.9184446Z ##[group]Run actions/setup-go@v62026-07-28T01:31:35.9184738Z with:2026-07-28T01:31:35.9184930Z go-version: 1.262026-07-28T01:31:35.9185140Z cache: true2026-07-28T01:31:35.9185670Z cache-dependency-path: go.sum2026-07-28T01:31:35.9185934Z check-latest: false2026-07-28T01:31:35.9188637Z token: ***2026-07-28T01:31:35.9188854Z ##[endgroup]2026-07-28T01:31:36.0485511Z Setup go version spec 1.262026-07-28T01:31:36.0806791Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-07-28T01:31:36.0808668Z Added go to the path2026-07-28T01:31:36.0812356Z Successfully set up Go version 1.262026-07-28T01:31:37.0006453Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-07-28T01:31:37.0040687Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-07-28T01:31:37.0070468Z /home/runner/go/pkg/mod2026-07-28T01:31:37.0097312Z /home/runner/.cache/go-build2026-07-28T01:31:37.1277521Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-07-28T01:31:38.3450472Z Received 134217728 of 251839808 (53.3%), 117.1 MBs/sec2026-07-28T01:31:38.9666341Z Received 251839808 of 251839808 (100.0%), 139.9 MBs/sec2026-07-28T01:31:38.9668856Z Cache Size: ~240 MB (251839808 B)2026-07-28T01:31:38.9740089Z [command]/usr/bin/tar -xf /home/runner/work/_temp/ba5ec739-4b4c-4ad0-98af-b3dd920b8f73/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-07-28T01:31:42.0596933Z Cache restored successfully2026-07-28T01:31:42.1201231Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-07-28T01:31:42.1228544Z go version go1.26.5 linux/amd642026-07-28T01:31:42.1228751Z 2026-07-28T01:31:42.1229053Z ##[group]go env2026-07-28T01:31:42.1849088Z AR='ar'2026-07-28T01:31:42.1849529Z CC='gcc'2026-07-28T01:31:42.1849966Z CGO_CFLAGS='-O2 -g'2026-07-28T01:31:42.1850455Z CGO_CPPFLAGS=''2026-07-28T01:31:42.1850758Z CGO_CXXFLAGS='-O2 -g'2026-07-28T01:31:42.1851072Z CGO_ENABLED='1'2026-07-28T01:31:42.1851345Z CGO_FFLAGS='-O2 -g'2026-07-28T01:31:42.1851639Z CGO_LDFLAGS='-O2 -g'2026-07-28T01:31:42.1852170Z CXX='g++'2026-07-28T01:31:42.1852431Z GCCGO='gccgo'2026-07-28T01:31:42.1852697Z GO111MODULE=''2026-07-28T01:31:42.1852960Z GOAMD64='v1'2026-07-28T01:31:42.1853236Z GOARCH='amd64'2026-07-28T01:31:42.1853494Z GOAUTH='netrc'2026-07-28T01:31:42.1853752Z GOBIN=''2026-07-28T01:31:42.1854032Z GOCACHE='/home/runner/.cache/go-build'2026-07-28T01:31:42.1854395Z GOCACHEPROG=''2026-07-28T01:31:42.1854663Z GODEBUG=''2026-07-28T01:31:42.1854960Z GOENV='/home/runner/.config/go/env'2026-07-28T01:31:42.1855572Z GOEXE=''2026-07-28T01:31:42.1855821Z GOEXPERIMENT=''2026-07-28T01:31:42.1856126Z GOFIPS140='off'2026-07-28T01:31:42.1856393Z GOFLAGS=''2026-07-28T01:31:42.1857272Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build2916481868=/tmp/go-build -gno-record-gcc-switches'2026-07-28T01:31:42.1858176Z GOHOSTARCH='amd64'2026-07-28T01:31:42.1858461Z GOHOSTOS='linux'2026-07-28T01:31:42.1858742Z GOINSECURE=''2026-07-28T01:31:42.1859199Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-07-28T01:31:42.1859771Z GOMODCACHE='/home/runner/go/pkg/mod'2026-07-28T01:31:42.1860123Z GONOPROXY=''2026-07-28T01:31:42.1860378Z GONOSUMDB=''2026-07-28T01:31:42.1860635Z GOOS='linux'2026-07-28T01:31:42.1860927Z GOPATH='/home/runner/go'2026-07-28T01:31:42.1861238Z GOPRIVATE=''2026-07-28T01:31:42.1861582Z GOPROXY='https://proxy.golang.org,direct'2026-07-28T01:31:42.1862019Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-07-28T01:31:42.1862413Z GOSUMDB='sum.golang.org'2026-07-28T01:31:42.1862722Z GOTELEMETRY='local'2026-07-28T01:31:42.1863110Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-07-28T01:31:42.1863519Z GOTMPDIR=''2026-07-28T01:31:42.1863779Z GOTOOLCHAIN='local'2026-07-28T01:31:42.1864426Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-07-28T01:31:42.1864904Z GOVCS=''2026-07-28T01:31:42.1865537Z GOVERSION='go1.26.5'2026-07-28T01:31:42.1865834Z GOWORK=''2026-07-28T01:31:42.1866160Z PKG_CONFIG='pkg-config'2026-07-28T01:31:42.1866346Z 2026-07-28T01:31:42.1866693Z ##[endgroup]2026-07-28T01:31:42.2049487Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-07-28T01:31:42.2050077Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-07-28T01:31:42.2108820Z shell: /usr/bin/bash -e {0}2026-07-28T01:31:42.2109074Z env:2026-07-28T01:31:42.2109273Z GOTOOLCHAIN: local2026-07-28T01:31:42.2109514Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-07-28T01:31:42.2109798Z OSPOOL_EP_CMTag: 25.x-el92026-07-28T01:31:42.2110043Z OSPOOL_EP_CvmfsType: cvmfsexec2026-07-28T01:31:42.2110293Z ##[endgroup]2026-07-28T01:31:49.9580832Z === RUN TestOSPoolEP2026-07-28T01:31:49.9590830Z === PAUSE TestOSPoolEP2026-07-28T01:31:49.9591288Z === CONT TestOSPoolEP2026-07-28T01:31:49.9592559Z TestOSPoolEP 2026-07-28T01:31:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:31:49.9668822Z TestOSPoolEP 2026-07-28T01:31:49Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu apply -f /tmp/TestOSPoolEP888763187]2026-07-28T01:31:50.5767787Z TestOSPoolEP 2026-07-28T01:31:50Z logger.go:67: pod/idtoken-generator created2026-07-28T01:31:50.5832928Z TestOSPoolEP 2026-07-28T01:31:50Z logger.go:67: secret/pool-passwd created2026-07-28T01:31:50.5872695Z TestOSPoolEP 2026-07-28T01:31:50Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-28T01:31:50.5874538Z TestOSPoolEP 2026-07-28T01:31:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:31:50.5896897Z TestOSPoolEP 2026-07-28T01:31:50Z 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-28T01:32:00.5899959Z TestOSPoolEP 2026-07-28T01:32:00Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-28T01:32:00.5901392Z TestOSPoolEP 2026-07-28T01:32:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:00.6311396Z TestOSPoolEP 2026-07-28T01:32:00Z 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-28T01:32:10.6314348Z TestOSPoolEP 2026-07-28T01:32:10Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-07-28T01:32:10.6318167Z TestOSPoolEP 2026-07-28T01:32:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:10.6339423Z TestOSPoolEP 2026-07-28T01:32:10Z logger.go:67: Pod is now available2026-07-28T01:32:10.6341334Z TestOSPoolEP 2026-07-28T01:32:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-07-28T01:32:11.2380874Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: ***2026-07-28T01:32:11.2435998Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu apply -f /tmp/TestOSPoolEP1578314644]2026-07-28T01:32:11.4217933Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: secret/pool-token created2026-07-28T01:32:11.4243242Z TestOSPoolEP 2026-07-28T01:32:11Z retry.go:91: Wait for secret pool-token to be provisioned.2026-07-28T01:32:11.4244409Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:11.4262781Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: Secret is now available2026-07-28T01:32:11.4293281Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu apply -k /tmp/kustomize-template-265583678]2026-07-28T01:32:11.7286420Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: service/test-cm created2026-07-28T01:32:11.7382225Z TestOSPoolEP 2026-07-28T01:32:11Z 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-28T01:32:11.7389608Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: deployment.apps/ospool-ep created2026-07-28T01:32:11.7490442Z TestOSPoolEP 2026-07-28T01:32:11Z logger.go:67: deployment.apps/test-cm created2026-07-28T01:32:11.7550971Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-07-28T01:32:11.7569830Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:11.7724556Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-28T01:32:11.7738459Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:11.7741318Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-28T01:32:11.7744314Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:11.7787788Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z 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-07-28T01:32:11.7832496Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:11Z 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-07-28T01:32:21.7790134Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:21Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-07-28T01:32:21.7793647Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:21.7816575Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:21Z logger.go:67: Deployment is now available2026-07-28T01:32:21.7853706Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:21Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-28T01:32:21.7855091Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:21.7879980Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:21Z 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-07-28T01:32:31.7885696Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:31Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-07-28T01:32:31.7887140Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7911563Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-07-28T01:32:31Z logger.go:67: Deployment is now available2026-07-28T01:32:31.7913307Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-28T01:32:31.7914360Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-28T01:32:31.7915677Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-28T01:32:31.7916784Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-28T01:32:31.7917784Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-28T01:32:31.7918735Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-28T01:32:31.7919723Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-28T01:32:31.7921387Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7922852Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-28T01:32:31.7923662Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-28T01:32:31.7925531Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7928264Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7949256Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7956665Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7958921Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:32:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:32:31.7977523Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:32:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']2026-07-28T01:32:31.8001807Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:32:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:32:31.8006225Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:32:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]2026-07-28T01:32:32.0349818Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-28T01:32:32.0351640Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 10s.2026-07-28T01:32:32.0352975Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-28T01:32:32.0357121Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:32:32.0367422Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-28T01:32:32.0369511Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 10s.2026-07-28T01:32:42.0363780Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:32:42Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']2026-07-28T01:32:42.0376279Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:32:42Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]2026-07-28T01:32:42.2156590Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-28T01:32:42.2157984Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 10s.2026-07-28T01:32:42.2182052Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-28T01:32:42.2183472Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 10s.2026-07-28T01:32:52.2173240Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:32:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']2026-07-28T01:32:52.2185063Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:32:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]2026-07-28T01:32:52.4073544Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-07-28T01:32:52.4075470Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 10s.2026-07-28T01:32:52.4083783Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-07-28T01:32:52.4086059Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 10s.2026-07-28T01:33:02.0357923Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:33:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:33:02.1963108Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:33:02Z logger.go:67: undefined2026-07-28T01:33:02.2014205Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-07-28T01:33:02.2016611Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:33:02.4079698Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)']2026-07-28T01:33:02.4090975Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:33:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_SINGULARITY]2026-07-28T01:33:02.6227581Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-07-28T01:33:02Z logger.go:67: true2026-07-28T01:33:02.6470034Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-07-28T01:33:02.6472298Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: 2026-07-28T01:33:02.6478627Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-qjp26 LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-07-28T01:33:02.6480365Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: 2026-07-28T01:33:02.6481971Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-07-28T01:33:02.6483480Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: 2026-07-28T01:33:02.6484872Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-07-28T01:33:02.6486534Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: 2026-07-28T01:33:02.6489812Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-07-28T01:33:02Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-07-28T01:33:32.2018527Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:33:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:33:32.3452892Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:33:32Z logger.go:67: undefined2026-07-28T01:33:32.3508413Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:34:02.3522358Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:34:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:34:02.5167669Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:34:02Z logger.go:67: undefined2026-07-28T01:34:02.5225429Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:34:32.5233146Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:34:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:34:32.6878796Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:34:32Z logger.go:67: undefined2026-07-28T01:34:32.6938585Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:35:02.6962594Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:35:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:35:02.8771105Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:35:02Z logger.go:67: undefined2026-07-28T01:35:02.8840518Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:35:32.8866593Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:35:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:35:33.0316656Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:35:33Z logger.go:67: undefined2026-07-28T01:35:33.0399479Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:36:03.0402965Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:36:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:36:03.1870254Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:36:03Z logger.go:67: undefined2026-07-28T01:36:03.1926700Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:36:33.1933575Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:36:33Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:36:33.3374748Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:36:33Z logger.go:67: undefined2026-07-28T01:36:33.3431538Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:37:03.3445472Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:37:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:37:03.4894684Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:37:03Z logger.go:67: undefined2026-07-28T01:37:03.4950521Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:37:33.4982705Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:37:33Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:37:33.6482939Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:37:33Z logger.go:67: undefined2026-07-28T01:37:33.6546772Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-wwhjf failed. Retrying in 30s.2026-07-28T01:38:03.6579680Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:38:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu exec test-cm-5bb545475c-wwhjf -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-qjp26",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-07-28T01:38:03.8167862Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-07-28T01:38:03Z logger.go:67: true2026-07-28T01:38:03.8234985Z TestOSPoolEP 2026-07-28T01:38:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:03.8311983Z TestOSPoolEP 2026-07-28T01:38:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.0047840Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.1334915Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.2402129Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.2428253Z === NAME TestOSPoolEP2026-07-28T01:38:04.2429017Z ospool_ep_test.go:101: ---2026-07-28T01:38:04.2430124Z Events for pod idtoken-generator:2026-07-28T01:38:04.2431656Z 2026-07-28 01:31:50 +0000 UTC Normal Successfully assigned test-ospool-ep-5xizdu/idtoken-generator to minikube2026-07-28T01:38:04.2433002Z 2026-07-28 01:31:52 +0000 UTC Normal Pulling image "htcondor/base"2026-07-28T01:38:04.2435085Z 2026-07-28 01:32:00 +0000 UTC Normal Successfully pulled image "htcondor/base" in 8.651s (8.651s including waiting). Image size: 560910730 bytes.2026-07-28T01:38:04.2437155Z 2026-07-28 01:32:00 +0000 UTC Normal Container created2026-07-28T01:38:04.2438057Z 2026-07-28 01:32:01 +0000 UTC Normal Container started2026-07-28T01:38:04.2438635Z 2026-07-28T01:38:04.2439017Z ---2026-07-28T01:38:04.2440068Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.2454433Z ospool_ep_test.go:101: ---2026-07-28T01:38:04.2455639Z Events for pod ospool-ep-65c844ddbb-qjp26:2026-07-28T01:38:04.2457527Z 2026-07-28 01:32:11 +0000 UTC Normal Successfully assigned test-ospool-ep-5xizdu/ospool-ep-65c844ddbb-qjp26 to minikube2026-07-28T01:38:04.2459077Z 2026-07-28 01:32:12 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-07-28T01:38:04.2461714Z 2026-07-28 01:32:13 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 1.426s (1.426s including waiting). Image size: 5590942 bytes.2026-07-28T01:38:04.2463451Z 2026-07-28 01:32:13 +0000 UTC Normal Container created2026-07-28T01:38:04.2468235Z 2026-07-28 01:32:13 +0000 UTC Normal Container started2026-07-28T01:38:04.2470161Z 2026-07-28 01:32:14 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-07-28T01:38:04.2473729Z 2026-07-28 01:32:27 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 12.704s (12.704s including waiting). Image size: 1250776798 bytes.2026-07-28T01:38:04.2475005Z 2026-07-28 01:32:27 +0000 UTC Normal Container created2026-07-28T01:38:04.2475791Z 2026-07-28 01:32:27 +0000 UTC Normal Container started2026-07-28T01:38:04.2476130Z 2026-07-28T01:38:04.2476426Z ---2026-07-28T01:38:04.2477239Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.2481597Z ospool_ep_test.go:101: ---2026-07-28T01:38:04.2482721Z Events for pod test-cm-5bb545475c-wwhjf:2026-07-28T01:38:04.2484188Z 2026-07-28 01:32:11 +0000 UTC Normal Successfully assigned test-ospool-ep-5xizdu/test-cm-5bb545475c-wwhjf to minikube2026-07-28T01:38:04.2485786Z 2026-07-28 01:32:12 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-07-28T01:38:04.2487648Z 2026-07-28 01:32:14 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 543ms (1.958s including waiting). Image size: 560910756 bytes.2026-07-28T01:38:04.2489126Z 2026-07-28 01:32:14 +0000 UTC Normal Container created2026-07-28T01:38:04.2489992Z 2026-07-28 01:32:14 +0000 UTC Normal Container started2026-07-28T01:38:04.2490550Z 2026-07-28T01:38:04.2490925Z ---2026-07-28T01:38:04.2492243Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-07-28T01:38:04.2520412Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu delete -f /tmp/TestOSPoolEP1292017788]2026-07-28T01:38:04.3682540Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-5xizdu namespace2026-07-28T01:38:04.3739373Z TestOSPoolEP 2026-07-28T01:38:04Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-5xizdu namespace2026-07-28T01:38:05.5810904Z TestOSPoolEP 2026-07-28T01:38:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu delete -f /tmp/TestOSPoolEP3239246644]2026-07-28T01:38:05.6723990Z TestOSPoolEP 2026-07-28T01:38:05Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-5xizdu namespace2026-07-28T01:38:05.6766911Z TestOSPoolEP 2026-07-28T01:38:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-5xizdu delete -k /tmp/kustomize-template-265583678]2026-07-28T01:38:05.8036107Z TestOSPoolEP 2026-07-28T01:38:05Z logger.go:67: service "test-cm" deleted from test-ospool-ep-5xizdu namespace2026-07-28T01:38:05.8115894Z TestOSPoolEP 2026-07-28T01:38:05Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-5xizdu namespace2026-07-28T01:38:05.8178444Z TestOSPoolEP 2026-07-28T01:38:05Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-5xizdu namespace2026-07-28T01:38:05.8248364Z --- PASS: TestOSPoolEP (43.83s)2026-07-28T01:38:05.8250357Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (20.04s)2026-07-28T01:38:05.8251571Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (30.84s)2026-07-28T01:38:05.8252730Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (30.86s)2026-07-28T01:38:05.8253822Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.03s)2026-07-28T01:38:05.8255053Z PASS2026-07-28T01:38:05.8294053Z ok github.com/osg-htc/k8s-integration-tests/test 375.881s2026-07-28T01:38:05.9646423Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-07-28T01:38:05.9647116Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-07-28T01:38:05.9718494Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-07-28T01:38:05.9718873Z env:2026-07-28T01:38:05.9719068Z GOTOOLCHAIN: local2026-07-28T01:38:05.9719736Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-07-28T01:38:05.9720231Z ##[endgroup]2026-07-28T01:38:05.9933488Z 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-28T01:38:05.9934841Z ##[group]Run actions/upload-artifact@v42026-07-28T01:38:05.9935116Z with:2026-07-28T01:38:05.9935829Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-07-28T01:38:05.9936295Z path: /tmp/k8s-tests2026-07-28T01:38:05.9936529Z retention-days: 52026-07-28T01:38:05.9936747Z if-no-files-found: warn2026-07-28T01:38:05.9936981Z compression-level: 62026-07-28T01:38:05.9937576Z overwrite: false2026-07-28T01:38:05.9937797Z include-hidden-files: false2026-07-28T01:38:05.9938040Z env:2026-07-28T01:38:05.9938223Z GOTOOLCHAIN: local2026-07-28T01:38:05.9938439Z ##[endgroup]2026-07-28T01:38:06.2001231Z (node:17558) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-07-28T01:38:06.2002929Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-07-28T01:38:06.2089388Z With the provided path, there will be 6 files uploaded2026-07-28T01:38:06.2094151Z Artifact name is valid!2026-07-28T01:38:06.2095371Z Root directory input is valid!2026-07-28T01:38:06.4009005Z Beginning upload of artifact content to blob storage2026-07-28T01:38:06.4324265Z (node:17558) [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-28T01:38:06.5494070Z Uploaded bytes 139372026-07-28T01:38:06.5817899Z Finished uploading artifact content to blob storage!2026-07-28T01:38:06.5819046Z SHA256 digest of uploaded artifact zip is 1daf2abd71cf9d963e31e58b31f931b6605bfa3a5dbdee166677abec381835572026-07-28T01:38:06.5822713Z Finalizing artifact upload2026-07-28T01:38:06.7499126Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 86739073232026-07-28T01:38:06.7500806Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 13937 bytes. Artifact ID is 86739073232026-07-28T01:38:06.7506024Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30320418545/artifacts/86739073232026-07-28T01:38:06.7679397Z Post job cleanup.2026-07-28T01:38:06.9178524Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-07-28T01:38:06.9334157Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-07-28T01:38:06.9397210Z /home/runner/go/pkg/mod2026-07-28T01:38:06.9435553Z /home/runner/.cache/go-build2026-07-28T01:38:06.9440979Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-07-28T01:38:06.9713659Z Post job cleanup.2026-07-28T01:38:07.0930609Z [command]/usr/bin/git version2026-07-28T01:38:07.1001903Z git version 2.54.02026-07-28T01:38:07.1061444Z Temporarily overriding HOME='/home/runner/work/_temp/fdfe5432-bd04-48e0-a7e6-c4b9fdd125fe' before making global git config changes2026-07-28T01:38:07.1063723Z Adding repository directory to the temporary git global config as a safe directory2026-07-28T01:38:07.1073459Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-07-28T01:38:07.1143040Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-07-28T01:38:07.1204221Z [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-28T01:38:07.1525635Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-07-28T01:38:07.1566555Z http.https://github.com/.extraheader2026-07-28T01:38:07.1591250Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-07-28T01:38:07.1646769Z [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-28T01:38:07.1940788Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-07-28T01:38:07.1984869Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-07-28T01:38:07.2434168Z Cleaning up orphan processes2026-07-28T01:38:07.3245110Z ##[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/