2026-08-07T02:05:52.9005052Z Current runner version: '2.336.0'2026-08-07T02:05:52.9030825Z ##[group]Runner Image Provisioner2026-08-07T02:05:52.9031725Z Hosted Compute Agent2026-08-07T02:05:52.9032809Z Version: 20260707.5632026-08-07T02:05:52.9033497Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-07T02:05:52.9034224Z Build Date: 2026-07-07T19:33:50Z2026-08-07T02:05:52.9035028Z Worker ID: {5e4352e4-e520-4756-91c2-089bb0f62771}2026-08-07T02:05:52.9035746Z Azure Region: westus22026-08-07T02:05:52.9036368Z ##[endgroup]2026-08-07T02:05:52.9037944Z ##[group]Operating System2026-08-07T02:05:52.9038612Z Ubuntu2026-08-07T02:05:52.9039305Z 22.04.52026-08-07T02:05:52.9039835Z LTS2026-08-07T02:05:52.9040329Z ##[endgroup]2026-08-07T02:05:52.9040969Z ##[group]Runner Image2026-08-07T02:05:52.9041573Z Image: ubuntu-22.042026-08-07T02:05:52.9042668Z Version: 20260720.234.22026-08-07T02:05:52.9044098Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-07T02:05:52.9045754Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-07T02:05:52.9046790Z ##[endgroup]2026-08-07T02:05:52.9048079Z ##[group]GITHUB_TOKEN Permissions2026-08-07T02:05:52.9050260Z Contents: read2026-08-07T02:05:52.9050951Z Metadata: read2026-08-07T02:05:52.9051566Z Packages: read2026-08-07T02:05:52.9052510Z ##[endgroup]2026-08-07T02:05:52.9055120Z Secret source: Actions2026-08-07T02:05:52.9056271Z Prepare workflow directory2026-08-07T02:05:52.9413713Z Prepare all required actions2026-08-07T02:05:52.9469195Z Getting action download info2026-08-07T02:05:53.3130967Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-07T02:05:53.6502882Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-07T02:05:53.8634109Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-07T02:05:54.0901482Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-07T02:05:54.3910894Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-07T02:05:54.3917669Z ##[group] Inputs2026-08-07T02:05:54.3918368Z testName: TestOSPoolEP2026-08-07T02:05:54.3920085Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-07T02:05:54.3921714Z setupScript: 2026-08-07T02:05:54.3922559Z setupEnv: {}2026-08-07T02:05:54.3923118Z ##[endgroup]2026-08-07T02:05:54.3924330Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-08-07T02:05:54.4766964Z ##[group]Run actions/checkout@v52026-08-07T02:05:54.4768036Z with:2026-08-07T02:05:54.4768657Z repository: mwestphall/k8s-integration-tests2026-08-07T02:05:54.4777041Z token: ***2026-08-07T02:05:54.4777567Z ssh-strict: true2026-08-07T02:05:54.4778142Z ssh-user: git2026-08-07T02:05:54.4778690Z persist-credentials: true2026-08-07T02:05:54.4779325Z clean: true2026-08-07T02:05:54.4779872Z sparse-checkout-cone-mode: true2026-08-07T02:05:54.4780585Z fetch-depth: 12026-08-07T02:05:54.4781118Z fetch-tags: false2026-08-07T02:05:54.4781675Z show-progress: true2026-08-07T02:05:54.4782378Z lfs: false2026-08-07T02:05:54.4782862Z submodules: false2026-08-07T02:05:54.4783421Z set-safe-directory: true2026-08-07T02:05:54.4784092Z allow-unsafe-pr-checkout: false2026-08-07T02:05:54.4785140Z ##[endgroup]2026-08-07T02:05:54.5860214Z Syncing repository: mwestphall/k8s-integration-tests2026-08-07T02:05:54.5863198Z ##[group]Getting Git version info2026-08-07T02:05:54.5864671Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-07T02:05:54.5866801Z [command]/usr/bin/git version2026-08-07T02:05:54.5965608Z git version 2.54.02026-08-07T02:05:54.6024160Z ##[endgroup]2026-08-07T02:05:54.6042819Z Temporarily overriding HOME='/home/runner/work/_temp/c8559d76-5eea-4c1c-a19b-dd19452d9130' before making global git config changes2026-08-07T02:05:54.6047115Z Adding repository directory to the temporary git global config as a safe directory2026-08-07T02:05:54.6051478Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-07T02:05:54.6092887Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-07T02:05:54.6098030Z ##[group]Initializing the repository2026-08-07T02:05:54.6104361Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-07T02:05:54.6270940Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-07T02:05:54.6273353Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-07T02:05:54.6275134Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-07T02:05:54.6277432Z hint: call:2026-08-07T02:05:54.6278142Z hint:2026-08-07T02:05:54.6278973Z hint: git config --global init.defaultBranch <name>2026-08-07T02:05:54.6280027Z hint:2026-08-07T02:05:54.6280990Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-07T02:05:54.6285862Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-07T02:05:54.6287859Z hint:2026-08-07T02:05:54.6289206Z hint: git branch -m <name>2026-08-07T02:05:54.6290530Z hint:2026-08-07T02:05:54.6292479Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-07T02:05:54.6296284Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-07T02:05:54.6302401Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-07T02:05:54.6335526Z ##[endgroup]2026-08-07T02:05:54.6337398Z ##[group]Disabling automatic garbage collection2026-08-07T02:05:54.6340489Z [command]/usr/bin/git config --local gc.auto 02026-08-07T02:05:54.6381829Z ##[endgroup]2026-08-07T02:05:54.6384129Z ##[group]Setting up auth2026-08-07T02:05:54.6390605Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-07T02:05:54.6438410Z [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-07T02:05:54.6887302Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-07T02:05:54.6928673Z [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-07T02:05:54.7177641Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-07T02:05:54.7218016Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-07T02:05:54.7481201Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-07T02:05:54.7523637Z ##[endgroup]2026-08-07T02:05:54.7524997Z ##[group]Fetching the repository2026-08-07T02:05:54.7534369Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-07T02:05:55.0830479Z From https://github.com/mwestphall/k8s-integration-tests2026-08-07T02:05:55.0832344Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-07T02:05:55.0856296Z ##[endgroup]2026-08-07T02:05:55.0857256Z ##[group]Determining the checkout info2026-08-07T02:05:55.0858729Z ##[endgroup]2026-08-07T02:05:55.0864681Z [command]/usr/bin/git sparse-checkout disable2026-08-07T02:05:55.0914631Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-07T02:05:55.0947614Z ##[group]Checking out the ref2026-08-07T02:05:55.0951864Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-07T02:05:55.1089251Z Switched to a new branch 'main'2026-08-07T02:05:55.1092613Z branch 'main' set up to track 'origin/main'.2026-08-07T02:05:55.1098672Z ##[endgroup]2026-08-07T02:05:55.1149592Z [command]/usr/bin/git log -1 --format=%H2026-08-07T02:05:55.1178783Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-07T02:05:55.1492501Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-07T02:05:55.1494430Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-07T02:05:55.1496179Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-07T02:05:55.1635253Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-07T02:05:55.1636281Z env:2026-08-07T02:05:55.1637490Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-07T02:05:55.1638969Z SETUP_ENV_YAML: {}2026-08-07T02:05:55.1639504Z ##[endgroup]2026-08-07T02:05:55.6830789Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-07T02:05:55.6833502Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-07T02:05:55.6894453Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-07T02:05:55.6895924Z env:2026-08-07T02:05:55.6897748Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-07T02:05:55.6899886Z ##[endgroup]2026-08-07T02:05:55.7096247Z ##[group]Run true2026-08-07T02:05:55.7097215Z [36;1mtrue[0m2026-08-07T02:05:55.7155913Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-07T02:05:55.7157293Z ##[endgroup]2026-08-07T02:05:55.7434935Z ##[group]Run medyagh/setup-minikube@latest2026-08-07T02:05:55.7436104Z with:2026-08-07T02:05:55.7436884Z start: true2026-08-07T02:05:55.7437696Z cache: true2026-08-07T02:05:55.7438540Z minikube-version: latest2026-08-07T02:05:55.7439487Z wait: all2026-08-07T02:05:55.7440276Z ##[endgroup]2026-08-07T02:05:55.8830496Z (node:2047) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-07T02:05:55.8834847Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-07T02:05:56.9314562Z [command]/usr/bin/chmod +x /home/runner/work/_temp/b2036429-e9d1-4184-9562-579ba05463a42026-08-07T02:05:57.1066837Z [command]/home/runner/bin/minikube version --short2026-08-07T02:05:57.1482225Z v1.38.12026-08-07T02:05:57.4751038Z Cache hit for: kic-v1.38.1-x642026-08-07T02:05:57.4876354Z (node:2047) [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-07T02:05:57.4952507Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-07T02:05:58.7931375Z Received 4194304 of 544082657 (0.8%), 4.0 MBs/sec2026-08-07T02:05:59.7933437Z Received 125829120 of 544082657 (23.1%), 60.0 MBs/sec2026-08-07T02:06:00.7935713Z Received 230686720 of 544082657 (42.4%), 73.3 MBs/sec2026-08-07T02:06:01.7946045Z Received 348127232 of 544082657 (64.0%), 83.0 MBs/sec2026-08-07T02:06:02.7960340Z Received 486539264 of 544082657 (89.4%), 92.7 MBs/sec2026-08-07T02:06:03.3589502Z Received 544082657 of 544082657 (100.0%), 93.2 MBs/sec2026-08-07T02:06:03.3591212Z Cache Size: ~519 MB (544082657 B)2026-08-07T02:06:03.3720745Z [command]/usr/bin/tar -xf /home/runner/work/_temp/54fc5768-3f91-4187-ab96-3572f65fb7a2/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-07T02:06:03.8400295Z Received 8388608 of 224218863 (3.7%), 8.0 MBs/sec2026-08-07T02:06:04.2899923Z Cache restored successfully2026-08-07T02:06:04.8410653Z Received 117440512 of 224218863 (52.4%), 56.0 MBs/sec2026-08-07T02:06:05.7732695Z Received 224218863 of 224218863 (100.0%), 72.9 MBs/sec2026-08-07T02:06:05.7734113Z Cache Size: ~214 MB (224218863 B)2026-08-07T02:06:05.7808992Z [command]/usr/bin/tar -xf /home/runner/work/_temp/c82f4452-9ce2-423e-ac48-9f6461b9021f/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-07T02:06:06.3145193Z Cache restored successfully2026-08-07T02:06:06.3645140Z [command]/home/runner/bin/minikube start --wait all2026-08-07T02:06:06.4026129Z * minikube v1.38.1 on Ubuntu 22.042026-08-07T02:06:09.5226874Z ! 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-07T02:06:09.5257770Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-07T02:06:09.5961258Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-07T02:06:09.5969545Z * Using Docker driver with root privileges2026-08-07T02:06:09.5988556Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-07T02:06:09.6011409Z * Pulling base image v0.0.50 ...2026-08-07T02:06:42.4817231Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-07T02:06:47.1518475Z * Verifying Kubernetes components...2026-08-07T02:06:47.1755103Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-07T02:06:47.7137464Z * Enabled addons: storage-provisioner, default-storageclass2026-08-07T02:07:24.6432840Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-07T02:07:24.6554305Z [command]/home/runner/bin/minikube version --short2026-08-07T02:07:24.6928940Z v1.38.12026-08-07T02:07:24.7225216Z ##[group]Run actions/setup-go@v62026-08-07T02:07:24.7225512Z with:2026-08-07T02:07:24.7225702Z go-version: 1.262026-08-07T02:07:24.7225898Z cache: true2026-08-07T02:07:24.7226105Z cache-dependency-path: go.sum2026-08-07T02:07:24.7226362Z check-latest: false2026-08-07T02:07:24.7228743Z token: ***2026-08-07T02:07:24.7228945Z ##[endgroup]2026-08-07T02:07:24.8587414Z Setup go version spec 1.262026-08-07T02:07:24.8788678Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-07T02:07:24.8791338Z Added go to the path2026-08-07T02:07:24.8795162Z Successfully set up Go version 1.262026-08-07T02:07:25.3541356Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-07T02:07:25.3590776Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-07T02:07:25.3625292Z /home/runner/go/pkg/mod2026-08-07T02:07:25.3651077Z /home/runner/.cache/go-build2026-08-07T02:07:25.6483864Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-07T02:07:26.9536183Z Received 0 of 251839808 (0.0%), 0.0 MBs/sec2026-08-07T02:07:27.9574157Z Received 113246208 of 251839808 (45.0%), 53.9 MBs/sec2026-08-07T02:07:28.9562714Z Received 218103808 of 251839808 (86.6%), 69.2 MBs/sec2026-08-07T02:07:29.3436624Z Received 251839808 of 251839808 (100.0%), 70.8 MBs/sec2026-08-07T02:07:29.3438562Z Cache Size: ~240 MB (251839808 B)2026-08-07T02:07:29.3511515Z [command]/usr/bin/tar -xf /home/runner/work/_temp/fe756d18-2bf8-43a5-b387-86593b07273e/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-07T02:07:32.5171187Z Cache restored successfully2026-08-07T02:07:32.5730432Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-07T02:07:32.5759572Z go version go1.26.5 linux/amd642026-08-07T02:07:32.5759797Z 2026-08-07T02:07:32.5760091Z ##[group]go env2026-08-07T02:07:32.6099815Z AR='ar'2026-08-07T02:07:32.6100085Z CC='gcc'2026-08-07T02:07:32.6100314Z CGO_CFLAGS='-O2 -g'2026-08-07T02:07:32.6100571Z CGO_CPPFLAGS=''2026-08-07T02:07:32.6100932Z CGO_CXXFLAGS='-O2 -g'2026-08-07T02:07:32.6101259Z CGO_ENABLED='1'2026-08-07T02:07:32.6101577Z CGO_FFLAGS='-O2 -g'2026-08-07T02:07:32.6101885Z CGO_LDFLAGS='-O2 -g'2026-08-07T02:07:32.6102463Z CXX='g++'2026-08-07T02:07:32.6102683Z GCCGO='gccgo'2026-08-07T02:07:32.6102914Z GO111MODULE=''2026-08-07T02:07:32.6103142Z GOAMD64='v1'2026-08-07T02:07:32.6103363Z GOARCH='amd64'2026-08-07T02:07:32.6103588Z GOAUTH='netrc'2026-08-07T02:07:32.6103803Z GOBIN=''2026-08-07T02:07:32.6104054Z GOCACHE='/home/runner/.cache/go-build'2026-08-07T02:07:32.6104363Z GOCACHEPROG=''2026-08-07T02:07:32.6104582Z GODEBUG=''2026-08-07T02:07:32.6104821Z GOENV='/home/runner/.config/go/env'2026-08-07T02:07:32.6105125Z GOEXE=''2026-08-07T02:07:32.6105342Z GOEXPERIMENT=''2026-08-07T02:07:32.6105587Z GOFIPS140='off'2026-08-07T02:07:32.6105827Z GOFLAGS=''2026-08-07T02:07:32.6106577Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build1460749599=/tmp/go-build -gno-record-gcc-switches'2026-08-07T02:07:32.6107362Z GOHOSTARCH='amd64'2026-08-07T02:07:32.6107604Z GOHOSTOS='linux'2026-08-07T02:07:32.6107838Z GOINSECURE=''2026-08-07T02:07:32.6108249Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-07T02:07:32.6108730Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-07T02:07:32.6109032Z GONOPROXY=''2026-08-07T02:07:32.6109249Z GONOSUMDB=''2026-08-07T02:07:32.6109467Z GOOS='linux'2026-08-07T02:07:32.6109697Z GOPATH='/home/runner/go'2026-08-07T02:07:32.6109957Z GOPRIVATE=''2026-08-07T02:07:32.6110249Z GOPROXY='https://proxy.golang.org,direct'2026-08-07T02:07:32.6110615Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-07T02:07:32.6110952Z GOSUMDB='sum.golang.org'2026-08-07T02:07:32.6111222Z GOTELEMETRY='local'2026-08-07T02:07:32.6111792Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-07T02:07:32.6112485Z GOTMPDIR=''2026-08-07T02:07:32.6112700Z GOTOOLCHAIN='local'2026-08-07T02:07:32.6113039Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-07T02:07:32.6113400Z GOVCS=''2026-08-07T02:07:32.6113605Z GOVERSION='go1.26.5'2026-08-07T02:07:32.6113816Z GOWORK=''2026-08-07T02:07:32.6114029Z PKG_CONFIG='pkg-config'2026-08-07T02:07:32.6114178Z 2026-08-07T02:07:32.6114520Z ##[endgroup]2026-08-07T02:07:32.6295511Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-07T02:07:32.6296034Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-07T02:07:32.6352181Z shell: /usr/bin/bash -e {0}2026-08-07T02:07:32.6352436Z env:2026-08-07T02:07:32.6352648Z GOTOOLCHAIN: local2026-08-07T02:07:32.6352889Z OSPOOL_EP_OSPoolEPTag: 25-release2026-08-07T02:07:32.6353163Z OSPOOL_EP_CMTag: 25.0-el92026-08-07T02:07:32.6353414Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-07T02:07:32.6353683Z ##[endgroup]2026-08-07T02:07:39.1716373Z === RUN TestOSPoolEP2026-08-07T02:07:39.1718491Z === PAUSE TestOSPoolEP2026-08-07T02:07:39.1718904Z === CONT TestOSPoolEP2026-08-07T02:07:39.1729070Z TestOSPoolEP 2026-08-07T02:07:39Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:07:39.2060151Z TestOSPoolEP 2026-08-07T02:07:39Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni apply -f /tmp/TestOSPoolEP3106264377]2026-08-07T02:07:39.8570213Z TestOSPoolEP 2026-08-07T02:07:39Z logger.go:67: pod/idtoken-generator created2026-08-07T02:07:39.8681528Z TestOSPoolEP 2026-08-07T02:07:39Z logger.go:67: secret/pool-passwd created2026-08-07T02:07:39.8755426Z TestOSPoolEP 2026-08-07T02:07:39Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-07T02:07:39.8773457Z TestOSPoolEP 2026-08-07T02:07:39Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:07:39.8784051Z TestOSPoolEP 2026-08-07T02:07:39Z 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-07T02:07:49.8776420Z TestOSPoolEP 2026-08-07T02:07:49Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-07T02:07:49.8780558Z TestOSPoolEP 2026-08-07T02:07:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:07:49.8808355Z TestOSPoolEP 2026-08-07T02:07:49Z 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-07T02:07:59.8813015Z TestOSPoolEP 2026-08-07T02:07:59Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-07T02:07:59.8813999Z TestOSPoolEP 2026-08-07T02:07:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:07:59.8838970Z TestOSPoolEP 2026-08-07T02:07:59Z logger.go:67: Pod is now available2026-08-07T02:07:59.8840490Z TestOSPoolEP 2026-08-07T02:07:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-07T02:08:00.3667851Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: ***2026-08-07T02:08:00.3723461Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni apply -f /tmp/TestOSPoolEP1792141262]2026-08-07T02:08:00.5513016Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: secret/pool-token created2026-08-07T02:08:00.5544202Z TestOSPoolEP 2026-08-07T02:08:00Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-07T02:08:00.5545401Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:00.5566235Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: Secret is now available2026-08-07T02:08:00.5589190Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni apply -k /tmp/kustomize-template-3520741327]2026-08-07T02:08:00.8371559Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: service/test-cm created2026-08-07T02:08:00.8458237Z TestOSPoolEP 2026-08-07T02:08:00Z 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-07T02:08:00.8462734Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: deployment.apps/ospool-ep created2026-08-07T02:08:00.8725230Z TestOSPoolEP 2026-08-07T02:08:00Z logger.go:67: deployment.apps/test-cm created2026-08-07T02:08:00.8748730Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-07T02:08:00.8761331Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:00.8945373Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-07T02:08:00.8950812Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:00.8954311Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-07T02:08:00.8963044Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:00.8981064Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "test-cm-54b7d49f85". Sleeping for 10s and will try again.2026-08-07T02:08:00.9054394Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:00Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-07T02:08:10.8997626Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:10Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-07T02:08:10.8999386Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:10.9028001Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:10Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "test-cm-54b7d49f85" is progressing.. Sleeping for 10s and will try again.2026-08-07T02:08:10.9058684Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:10Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-07T02:08:10.9060879Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:10.9084988Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:10Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-07T02:08:20.9040273Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:20Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-07T02:08:20.9043501Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:20.9067910Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:20Z logger.go:67: Deployment is now available2026-08-07T02:08:20.9097151Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:20Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-07T02:08:20.9100578Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:20.9119046Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:20Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-07T02:08:30.9130918Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:30Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-07T02:08:30.9133080Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9179001Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-07T02:08:30Z logger.go:67: Deployment is now available2026-08-07T02:08:30.9180301Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-07T02:08:30.9181802Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-07T02:08:30.9182865Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-07T02:08:30.9183678Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-07T02:08:30.9184486Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-07T02:08:30.9185159Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-07T02:08:30.9185826Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-07T02:08:30.9186658Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9187793Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-07T02:08:30.9188657Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-07T02:08:30.9189878Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9191585Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9211391Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9212996Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9214218Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-07T02:08:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:08:30.9229613Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:08:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:08:30.9240117Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-07T02:08:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_SINGULARITY]2026-08-07T02:08:30.9246808Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)']2026-08-07T02:08:31.1617725Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-07T02:08:31.1620185Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:08:31.1795763Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-07T02:08:31.1820948Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 10s.2026-08-07T02:08:31.1831461Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-07T02:08:31.1833448Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)']' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 10s.2026-08-07T02:08:41.1806037Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-07T02:08:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_SINGULARITY]2026-08-07T02:08:41.1855152Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)']2026-08-07T02:08:41.4600930Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-07T02:08:41.4603577Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 10s.2026-08-07T02:08:41.4604762Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-07T02:08:41.4605871Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)']' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 10s.2026-08-07T02:08:51.4609037Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)']2026-08-07T02:08:51.4613189Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-07T02:08:51Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_SINGULARITY]2026-08-07T02:08:51.6523438Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-07T02:08:51Z logger.go:67: true2026-08-07T02:08:51.6533364Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-07T02:08:51.6535719Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: 2026-08-07T02:08:51.6538135Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-sqknz LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-08-07T02:08:51.6540276Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: 2026-08-07T02:08:51.6542876Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-07T02:08:51.6544391Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: 2026-08-07T02:08:51.6547030Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-07T02:08:51.6551636Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: 2026-08-07T02:08:51.6553351Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-07T02:08:51Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-07T02:09:01.1624219Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:09:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:09:01.3133116Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:09:01Z logger.go:67: undefined2026-08-07T02:09:01.3186439Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-07T02:09:01.3187939Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:09:31.3198014Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:09:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:09:31.4680600Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:09:31Z logger.go:67: undefined2026-08-07T02:09:31.4739184Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:10:01.4746696Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:10:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:10:01.6315858Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:10:01Z logger.go:67: undefined2026-08-07T02:10:01.6376006Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:10:31.6382663Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:10:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:10:31.7907769Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:10:31Z logger.go:67: undefined2026-08-07T02:10:31.7970135Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:11:01.7992329Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:11:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:11:01.9562671Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:11:01Z logger.go:67: undefined2026-08-07T02:11:01.9621549Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:11:31.9645006Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:11:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:11:32.1175797Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:11:32Z logger.go:67: undefined2026-08-07T02:11:32.1236514Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:12:02.1267298Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:12:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:12:02.2820592Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:12:02Z logger.go:67: undefined2026-08-07T02:12:02.2876435Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:12:32.2881489Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:12:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:12:32.4628740Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:12:32Z logger.go:67: undefined2026-08-07T02:12:32.4683309Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:13:02.4697747Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:13:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:13:02.6203835Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:13:02Z logger.go:67: undefined2026-08-07T02:13:02.6260831Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:13:32.6293861Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:13:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:13:32.7730225Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:13:32Z logger.go:67: undefined2026-08-07T02:13:32.7787661Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-qg58m failed. Retrying in 30s.2026-08-07T02:14:02.7819821Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:14:02Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni exec test-cm-54b7d49f85-qg58m -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-sqknz",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-07T02:14:02.9424348Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-07T02:14:02Z logger.go:67: true2026-08-07T02:14:02.9488065Z TestOSPoolEP 2026-08-07T02:14:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:02.9572768Z TestOSPoolEP 2026-08-07T02:14:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.1338175Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.2724100Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.3950106Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.3989290Z === NAME TestOSPoolEP2026-08-07T02:14:03.3991259Z ospool_ep_test.go:101: ---2026-08-07T02:14:03.3992368Z Events for pod idtoken-generator:2026-08-07T02:14:03.3994374Z 2026-08-07 02:07:39 +0000 UTC Normal Successfully assigned test-ospool-ep-g3bdni/idtoken-generator to minikube2026-08-07T02:14:03.3995897Z 2026-08-07 02:07:41 +0000 UTC Normal Pulling image "htcondor/base"2026-08-07T02:14:03.3997595Z 2026-08-07 02:07:50 +0000 UTC Normal Successfully pulled image "htcondor/base" in 9.481s (9.481s including waiting). Image size: 560910730 bytes.2026-08-07T02:14:03.3998906Z 2026-08-07 02:07:50 +0000 UTC Normal Container created2026-08-07T02:14:03.4002460Z 2026-08-07 02:07:50 +0000 UTC Normal Container started2026-08-07T02:14:03.4003071Z 2026-08-07T02:14:03.4003434Z ---2026-08-07T02:14:03.4004418Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.4009872Z ospool_ep_test.go:101: ---2026-08-07T02:14:03.4012504Z Events for pod ospool-ep-6c7d796845-sqknz:2026-08-07T02:14:03.4014120Z 2026-08-07 02:08:00 +0000 UTC Normal Successfully assigned test-ospool-ep-g3bdni/ospool-ep-6c7d796845-sqknz to minikube2026-08-07T02:14:03.4015797Z 2026-08-07 02:08:01 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-07T02:14:03.4017959Z 2026-08-07 02:08:03 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.248s (2.248s including waiting). Image size: 5590942 bytes.2026-08-07T02:14:03.4019570Z 2026-08-07 02:08:03 +0000 UTC Normal Container created2026-08-07T02:14:03.4020470Z 2026-08-07 02:08:03 +0000 UTC Normal Container started2026-08-07T02:14:03.4023165Z 2026-08-07 02:08:04 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-08-07T02:14:03.4027840Z 2026-08-07 02:08:29 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 16.07s (24.769s including waiting). Image size: 1391641574 bytes.2026-08-07T02:14:03.4029376Z 2026-08-07 02:08:29 +0000 UTC Normal Container created2026-08-07T02:14:03.4030221Z 2026-08-07 02:08:29 +0000 UTC Normal Container started2026-08-07T02:14:03.4030939Z 2026-08-07T02:14:03.4031474Z ---2026-08-07T02:14:03.4032911Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.4035493Z ospool_ep_test.go:101: ---2026-08-07T02:14:03.4036630Z Events for pod test-cm-54b7d49f85-qg58m:2026-08-07T02:14:03.4038202Z 2026-08-07 02:08:00 +0000 UTC Normal Successfully assigned test-ospool-ep-g3bdni/test-cm-54b7d49f85-qg58m to minikube2026-08-07T02:14:03.4039759Z 2026-08-07 02:08:01 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-08-07T02:14:03.4042352Z 2026-08-07 02:08:13 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 9.407s (11.626s including waiting). Image size: 573055668 bytes.2026-08-07T02:14:03.4044031Z 2026-08-07 02:08:13 +0000 UTC Normal Container created2026-08-07T02:14:03.4045077Z 2026-08-07 02:08:13 +0000 UTC Normal Container started2026-08-07T02:14:03.4045781Z 2026-08-07T02:14:03.4046330Z ---2026-08-07T02:14:03.4047516Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-07T02:14:03.4078381Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni delete -f /tmp/TestOSPoolEP2550524803]2026-08-07T02:14:03.5108124Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-g3bdni namespace2026-08-07T02:14:03.5208903Z TestOSPoolEP 2026-08-07T02:14:03Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-g3bdni namespace2026-08-07T02:14:05.4267365Z TestOSPoolEP 2026-08-07T02:14:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni delete -f /tmp/TestOSPoolEP132040656]2026-08-07T02:14:05.5219263Z TestOSPoolEP 2026-08-07T02:14:05Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-g3bdni namespace2026-08-07T02:14:05.5269111Z TestOSPoolEP 2026-08-07T02:14:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-g3bdni delete -k /tmp/kustomize-template-3520741327]2026-08-07T02:14:05.6464298Z TestOSPoolEP 2026-08-07T02:14:05Z logger.go:67: service "test-cm" deleted from test-ospool-ep-g3bdni namespace2026-08-07T02:14:05.6517151Z TestOSPoolEP 2026-08-07T02:14:05Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-g3bdni namespace2026-08-07T02:14:05.6568032Z TestOSPoolEP 2026-08-07T02:14:05Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-g3bdni namespace2026-08-07T02:14:05.6673261Z --- PASS: TestOSPoolEP (54.46s)2026-08-07T02:14:05.6715144Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.04s)2026-08-07T02:14:05.6718121Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.74s)2026-08-07T02:14:05.6721387Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.74s)2026-08-07T02:14:05.6723695Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.03s)2026-08-07T02:14:05.6726204Z PASS2026-08-07T02:14:05.6726924Z ok github.com/osg-htc/k8s-integration-tests/test 386.512s2026-08-07T02:14:05.7903476Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-07T02:14:05.7904475Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-07T02:14:05.7991241Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-07T02:14:05.7991635Z env:2026-08-07T02:14:05.7991850Z GOTOOLCHAIN: local2026-08-07T02:14:05.7992770Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-07T02:14:05.7993284Z ##[endgroup]2026-08-07T02:14:05.8231672Z 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-07T02:14:05.8233912Z ##[group]Run actions/upload-artifact@v42026-08-07T02:14:05.8234368Z with:2026-08-07T02:14:05.8234936Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-08-07T02:14:05.8235737Z path: /tmp/k8s-tests2026-08-07T02:14:05.8236137Z retention-days: 52026-08-07T02:14:05.8236468Z if-no-files-found: warn2026-08-07T02:14:05.8236829Z compression-level: 62026-08-07T02:14:05.8237193Z overwrite: false2026-08-07T02:14:05.8237545Z include-hidden-files: false2026-08-07T02:14:05.8237844Z env:2026-08-07T02:14:05.8238156Z GOTOOLCHAIN: local2026-08-07T02:14:05.8238514Z ##[endgroup]2026-08-07T02:14:06.0333699Z (node:17467) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-07T02:14:06.0335478Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-07T02:14:06.0431714Z With the provided path, there will be 6 files uploaded2026-08-07T02:14:06.0433256Z Artifact name is valid!2026-08-07T02:14:06.0433848Z Root directory input is valid!2026-08-07T02:14:06.3748011Z Beginning upload of artifact content to blob storage2026-08-07T02:14:06.4106519Z (node:17467) [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-07T02:14:06.7683510Z Uploaded bytes 140152026-08-07T02:14:06.8499949Z Finished uploading artifact content to blob storage!2026-08-07T02:14:06.8501145Z SHA256 digest of uploaded artifact zip is d4d769ae6702b62b20e1001137ab34eed430a011f525283a3acb396a79e7c8932026-08-07T02:14:06.8503062Z Finalizing artifact upload2026-08-07T02:14:07.0613235Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 89796414642026-08-07T02:14:07.0615029Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14015 bytes. Artifact ID is 89796414642026-08-07T02:14:07.0620385Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/31140135689/artifacts/89796414642026-08-07T02:14:07.0795883Z Post job cleanup.2026-08-07T02:14:07.2175759Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-07T02:14:07.2228383Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-07T02:14:07.2280517Z /home/runner/go/pkg/mod2026-08-07T02:14:07.2306591Z /home/runner/.cache/go-build2026-08-07T02:14:07.2317355Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-07T02:14:07.2509777Z Post job cleanup.2026-08-07T02:14:07.3468039Z [command]/usr/bin/git version2026-08-07T02:14:07.3516134Z git version 2.54.02026-08-07T02:14:07.3591439Z Temporarily overriding HOME='/home/runner/work/_temp/f0805fa2-9d48-4d64-b8c5-ff76caf45209' before making global git config changes2026-08-07T02:14:07.3593032Z Adding repository directory to the temporary git global config as a safe directory2026-08-07T02:14:07.3600359Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-07T02:14:07.3647386Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-07T02:14:07.3690017Z [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-07T02:14:07.4073474Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-07T02:14:07.4114154Z http.https://github.com/.extraheader2026-08-07T02:14:07.4144788Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-07T02:14:07.4242683Z [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-07T02:14:07.4830416Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-07T02:14:07.4875637Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-07T02:14:07.5345064Z Cleaning up orphan processes2026-08-07T02:14:07.6108857Z ##[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/