2026-08-12T01:02:37.5436950Z Current runner version: '2.336.0'2026-08-12T01:02:37.5462356Z ##[group]Runner Image Provisioner2026-08-12T01:02:37.5463220Z Hosted Compute Agent2026-08-12T01:02:37.5463916Z Version: 20260707.5632026-08-12T01:02:37.5464568Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-12T01:02:37.5465342Z Build Date: 2026-07-07T19:33:50Z2026-08-12T01:02:37.5466065Z Worker ID: {03b2178d-8b8b-4e68-bebf-7b05230324d5}2026-08-12T01:02:37.5466785Z Azure Region: westus2026-08-12T01:02:37.5467399Z ##[endgroup]2026-08-12T01:02:37.5468920Z ##[group]Operating System2026-08-12T01:02:37.5469537Z Ubuntu2026-08-12T01:02:37.5470233Z 22.04.52026-08-12T01:02:37.5470751Z LTS2026-08-12T01:02:37.5471487Z ##[endgroup]2026-08-12T01:02:37.5472157Z ##[group]Runner Image2026-08-12T01:02:37.5472760Z Image: ubuntu-22.042026-08-12T01:02:37.5473418Z Version: 20260720.234.22026-08-12T01:02:37.5474679Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-12T01:02:37.5476257Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-12T01:02:37.5477213Z ##[endgroup]2026-08-12T01:02:37.5478492Z ##[group]GITHUB_TOKEN Permissions2026-08-12T01:02:37.5480561Z Contents: read2026-08-12T01:02:37.5481240Z Metadata: read2026-08-12T01:02:37.5482236Z Packages: read2026-08-12T01:02:37.5482829Z ##[endgroup]2026-08-12T01:02:37.5485234Z Secret source: Actions2026-08-12T01:02:37.5486331Z Prepare workflow directory2026-08-12T01:02:37.5812342Z Prepare all required actions2026-08-12T01:02:37.5866834Z Getting action download info2026-08-12T01:02:37.9363647Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-12T01:02:38.3245008Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-12T01:02:38.6654725Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-12T01:02:39.0270254Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-12T01:02:39.2654586Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-12T01:02:39.2659610Z ##[group] Inputs2026-08-12T01:02:39.2660138Z testName: TestOSPoolEP2026-08-12T01:02:39.2661004Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-12T01:02:39.2662349Z setupScript: 2026-08-12T01:02:39.2662792Z setupEnv: {}2026-08-12T01:02:39.2663221Z ##[endgroup]2026-08-12T01:02:39.2663891Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-08-12T01:02:39.3539074Z ##[group]Run actions/checkout@v52026-08-12T01:02:39.3540624Z with:2026-08-12T01:02:39.3542155Z repository: mwestphall/k8s-integration-tests2026-08-12T01:02:39.3550062Z token: ***2026-08-12T01:02:39.3550798Z ssh-strict: true2026-08-12T01:02:39.3551818Z ssh-user: git2026-08-12T01:02:39.3552573Z persist-credentials: true2026-08-12T01:02:39.3553385Z clean: true2026-08-12T01:02:39.3554115Z sparse-checkout-cone-mode: true2026-08-12T01:02:39.3554971Z fetch-depth: 12026-08-12T01:02:39.3555671Z fetch-tags: false2026-08-12T01:02:39.3556385Z show-progress: true2026-08-12T01:02:39.3557105Z lfs: false2026-08-12T01:02:39.3558393Z submodules: false2026-08-12T01:02:39.3559138Z set-safe-directory: true2026-08-12T01:02:39.3560089Z allow-unsafe-pr-checkout: false2026-08-12T01:02:39.3561640Z ##[endgroup]2026-08-12T01:02:39.4591776Z Syncing repository: mwestphall/k8s-integration-tests2026-08-12T01:02:39.4594711Z ##[group]Getting Git version info2026-08-12T01:02:39.4596242Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-12T01:02:39.4597839Z [command]/usr/bin/git version2026-08-12T01:02:39.4663729Z git version 2.54.02026-08-12T01:02:39.4687621Z ##[endgroup]2026-08-12T01:02:39.4703848Z Temporarily overriding HOME='/home/runner/work/_temp/2ab7c536-14e1-4743-b791-ae27f88e7b1f' before making global git config changes2026-08-12T01:02:39.4705624Z Adding repository directory to the temporary git global config as a safe directory2026-08-12T01:02:39.4714257Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-12T01:02:39.4752272Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-12T01:02:39.4757159Z ##[group]Initializing the repository2026-08-12T01:02:39.4763573Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-12T01:02:39.4870448Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-12T01:02:39.4872923Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-12T01:02:39.4874859Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-12T01:02:39.4876540Z hint: call:2026-08-12T01:02:39.4877291Z hint:2026-08-12T01:02:39.4878022Z hint: git config --global init.defaultBranch <name>2026-08-12T01:02:39.4878909Z hint:2026-08-12T01:02:39.4879714Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-12T01:02:39.4880879Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-12T01:02:39.4882705Z hint:2026-08-12T01:02:39.4883466Z hint: git branch -m <name>2026-08-12T01:02:39.4884285Z hint:2026-08-12T01:02:39.4885294Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-12T01:02:39.4887207Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-12T01:02:39.4900167Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-12T01:02:39.4930362Z ##[endgroup]2026-08-12T01:02:39.4931724Z ##[group]Disabling automatic garbage collection2026-08-12T01:02:39.4934492Z [command]/usr/bin/git config --local gc.auto 02026-08-12T01:02:39.4967614Z ##[endgroup]2026-08-12T01:02:39.4968655Z ##[group]Setting up auth2026-08-12T01:02:39.4975805Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-12T01:02:39.5009024Z [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-12T01:02:39.5407753Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-12T01:02:39.5448056Z [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-12T01:02:39.5717524Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-12T01:02:39.5755433Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-12T01:02:39.5998298Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-12T01:02:39.6055811Z ##[endgroup]2026-08-12T01:02:39.6064633Z ##[group]Fetching the repository2026-08-12T01:02:39.6065992Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-12T01:02:40.0362500Z From https://github.com/mwestphall/k8s-integration-tests2026-08-12T01:02:40.0363615Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-12T01:02:40.0389690Z ##[endgroup]2026-08-12T01:02:40.0391003Z ##[group]Determining the checkout info2026-08-12T01:02:40.0392779Z ##[endgroup]2026-08-12T01:02:40.0397693Z [command]/usr/bin/git sparse-checkout disable2026-08-12T01:02:40.0443765Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-12T01:02:40.0475831Z ##[group]Checking out the ref2026-08-12T01:02:40.0479660Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-12T01:02:40.0573150Z Switched to a new branch 'main'2026-08-12T01:02:40.0577334Z branch 'main' set up to track 'origin/main'.2026-08-12T01:02:40.0583147Z ##[endgroup]2026-08-12T01:02:40.0622442Z [command]/usr/bin/git log -1 --format=%H2026-08-12T01:02:40.0650820Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-12T01:02:40.0973293Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-12T01:02:40.0974903Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-12T01:02:40.0976397Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-12T01:02:40.1095487Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-12T01:02:40.1096388Z env:2026-08-12T01:02:40.1097512Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-12T01:02:40.1098794Z SETUP_ENV_YAML: {}2026-08-12T01:02:40.1099328Z ##[endgroup]2026-08-12T01:02:40.8675276Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-12T01:02:40.8678297Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-12T01:02:40.8740488Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-12T01:02:40.8742401Z env:2026-08-12T01:02:40.8744598Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-12T01:02:40.8747251Z ##[endgroup]2026-08-12T01:02:40.8944858Z ##[group]Run true2026-08-12T01:02:40.8945786Z [36;1mtrue[0m2026-08-12T01:02:40.9003939Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-12T01:02:40.9005380Z ##[endgroup]2026-08-12T01:02:40.9305682Z ##[group]Run medyagh/setup-minikube@latest2026-08-12T01:02:40.9306886Z with:2026-08-12T01:02:40.9307669Z start: true2026-08-12T01:02:40.9308474Z cache: true2026-08-12T01:02:40.9309329Z minikube-version: latest2026-08-12T01:02:40.9310297Z wait: all2026-08-12T01:02:40.9311097Z ##[endgroup]2026-08-12T01:02:41.0592106Z (node:2085) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-12T01:02:41.0606321Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-12T01:02:42.0892689Z [command]/usr/bin/chmod +x /home/runner/work/_temp/de4e00a3-d02c-4b97-90dd-d67e458530052026-08-12T01:02:42.2647634Z [command]/home/runner/bin/minikube version --short2026-08-12T01:02:42.3056071Z v1.38.12026-08-12T01:02:42.5584848Z Cache hit for: kic-v1.38.1-x642026-08-12T01:02:42.5705079Z (node:2085) [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-12T01:02:42.5741862Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-12T01:02:43.8687328Z Received 0 of 224218863 (0.0%), 0.0 MBs/sec2026-08-12T01:02:43.8860521Z Received 4194304 of 544082657 (0.8%), 4.0 MBs/sec2026-08-12T01:02:44.8693618Z Received 83886080 of 224218863 (37.4%), 40.0 MBs/sec2026-08-12T01:02:44.8914116Z Received 100663296 of 544082657 (18.5%), 47.9 MBs/sec2026-08-12T01:02:45.8692299Z Received 163577856 of 224218863 (73.0%), 52.0 MBs/sec2026-08-12T01:02:45.8916698Z Received 197132288 of 544082657 (36.2%), 62.5 MBs/sec2026-08-12T01:02:46.4866967Z Received 224218863 of 224218863 (100.0%), 59.1 MBs/sec2026-08-12T01:02:46.4869874Z Cache Size: ~214 MB (224218863 B)2026-08-12T01:02:46.4941779Z [command]/usr/bin/tar -xf /home/runner/work/_temp/2c47dee9-6406-4e1e-b1b2-d2077abb6c9e/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-12T01:02:46.8942364Z Received 327155712 of 544082657 (60.1%), 77.8 MBs/sec2026-08-12T01:02:47.0902858Z Cache restored successfully2026-08-12T01:02:47.8982438Z Received 432013312 of 544082657 (79.4%), 82.3 MBs/sec2026-08-12T01:02:48.8819526Z Received 544082657 of 544082657 (100.0%), 86.5 MBs/sec2026-08-12T01:02:48.8820451Z Cache Size: ~519 MB (544082657 B)2026-08-12T01:02:48.8934404Z [command]/usr/bin/tar -xf /home/runner/work/_temp/4d3e0e00-2da3-4066-915e-e2e7fab446b8/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-12T01:02:49.8277235Z Cache restored successfully2026-08-12T01:02:49.9421784Z [command]/home/runner/bin/minikube start --wait all2026-08-12T01:02:49.9857004Z * minikube v1.38.1 on Ubuntu 22.042026-08-12T01:02:50.4510830Z ! 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-12T01:02:50.4523861Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-12T01:02:50.5108212Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-12T01:02:50.5117630Z * Using Docker driver with root privileges2026-08-12T01:02:50.5131923Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-12T01:02:50.5143181Z * Pulling base image v0.0.50 ...2026-08-12T01:03:22.3923528Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-12T01:03:27.5568303Z * Verifying Kubernetes components...2026-08-12T01:03:27.6026606Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-12T01:03:28.3549700Z * Enabled addons: storage-provisioner, default-storageclass2026-08-12T01:03:43.1386771Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-12T01:03:43.1491181Z [command]/home/runner/bin/minikube version --short2026-08-12T01:03:43.1863865Z v1.38.12026-08-12T01:03:43.2154715Z ##[group]Run actions/setup-go@v62026-08-12T01:03:43.2155000Z with:2026-08-12T01:03:43.2155194Z go-version: 1.262026-08-12T01:03:43.2155404Z cache: true2026-08-12T01:03:43.2155617Z cache-dependency-path: go.sum2026-08-12T01:03:43.2155870Z check-latest: false2026-08-12T01:03:43.2158250Z token: ***2026-08-12T01:03:43.2158454Z ##[endgroup]2026-08-12T01:03:43.3443680Z Setup go version spec 1.262026-08-12T01:03:43.3503758Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-12T01:03:43.3505828Z Added go to the path2026-08-12T01:03:43.3511297Z Successfully set up Go version 1.262026-08-12T01:03:44.1035959Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-12T01:03:44.1070263Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-12T01:03:44.1100009Z /home/runner/go/pkg/mod2026-08-12T01:03:44.1131037Z /home/runner/.cache/go-build2026-08-12T01:03:44.3736305Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-12T01:03:45.6779998Z Received 0 of 251839808 (0.0%), 0.0 MBs/sec2026-08-12T01:03:46.6778091Z Received 92274688 of 251839808 (36.6%), 44.0 MBs/sec2026-08-12T01:03:47.6778482Z Received 130023424 of 251839808 (51.6%), 41.3 MBs/sec2026-08-12T01:03:48.6790295Z Received 130023424 of 251839808 (51.6%), 31.0 MBs/sec2026-08-12T01:03:49.6802151Z Received 130023424 of 251839808 (51.6%), 24.8 MBs/sec2026-08-12T01:03:50.6804704Z Received 130023424 of 251839808 (51.6%), 20.7 MBs/sec2026-08-12T01:03:51.6811880Z Received 239075328 of 251839808 (94.9%), 32.6 MBs/sec2026-08-12T01:03:51.8523381Z Received 251839808 of 251839808 (100.0%), 33.5 MBs/sec2026-08-12T01:03:51.8524813Z Cache Size: ~240 MB (251839808 B)2026-08-12T01:03:51.8587464Z [command]/usr/bin/tar -xf /home/runner/work/_temp/16fa5e82-7f5d-45e2-9e2b-09c6391701d5/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-12T01:03:54.9137696Z Cache restored successfully2026-08-12T01:03:54.9782607Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-12T01:03:54.9812084Z go version go1.26.5 linux/amd642026-08-12T01:03:54.9812298Z 2026-08-12T01:03:54.9812607Z ##[group]go env2026-08-12T01:03:55.0741030Z AR='ar'2026-08-12T01:03:55.0741794Z CC='gcc'2026-08-12T01:03:55.0742166Z CGO_CFLAGS='-O2 -g'2026-08-12T01:03:55.0742572Z CGO_CPPFLAGS=''2026-08-12T01:03:55.0742971Z CGO_CXXFLAGS='-O2 -g'2026-08-12T01:03:55.0743379Z CGO_ENABLED='1'2026-08-12T01:03:55.0743761Z CGO_FFLAGS='-O2 -g'2026-08-12T01:03:55.0744120Z CGO_LDFLAGS='-O2 -g'2026-08-12T01:03:55.0744473Z CXX='g++'2026-08-12T01:03:55.0744804Z GCCGO='gccgo'2026-08-12T01:03:55.0745127Z GO111MODULE=''2026-08-12T01:03:55.0745452Z GOAMD64='v1'2026-08-12T01:03:55.0745805Z GOARCH='amd64'2026-08-12T01:03:55.0746130Z GOAUTH='netrc'2026-08-12T01:03:55.0746488Z GOBIN=''2026-08-12T01:03:55.0746852Z GOCACHE='/home/runner/.cache/go-build'2026-08-12T01:03:55.0747322Z GOCACHEPROG=''2026-08-12T01:03:55.0747646Z GODEBUG=''2026-08-12T01:03:55.0748028Z GOENV='/home/runner/.config/go/env'2026-08-12T01:03:55.0748456Z GOEXE=''2026-08-12T01:03:55.0748790Z GOEXPERIMENT=''2026-08-12T01:03:55.0749135Z GOFIPS140='off'2026-08-12T01:03:55.0749469Z GOFLAGS=''2026-08-12T01:03:55.0750549Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build1915693260=/tmp/go-build -gno-record-gcc-switches'2026-08-12T01:03:55.0751925Z GOHOSTARCH='amd64'2026-08-12T01:03:55.0752278Z GOHOSTOS='linux'2026-08-12T01:03:55.0752620Z GOINSECURE=''2026-08-12T01:03:55.0753206Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-12T01:03:55.0753918Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-12T01:03:55.0754370Z GONOPROXY=''2026-08-12T01:03:55.0754689Z GONOSUMDB=''2026-08-12T01:03:55.0755030Z GOOS='linux'2026-08-12T01:03:55.0755366Z GOPATH='/home/runner/go'2026-08-12T01:03:55.0756107Z GOPRIVATE=''2026-08-12T01:03:55.0756579Z GOPROXY='https://proxy.golang.org,direct'2026-08-12T01:03:55.0757131Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-12T01:03:55.0757640Z GOSUMDB='sum.golang.org'2026-08-12T01:03:55.0758023Z GOTELEMETRY='local'2026-08-12T01:03:55.0758478Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-12T01:03:55.0758977Z GOTMPDIR=''2026-08-12T01:03:55.0759306Z GOTOOLCHAIN='local'2026-08-12T01:03:55.0759862Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-12T01:03:55.0760433Z GOVCS=''2026-08-12T01:03:55.0760755Z GOVERSION='go1.26.5'2026-08-12T01:03:55.0761111Z GOWORK=''2026-08-12T01:03:55.0761771Z PKG_CONFIG='pkg-config'2026-08-12T01:03:55.0762012Z 2026-08-12T01:03:55.0762518Z ##[endgroup]2026-08-12T01:03:55.0933065Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-12T01:03:55.0933586Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-12T01:03:55.0989150Z shell: /usr/bin/bash -e {0}2026-08-12T01:03:55.0989414Z env:2026-08-12T01:03:55.0989741Z GOTOOLCHAIN: local2026-08-12T01:03:55.0990102Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-08-12T01:03:55.0990387Z OSPOOL_EP_CMTag: 25.x-el92026-08-12T01:03:55.0990641Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-12T01:03:55.0991023Z ##[endgroup]2026-08-12T01:04:01.9616818Z === RUN TestOSPoolEP2026-08-12T01:04:01.9617468Z === PAUSE TestOSPoolEP2026-08-12T01:04:01.9617912Z === CONT TestOSPoolEP2026-08-12T01:04:01.9623286Z TestOSPoolEP 2026-08-12T01:04:01Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:01.9713820Z TestOSPoolEP 2026-08-12T01:04:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx apply -f /tmp/TestOSPoolEP2752897876]2026-08-12T01:04:02.5230052Z TestOSPoolEP 2026-08-12T01:04:02Z logger.go:67: pod/idtoken-generator created2026-08-12T01:04:02.5292481Z TestOSPoolEP 2026-08-12T01:04:02Z logger.go:67: secret/pool-passwd created2026-08-12T01:04:02.5349340Z TestOSPoolEP 2026-08-12T01:04:02Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-12T01:04:02.5360544Z TestOSPoolEP 2026-08-12T01:04:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:02.5391454Z TestOSPoolEP 2026-08-12T01:04:02Z 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-12T01:04:12.5407529Z TestOSPoolEP 2026-08-12T01:04:12Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-12T01:04:12.5409767Z TestOSPoolEP 2026-08-12T01:04:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:12.5437128Z TestOSPoolEP 2026-08-12T01:04:12Z 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-12T01:04:22.5440769Z TestOSPoolEP 2026-08-12T01:04:22Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-12T01:04:22.5442795Z TestOSPoolEP 2026-08-12T01:04:22Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:22.5465722Z TestOSPoolEP 2026-08-12T01:04:22Z logger.go:67: Pod is now available2026-08-12T01:04:22.5467623Z TestOSPoolEP 2026-08-12T01:04:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-12T01:04:23.0954691Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: ***2026-08-12T01:04:23.1010608Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx apply -f /tmp/TestOSPoolEP828847806]2026-08-12T01:04:23.2786568Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: secret/pool-token created2026-08-12T01:04:23.2817669Z TestOSPoolEP 2026-08-12T01:04:23Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-12T01:04:23.2819158Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:23.2837816Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: Secret is now available2026-08-12T01:04:23.2865134Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx apply -k /tmp/kustomize-template-3999549020]2026-08-12T01:04:23.5583932Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: service/test-cm created2026-08-12T01:04:23.5673047Z TestOSPoolEP 2026-08-12T01:04:23Z 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-12T01:04:23.5675515Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: deployment.apps/ospool-ep created2026-08-12T01:04:23.5769615Z TestOSPoolEP 2026-08-12T01:04:23Z logger.go:67: deployment.apps/test-cm created2026-08-12T01:04:23.5810498Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-12T01:04:23.5813281Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:23.5874140Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-12T01:04:23.5896398Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:23.5899313Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-12T01:04:23.5902850Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:23.5954796Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "test-cm-5bb545475c". Sleeping for 10s and will try again.2026-08-12T01:04:23.5994427Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:23Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "ospool-ep-65c844ddbb". Sleeping for 10s and will try again.2026-08-12T01:04:33.5957943Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:33Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-12T01:04:33.5966834Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:33Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:33.5994035Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:33Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-12T01:04:33.6020947Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:33Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:33.6023237Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:33Z logger.go:67: Deployment is now available2026-08-12T01:04:33.6039503Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:33Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-65c844ddbb" is progressing.. Sleeping for 10s and will try again.2026-08-12T01:04:43.6044406Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:43Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-12T01:04:43.6059528Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:43.6088441Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:43Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-65c844ddbb" is progressing.. Sleeping for 10s and will try again.2026-08-12T01:04:53.6096950Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:53Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-12T01:04:53.6099844Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6150157Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-12T01:04:53Z logger.go:67: Deployment is now available2026-08-12T01:04:53.6151832Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-12T01:04:53.6152790Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-12T01:04:53.6153747Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-12T01:04:53.6154819Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-12T01:04:53.6175437Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-12T01:04:53.6200036Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-12T01:04:53.6213988Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-12T01:04:53.6218696Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6222705Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-12T01:04:53.6224264Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6225569Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-12T01:04:53.6226832Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6232137Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6234808Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6237048Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:04:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:04:53.6239981Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-12T01:04:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_SINGULARITY]2026-08-12T01:04:53.6243681Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:04:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:04:53.6247104Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:04:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)']2026-08-12T01:04:53.9287713Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 10s.2026-08-12T01:04:53.9393877Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-12T01:04:53.9396257Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)']' in pod test-cm-5bb545475c-glfbz failed. Retrying in 10s.2026-08-12T01:04:53.9412946Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-12T01:04:53.9414415Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:05:03.9308165Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-12T01:05:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_SINGULARITY]2026-08-12T01:05:03.9399767Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)']2026-08-12T01:05:04.1478456Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-12T01:05:04.1480763Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 10s.2026-08-12T01:05:04.1482456Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-12T01:05:04.1484300Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)']' in pod test-cm-5bb545475c-glfbz failed. Retrying in 10s.2026-08-12T01:05:14.1487603Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)']2026-08-12T01:05:14.1493722Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-12T01:05:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_SINGULARITY]2026-08-12T01:05:14.3147943Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-12T01:05:14.3149581Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: 2026-08-12T01:05:14.3151180Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-7bbbs LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-08-12T01:05:14.3152919Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: 2026-08-12T01:05:14.3154203Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-12T01:05:14.3156269Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: 2026-08-12T01:05:14.3158147Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-12T01:05:14.3159616Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: 2026-08-12T01:05:14.3161123Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-12T01:05:14Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-12T01:05:14.3376241Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-12T01:05:14Z logger.go:67: true2026-08-12T01:05:23.9425521Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:05:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:05:24.0818764Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:05:24Z logger.go:67: undefined2026-08-12T01:05:24.0871551Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-12T01:05:24.0873123Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:05:54.0875630Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:05:54Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:05:54.2328671Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:05:54Z logger.go:67: undefined2026-08-12T01:05:54.2380726Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:06:24.2391214Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:06:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:06:24.3937217Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:06:24Z logger.go:67: undefined2026-08-12T01:06:24.3985383Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:06:54.3993201Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:06:54Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:06:54.5848569Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:06:54Z logger.go:67: undefined2026-08-12T01:06:54.5887826Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:07:24.5910179Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:07:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:07:24.7424174Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:07:24Z logger.go:67: undefined2026-08-12T01:07:24.7479735Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:07:54.7500267Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:07:54Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:07:54.9054110Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:07:54Z logger.go:67: undefined2026-08-12T01:07:54.9112926Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:08:24.9126080Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:08:24Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:08:25.0896499Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:08:25Z logger.go:67: undefined2026-08-12T01:08:25.0962448Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:08:55.0965734Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:08:55Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:08:55.2399948Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:08:55Z logger.go:67: undefined2026-08-12T01:08:55.2457289Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:09:25.2469987Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:09:25Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:09:25.4113032Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:09:25Z logger.go:67: undefined2026-08-12T01:09:25.4176678Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:09:55.4211917Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:09:55Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:09:55.5707016Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:09:55Z logger.go:67: undefined2026-08-12T01:09:55.5761204Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-glfbz failed. Retrying in 30s.2026-08-12T01:10:25.5787203Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:10:25Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx exec test-cm-5bb545475c-glfbz -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-7bbbs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-12T01:10:25.7229437Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-12T01:10:25Z logger.go:67: true2026-08-12T01:10:25.7295295Z TestOSPoolEP 2026-08-12T01:10:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:25.7361708Z TestOSPoolEP 2026-08-12T01:10:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:25.9492497Z TestOSPoolEP 2026-08-12T01:10:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:26.1016554Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:26.2338348Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:26.2367284Z === NAME TestOSPoolEP2026-08-12T01:10:26.2368240Z ospool_ep_test.go:101: ---2026-08-12T01:10:26.2369080Z Events for pod idtoken-generator:2026-08-12T01:10:26.2370347Z 2026-08-12 01:04:02 +0000 UTC Normal Successfully assigned test-ospool-ep-ttrghx/idtoken-generator to minikube2026-08-12T01:10:26.2371797Z 2026-08-12 01:04:05 +0000 UTC Normal Pulling image "htcondor/base"2026-08-12T01:10:26.2373637Z 2026-08-12 01:04:14 +0000 UTC Normal Successfully pulled image "htcondor/base" in 9.042s (9.042s including waiting). Image size: 560910730 bytes.2026-08-12T01:10:26.2375133Z 2026-08-12 01:04:14 +0000 UTC Normal Container created2026-08-12T01:10:26.2376023Z 2026-08-12 01:04:14 +0000 UTC Normal Container started2026-08-12T01:10:26.2376585Z 2026-08-12T01:10:26.2376933Z ---2026-08-12T01:10:26.2377867Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:26.2394885Z ospool_ep_test.go:101: ---2026-08-12T01:10:26.2395706Z Events for pod ospool-ep-65c844ddbb-7bbbs:2026-08-12T01:10:26.2397632Z 2026-08-12 01:04:23 +0000 UTC Normal Successfully assigned test-ospool-ep-ttrghx/ospool-ep-65c844ddbb-7bbbs to minikube2026-08-12T01:10:26.2398818Z 2026-08-12 01:04:24 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-12T01:10:26.2400426Z 2026-08-12 01:04:26 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.69s (2.69s including waiting). Image size: 5590942 bytes.2026-08-12T01:10:26.2402220Z 2026-08-12 01:04:26 +0000 UTC Normal Container created2026-08-12T01:10:26.2402740Z 2026-08-12 01:04:26 +0000 UTC Normal Container started2026-08-12T01:10:26.2403684Z 2026-08-12 01:04:27 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-08-12T01:10:26.2405289Z 2026-08-12 01:04:44 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 16.208s (17.032s including waiting). Image size: 1273054585 bytes.2026-08-12T01:10:26.2406807Z 2026-08-12 01:04:44 +0000 UTC Normal Container created2026-08-12T01:10:26.2407444Z 2026-08-12 01:04:44 +0000 UTC Normal Container started2026-08-12T01:10:26.2407762Z 2026-08-12T01:10:26.2408063Z ---2026-08-12T01:10:26.2408747Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:26.2419598Z ospool_ep_test.go:101: ---2026-08-12T01:10:26.2420786Z Events for pod test-cm-5bb545475c-glfbz:2026-08-12T01:10:26.2423270Z 2026-08-12 01:04:23 +0000 UTC Normal Successfully assigned test-ospool-ep-ttrghx/test-cm-5bb545475c-glfbz to minikube2026-08-12T01:10:26.2426169Z 2026-08-12 01:04:24 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-08-12T01:10:26.2429271Z 2026-08-12 01:04:27 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 1.128s (3.792s including waiting). Image size: 560910756 bytes.2026-08-12T01:10:26.2431153Z 2026-08-12 01:04:27 +0000 UTC Normal Container created2026-08-12T01:10:26.2433225Z 2026-08-12 01:04:27 +0000 UTC Normal Container started2026-08-12T01:10:26.2434213Z 2026-08-12T01:10:26.2434743Z ---2026-08-12T01:10:26.2436492Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-12T01:10:26.2468171Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx delete -f /tmp/TestOSPoolEP1085943197]2026-08-12T01:10:26.3584176Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-ttrghx namespace2026-08-12T01:10:26.3630844Z TestOSPoolEP 2026-08-12T01:10:26Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-ttrghx namespace2026-08-12T01:10:28.0745584Z TestOSPoolEP 2026-08-12T01:10:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx delete -f /tmp/TestOSPoolEP3098872930]2026-08-12T01:10:28.1635301Z TestOSPoolEP 2026-08-12T01:10:28Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-ttrghx namespace2026-08-12T01:10:28.1680713Z TestOSPoolEP 2026-08-12T01:10:28Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ttrghx delete -k /tmp/kustomize-template-3999549020]2026-08-12T01:10:28.2799291Z TestOSPoolEP 2026-08-12T01:10:28Z logger.go:67: service "test-cm" deleted from test-ospool-ep-ttrghx namespace2026-08-12T01:10:28.2838296Z TestOSPoolEP 2026-08-12T01:10:28Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-ttrghx namespace2026-08-12T01:10:28.2876859Z TestOSPoolEP 2026-08-12T01:10:28Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-ttrghx namespace2026-08-12T01:10:28.2977563Z --- PASS: TestOSPoolEP (54.22s)2026-08-12T01:10:28.2997182Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-08-12T01:10:28.2998997Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.71s)2026-08-12T01:10:28.3000932Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.73s)2026-08-12T01:10:28.3002339Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.12s)2026-08-12T01:10:28.3003645Z PASS2026-08-12T01:10:28.3020716Z ok github.com/osg-htc/k8s-integration-tests/test 386.351s2026-08-12T01:10:28.4248311Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-12T01:10:28.4249544Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-12T01:10:28.4344768Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-12T01:10:28.4345144Z env:2026-08-12T01:10:28.4345352Z GOTOOLCHAIN: local2026-08-12T01:10:28.4345977Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-12T01:10:28.4346514Z ##[endgroup]2026-08-12T01:10:28.4564894Z 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-12T01:10:28.4566476Z ##[group]Run actions/upload-artifact@v42026-08-12T01:10:28.4566756Z with:2026-08-12T01:10:28.4567249Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-08-12T01:10:28.4567765Z path: /tmp/k8s-tests2026-08-12T01:10:28.4567998Z retention-days: 52026-08-12T01:10:28.4568215Z if-no-files-found: warn2026-08-12T01:10:28.4568619Z compression-level: 62026-08-12T01:10:28.4568849Z overwrite: false2026-08-12T01:10:28.4569071Z include-hidden-files: false2026-08-12T01:10:28.4569314Z env:2026-08-12T01:10:28.4569579Z GOTOOLCHAIN: local2026-08-12T01:10:28.4569890Z ##[endgroup]2026-08-12T01:10:28.6655783Z (node:18154) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-12T01:10:28.6657272Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-12T01:10:28.6823210Z With the provided path, there will be 6 files uploaded2026-08-12T01:10:28.6842215Z Artifact name is valid!2026-08-12T01:10:28.6843004Z Root directory input is valid!2026-08-12T01:10:29.0491751Z Beginning upload of artifact content to blob storage2026-08-12T01:10:29.0918222Z (node:18154) [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-12T01:10:29.4458605Z Uploaded bytes 141202026-08-12T01:10:29.5260589Z Finished uploading artifact content to blob storage!2026-08-12T01:10:29.5261803Z SHA256 digest of uploaded artifact zip is 175815bf5c162e91c4f09965c59c2956029d602b1222347217af0d9154e429442026-08-12T01:10:29.5263955Z Finalizing artifact upload2026-08-12T01:10:29.7378432Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 91248382812026-08-12T01:10:29.7380974Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 14120 bytes. Artifact ID is 91248382812026-08-12T01:10:29.7387068Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/31552283944/artifacts/91248382812026-08-12T01:10:29.7565812Z Post job cleanup.2026-08-12T01:10:29.8846184Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-12T01:10:29.8894913Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-12T01:10:29.8932127Z /home/runner/go/pkg/mod2026-08-12T01:10:29.8962277Z /home/runner/.cache/go-build2026-08-12T01:10:29.8971062Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-12T01:10:29.9142518Z Post job cleanup.2026-08-12T01:10:30.0172385Z [command]/usr/bin/git version2026-08-12T01:10:30.0224071Z git version 2.54.02026-08-12T01:10:30.0278199Z Temporarily overriding HOME='/home/runner/work/_temp/bb89b1de-cd22-49f5-836b-7411c122f5be' before making global git config changes2026-08-12T01:10:30.0280152Z Adding repository directory to the temporary git global config as a safe directory2026-08-12T01:10:30.0288388Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-12T01:10:30.0349959Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-12T01:10:30.0394001Z [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-12T01:10:30.0678282Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-12T01:10:30.0722288Z http.https://github.com/.extraheader2026-08-12T01:10:30.0723805Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-12T01:10:30.0759201Z [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-12T01:10:30.1095561Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-12T01:10:30.1142965Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-12T01:10:30.1554998Z Cleaning up orphan processes2026-08-12T01:10:30.2278509Z ##[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/