2026-08-03T01:50:17.5395805Z Current runner version: '2.336.0'2026-08-03T01:50:17.5424008Z ##[group]Runner Image Provisioner2026-08-03T01:50:17.5424973Z Hosted Compute Agent2026-08-03T01:50:17.5425799Z Version: 20260707.5632026-08-03T01:50:17.5426584Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-03T01:50:17.5427689Z Build Date: 2026-07-07T19:33:50Z2026-08-03T01:50:17.5428556Z Worker ID: {6e78a304-48a6-4c7c-8c43-a9e1402e967a}2026-08-03T01:50:17.5429409Z Azure Region: westus32026-08-03T01:50:17.5430247Z ##[endgroup]2026-08-03T01:50:17.5431954Z ##[group]Operating System2026-08-03T01:50:17.5432692Z Ubuntu2026-08-03T01:50:17.5433510Z 22.04.52026-08-03T01:50:17.5434142Z LTS2026-08-03T01:50:17.5434877Z ##[endgroup]2026-08-03T01:50:17.5435637Z ##[group]Runner Image2026-08-03T01:50:17.5436383Z Image: ubuntu-22.042026-08-03T01:50:17.5437427Z Version: 20260720.234.22026-08-03T01:50:17.5439017Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-03T01:50:17.5441113Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-03T01:50:17.5442322Z ##[endgroup]2026-08-03T01:50:17.5443909Z ##[group]GITHUB_TOKEN Permissions2026-08-03T01:50:17.5446335Z Contents: read2026-08-03T01:50:17.5447185Z Metadata: read2026-08-03T01:50:17.5448119Z Packages: read2026-08-03T01:50:17.5448730Z ##[endgroup]2026-08-03T01:50:17.5451557Z Secret source: Actions2026-08-03T01:50:17.5452865Z Prepare workflow directory2026-08-03T01:50:17.5823566Z Prepare all required actions2026-08-03T01:50:17.5861405Z Getting action download info2026-08-03T01:50:17.8578241Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-03T01:50:18.4573928Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-03T01:50:18.9940549Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-03T01:50:19.5407830Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-03T01:50:19.8221916Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-03T01:50:19.8225521Z ##[group] Inputs2026-08-03T01:50:19.8225799Z testName: TestOSPoolEP2026-08-03T01:50:19.8226231Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:50:19.8226670Z setupScript: 2026-08-03T01:50:19.8226878Z setupEnv: {}2026-08-03T01:50:19.8227088Z ##[endgroup]2026-08-03T01:50:19.8227567Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-08-03T01:50:19.8748246Z ##[group]Run actions/checkout@v52026-08-03T01:50:19.8748755Z with:2026-08-03T01:50:19.8749012Z repository: mwestphall/k8s-integration-tests2026-08-03T01:50:19.8751281Z token: ***2026-08-03T01:50:19.8751495Z ssh-strict: true2026-08-03T01:50:19.8751745Z ssh-user: git2026-08-03T01:50:19.8751961Z persist-credentials: true2026-08-03T01:50:19.8752190Z clean: true2026-08-03T01:50:19.8752401Z sparse-checkout-cone-mode: true2026-08-03T01:50:19.8752650Z fetch-depth: 12026-08-03T01:50:19.8752854Z fetch-tags: false2026-08-03T01:50:19.8753071Z show-progress: true2026-08-03T01:50:19.8753283Z lfs: false2026-08-03T01:50:19.8753475Z submodules: false2026-08-03T01:50:19.8753685Z set-safe-directory: true2026-08-03T01:50:19.8753928Z allow-unsafe-pr-checkout: false2026-08-03T01:50:19.8754310Z ##[endgroup]2026-08-03T01:50:19.9570939Z Syncing repository: mwestphall/k8s-integration-tests2026-08-03T01:50:19.9572140Z ##[group]Getting Git version info2026-08-03T01:50:19.9572633Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-03T01:50:19.9573297Z [command]/usr/bin/git version2026-08-03T01:50:19.9598727Z git version 2.54.02026-08-03T01:50:19.9646214Z ##[endgroup]2026-08-03T01:50:19.9663928Z Temporarily overriding HOME='/home/runner/work/_temp/6f2679a4-52bf-4b04-96b7-1ed3603be0d5' before making global git config changes2026-08-03T01:50:19.9665540Z Adding repository directory to the temporary git global config as a safe directory2026-08-03T01:50:19.9678330Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-03T01:50:19.9703224Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-03T01:50:19.9707471Z ##[group]Initializing the repository2026-08-03T01:50:19.9711555Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-03T01:50:19.9792129Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-03T01:50:19.9792883Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-03T01:50:19.9794744Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-03T01:50:19.9795439Z hint: call:2026-08-03T01:50:19.9795774Z hint:2026-08-03T01:50:19.9796174Z hint: git config --global init.defaultBranch <name>2026-08-03T01:50:19.9796551Z hint:2026-08-03T01:50:19.9796909Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-03T01:50:19.9797874Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-03T01:50:19.9798338Z hint:2026-08-03T01:50:19.9798555Z hint: git branch -m <name>2026-08-03T01:50:19.9798778Z hint:2026-08-03T01:50:19.9799075Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-03T01:50:19.9799980Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-03T01:50:19.9808288Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-03T01:50:19.9835875Z ##[endgroup]2026-08-03T01:50:19.9836288Z ##[group]Disabling automatic garbage collection2026-08-03T01:50:19.9849189Z [command]/usr/bin/git config --local gc.auto 02026-08-03T01:50:19.9879593Z ##[endgroup]2026-08-03T01:50:19.9880541Z ##[group]Setting up auth2026-08-03T01:50:19.9885505Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-03T01:50:19.9915901Z [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-03T01:50:20.0213501Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-03T01:50:20.0246674Z [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-03T01:50:20.0464833Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-03T01:50:20.0495339Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-03T01:50:20.0697539Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-03T01:50:20.0745452Z ##[endgroup]2026-08-03T01:50:20.0746040Z ##[group]Fetching the repository2026-08-03T01:50:20.0752513Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-03T01:50:20.5585854Z From https://github.com/mwestphall/k8s-integration-tests2026-08-03T01:50:20.5586497Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-03T01:50:20.5609066Z ##[endgroup]2026-08-03T01:50:20.5609577Z ##[group]Determining the checkout info2026-08-03T01:50:20.5611936Z ##[endgroup]2026-08-03T01:50:20.5616777Z [command]/usr/bin/git sparse-checkout disable2026-08-03T01:50:20.5653304Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-03T01:50:20.5680302Z ##[group]Checking out the ref2026-08-03T01:50:20.5683985Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-03T01:50:20.5778462Z Switched to a new branch 'main'2026-08-03T01:50:20.5781220Z branch 'main' set up to track 'origin/main'.2026-08-03T01:50:20.5787135Z ##[endgroup]2026-08-03T01:50:20.5832500Z [command]/usr/bin/git log -1 --format=%H2026-08-03T01:50:20.5857840Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-03T01:50:20.6085766Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-03T01:50:20.6086630Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-03T01:50:20.6147970Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-03T01:50:20.6281715Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:50:20.6282191Z env:2026-08-03T01:50:20.6282745Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:50:20.6283388Z SETUP_ENV_YAML: {}2026-08-03T01:50:20.6283663Z ##[endgroup]2026-08-03T01:50:20.7218124Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-03T01:50:20.7218649Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-03T01:50:20.7266688Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:50:20.7266963Z env:2026-08-03T01:50:20.7267521Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-03T01:50:20.7267940Z ##[endgroup]2026-08-03T01:50:20.7375117Z ##[group]Run true2026-08-03T01:50:20.7375314Z [36;1mtrue[0m2026-08-03T01:50:20.7417109Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:50:20.7417535Z ##[endgroup]2026-08-03T01:50:20.7539700Z ##[group]Run medyagh/setup-minikube@latest2026-08-03T01:50:20.7539936Z with:2026-08-03T01:50:20.7540090Z start: true2026-08-03T01:50:20.7540250Z cache: true2026-08-03T01:50:20.7540412Z minikube-version: latest2026-08-03T01:50:20.7540600Z wait: all2026-08-03T01:50:20.7540748Z ##[endgroup]2026-08-03T01:50:20.8554675Z (node:1917) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-03T01:50:20.8555668Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-03T01:50:21.8036823Z [command]/usr/bin/chmod +x /home/runner/work/_temp/b25401d6-daa0-48ad-a3f4-a734c93d9c3a2026-08-03T01:50:22.6377132Z [command]/home/runner/bin/minikube version --short2026-08-03T01:50:23.0304114Z v1.38.12026-08-03T01:50:23.2262596Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-03T01:50:23.2345320Z (node:1917) [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-03T01:50:23.2537625Z Cache hit for: kic-v1.38.1-x642026-08-03T01:50:24.4960603Z Received 29360128 of 224218863 (13.1%), 28.0 MBs/sec2026-08-03T01:50:24.5174244Z Received 33554432 of 544082657 (6.2%), 32.0 MBs/sec2026-08-03T01:50:25.5581558Z Received 134217728 of 224218863 (59.9%), 62.0 MBs/sec2026-08-03T01:50:25.5582115Z Received 171966464 of 544082657 (31.6%), 80.3 MBs/sec2026-08-03T01:50:26.3296098Z Received 224218863 of 224218863 (100.0%), 75.4 MBs/sec2026-08-03T01:50:26.3296583Z Cache Size: ~214 MB (224218863 B)2026-08-03T01:50:26.3661462Z [command]/usr/bin/tar -xf /home/runner/work/_temp/1acaa07b-20c9-4d1b-b916-9112df69f8e7/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-03T01:50:26.5673846Z Received 327155712 of 544082657 (60.1%), 102.3 MBs/sec2026-08-03T01:50:26.8010274Z Cache restored successfully2026-08-03T01:50:27.5677532Z Received 461373440 of 544082657 (84.8%), 108.6 MBs/sec2026-08-03T01:50:28.3866049Z Received 544082657 of 544082657 (100.0%), 106.5 MBs/sec2026-08-03T01:50:28.3867385Z Cache Size: ~519 MB (544082657 B)2026-08-03T01:50:28.3966329Z [command]/usr/bin/tar -xf /home/runner/work/_temp/0e2c2b9f-951d-47a3-b595-2f972534beb1/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-03T01:50:28.9955505Z Cache restored successfully2026-08-03T01:50:29.0885753Z [command]/home/runner/bin/minikube start --wait all2026-08-03T01:50:29.1208528Z * minikube v1.38.1 on Ubuntu 22.042026-08-03T01:50:29.2602169Z ! 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-03T01:50:29.8470000Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-03T01:50:29.8865794Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-03T01:50:29.8873387Z * Using Docker driver with root privileges2026-08-03T01:50:29.8882509Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-03T01:50:29.8888913Z * Pulling base image v0.0.50 ...2026-08-03T01:51:04.1435190Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-03T01:51:08.7934133Z * Verifying Kubernetes components...2026-08-03T01:51:08.8172985Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-03T01:51:09.2294389Z * Enabled addons: storage-provisioner, default-storageclass2026-08-03T01:51:48.6684487Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-03T01:51:48.7208530Z [command]/home/runner/bin/minikube version --short2026-08-03T01:51:48.7535886Z v1.38.12026-08-03T01:51:48.7781235Z ##[group]Run actions/setup-go@v62026-08-03T01:51:48.7781470Z with:2026-08-03T01:51:48.7781632Z go-version: 1.262026-08-03T01:51:48.7781805Z cache: true2026-08-03T01:51:48.7781987Z cache-dependency-path: go.sum2026-08-03T01:51:48.7782194Z check-latest: false2026-08-03T01:51:48.7784223Z token: ***2026-08-03T01:51:48.7784390Z ##[endgroup]2026-08-03T01:51:48.8821535Z Setup go version spec 1.262026-08-03T01:51:48.9351668Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-03T01:51:48.9353919Z Added go to the path2026-08-03T01:51:48.9357017Z Successfully set up Go version 1.262026-08-03T01:51:49.0850404Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-03T01:51:49.0878098Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-03T01:51:49.0901090Z /home/runner/go/pkg/mod2026-08-03T01:51:49.0920632Z /home/runner/.cache/go-build2026-08-03T01:51:49.3108522Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-03T01:51:50.5222306Z Received 29360128 of 251839808 (11.7%), 28.0 MBs/sec2026-08-03T01:51:51.5238819Z Received 163577856 of 251839808 (65.0%), 78.0 MBs/sec2026-08-03T01:51:51.9917524Z Received 251839808 of 251839808 (100.0%), 97.2 MBs/sec2026-08-03T01:51:51.9918552Z Cache Size: ~240 MB (251839808 B)2026-08-03T01:51:51.9970274Z [command]/usr/bin/tar -xf /home/runner/work/_temp/5ea637ef-79af-4c6c-8679-e0466d4b4a9f/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-03T01:51:54.2440517Z Cache restored successfully2026-08-03T01:51:54.2885383Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-03T01:51:54.2908456Z go version go1.26.5 linux/amd642026-08-03T01:51:54.2908700Z 2026-08-03T01:51:54.2909159Z ##[group]go env2026-08-03T01:51:54.3145637Z AR='ar'2026-08-03T01:51:54.3145946Z CC='gcc'2026-08-03T01:51:54.3146254Z CGO_CFLAGS='-O2 -g'2026-08-03T01:51:54.3146879Z CGO_CPPFLAGS=''2026-08-03T01:51:54.3147171Z CGO_CXXFLAGS='-O2 -g'2026-08-03T01:51:54.3147604Z CGO_ENABLED='1'2026-08-03T01:51:54.3147867Z CGO_FFLAGS='-O2 -g'2026-08-03T01:51:54.3148139Z CGO_LDFLAGS='-O2 -g'2026-08-03T01:51:54.3148397Z CXX='g++'2026-08-03T01:51:54.3148636Z GCCGO='gccgo'2026-08-03T01:51:54.3148877Z GO111MODULE=''2026-08-03T01:51:54.3149120Z GOAMD64='v1'2026-08-03T01:51:54.3149377Z GOARCH='amd64'2026-08-03T01:51:54.3149617Z GOAUTH='netrc'2026-08-03T01:51:54.3149855Z GOBIN=''2026-08-03T01:51:54.3150118Z GOCACHE='/home/runner/.cache/go-build'2026-08-03T01:51:54.3150446Z GOCACHEPROG=''2026-08-03T01:51:54.3150692Z GODEBUG=''2026-08-03T01:51:54.3150982Z GOENV='/home/runner/.config/go/env'2026-08-03T01:51:54.3151305Z GOEXE=''2026-08-03T01:51:54.3151535Z GOEXPERIMENT=''2026-08-03T01:51:54.3151800Z GOFIPS140='off'2026-08-03T01:51:54.3152031Z GOFLAGS=''2026-08-03T01:51:54.3152867Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build2459678162=/tmp/go-build -gno-record-gcc-switches'2026-08-03T01:51:54.3153716Z GOHOSTARCH='amd64'2026-08-03T01:51:54.3153983Z GOHOSTOS='linux'2026-08-03T01:51:54.3154246Z GOINSECURE=''2026-08-03T01:51:54.3154675Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-03T01:51:54.3155186Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-03T01:51:54.3155525Z GONOPROXY=''2026-08-03T01:51:54.3155763Z GONOSUMDB=''2026-08-03T01:51:54.3155994Z GOOS='linux'2026-08-03T01:51:54.3156244Z GOPATH='/home/runner/go'2026-08-03T01:51:54.3156530Z GOPRIVATE=''2026-08-03T01:51:54.3156866Z GOPROXY='https://proxy.golang.org,direct'2026-08-03T01:51:54.3157404Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-03T01:51:54.3157764Z GOSUMDB='sum.golang.org'2026-08-03T01:51:54.3158060Z GOTELEMETRY='local'2026-08-03T01:51:54.3158395Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-03T01:51:54.3158762Z GOTMPDIR=''2026-08-03T01:51:54.3159217Z GOTOOLCHAIN='local'2026-08-03T01:51:54.3159613Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-03T01:51:54.3160051Z GOVCS=''2026-08-03T01:51:54.3160281Z GOVERSION='go1.26.5'2026-08-03T01:51:54.3160535Z GOWORK=''2026-08-03T01:51:54.3160776Z PKG_CONFIG='pkg-config'2026-08-03T01:51:54.3160956Z 2026-08-03T01:51:54.3161370Z ##[endgroup]2026-08-03T01:51:54.3328530Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-03T01:51:54.3328933Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-03T01:51:54.3376339Z shell: /usr/bin/bash -e {0}2026-08-03T01:51:54.3376569Z env:2026-08-03T01:51:54.3376742Z GOTOOLCHAIN: local2026-08-03T01:51:54.3376944Z OSPOOL_EP_OSPoolEPTag: 25-release2026-08-03T01:51:54.3377172Z OSPOOL_EP_CMTag: 25.0-el92026-08-03T01:51:54.3377489Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:51:54.3377703Z ##[endgroup]2026-08-03T01:51:59.2692641Z === RUN TestOSPoolEP2026-08-03T01:51:59.2694777Z === PAUSE TestOSPoolEP2026-08-03T01:51:59.2695167Z === CONT TestOSPoolEP2026-08-03T01:51:59.2695995Z TestOSPoolEP 2026-08-03T01:51:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:51:59.2763700Z TestOSPoolEP 2026-08-03T01:51:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej apply -f /tmp/TestOSPoolEP2697658267]2026-08-03T01:51:59.5341373Z TestOSPoolEP 2026-08-03T01:51:59Z logger.go:67: pod/idtoken-generator created2026-08-03T01:51:59.5438795Z TestOSPoolEP 2026-08-03T01:51:59Z logger.go:67: secret/pool-passwd created2026-08-03T01:51:59.5485723Z TestOSPoolEP 2026-08-03T01:51:59Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-03T01:51:59.5486992Z TestOSPoolEP 2026-08-03T01:51:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:51:59.5524076Z TestOSPoolEP 2026-08-03T01:51:59Z 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-03T01:52:09.5531594Z TestOSPoolEP 2026-08-03T01:52:09Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-03T01:52:09.5533833Z TestOSPoolEP 2026-08-03T01:52:09Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:09.5567564Z TestOSPoolEP 2026-08-03T01:52:09Z 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-03T01:52:19.5581391Z TestOSPoolEP 2026-08-03T01:52:19Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-03T01:52:19.5582393Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:19.5603679Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Pod is now available2026-08-03T01:52:19.5604813Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-03T01:52:19.6767124Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: ***2026-08-03T01:52:19.6828247Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej apply -f /tmp/TestOSPoolEP425775188]2026-08-03T01:52:19.8160268Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: secret/pool-token created2026-08-03T01:52:19.8185426Z TestOSPoolEP 2026-08-03T01:52:19Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-03T01:52:19.8186178Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:19.8200758Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Secret is now available2026-08-03T01:52:19.8222765Z TestOSPoolEP 2026-08-03T01:52:19Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej apply -k /tmp/kustomize-template-394735525]2026-08-03T01:52:20.0243304Z TestOSPoolEP 2026-08-03T01:52:20Z logger.go:67: service/test-cm created2026-08-03T01:52:20.0354522Z TestOSPoolEP 2026-08-03T01:52:20Z logger.go:67: deployment.apps/ospool-ep created2026-08-03T01:52:20.0355339Z TestOSPoolEP 2026-08-03T01:52:20Z 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-03T01:52:20.0408342Z TestOSPoolEP 2026-08-03T01:52:20Z logger.go:67: deployment.apps/test-cm created2026-08-03T01:52:20.0456293Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-03T01:52:20.0457833Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:20.0569002Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:20Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:20.0572004Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:20.0594738Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:20Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-03T01:52:20.0603654Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:20.0652774Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:20Z 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-03T01:52:20.0672219Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52: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: NewReplicaSetCreated, message: Created new replica set "ospool-ep-6c7d796845". Sleeping for 10s and will try again.2026-08-03T01:52:30.0661915Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:30Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-03T01:52:30.0662828Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:30.0671579Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:30Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:30.0673035Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:30.0683172Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:30Z logger.go:67: Deployment is now available2026-08-03T01:52:30.0688553Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:30Z 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-03T01:52:40.0694660Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:40Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:40.0695831Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:40.0717691Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:40Z 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-03T01:52:50.0718452Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:50Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:52:50.0719325Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:52:50.0758655Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:52:50Z 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-03T01:53:00.0767548Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:53:00Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-03T01:53:00.0768614Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0788070Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-03T01:53:00Z logger.go:67: Deployment is now available2026-08-03T01:53:00.0788852Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:53:00.0789392Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:53:00.0794790Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:53:00.0795395Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:53:00.0795843Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:53:00.0796329Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:53:00.0796654Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:53:00.0797859Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0798606Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:53:00.0799696Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0800816Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:53:00.0801972Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0817773Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0819227Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0820779Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:53:00.0857610Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_SINGULARITY]2026-08-03T01:53:00.0860624Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)']2026-08-03T01:53:00.0863227Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:53:00.2653313Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 10s.2026-08-03T01:53:00.2654935Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:53:00.2661199Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)']' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 10s.2026-08-03T01:53:00.2776400Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:53:00.2777533Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:53:10.2657635Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_SINGULARITY]2026-08-03T01:53:10.2679053Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)']2026-08-03T01:53:10.4449327Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-03T01:53:10.4450847Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 10s.2026-08-03T01:53:10.4490439Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-03T01:53:10.4491251Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)']' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 10s.2026-08-03T01:53:20.4449892Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:20Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_SINGULARITY]2026-08-03T01:53:20.4501762Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)']2026-08-03T01:53:20.5916841Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-03T01:53:20.5918366Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: 2026-08-03T01:53:20.5919890Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-pxzcs LINUX X86_64 Unclaimed Idle 0.000 15995 0+00:00:002026-08-03T01:53:20.5921421Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: 2026-08-03T01:53:20.5922867Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-03T01:53:20.5924296Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: 2026-08-03T01:53:20.5926031Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-03T01:53:20.5927526Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: 2026-08-03T01:53:20.5928741Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-03T01:53:20Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-03T01:53:20.5930187Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-03T01:53:20Z logger.go:67: true2026-08-03T01:53:30.2787504Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:53:30.4061002Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:53:30Z logger.go:67: undefined2026-08-03T01:53:30.4101923Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-03T01:53:30.4102946Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:54:00.4107591Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:54:00.5239606Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:00Z logger.go:67: undefined2026-08-03T01:54:00.5281864Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:54:30.5283375Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:54:30.6410559Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:54:30Z logger.go:67: undefined2026-08-03T01:54:30.6452662Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:55:00.6455433Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:55:00.7602163Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:00Z logger.go:67: undefined2026-08-03T01:55:00.7649694Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:55:30.7663834Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:55:30.8831101Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:55:30Z logger.go:67: undefined2026-08-03T01:55:30.8858289Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:56:00.8874116Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:56:01.0041281Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:01Z logger.go:67: undefined2026-08-03T01:56:01.0085626Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:56:31.0100566Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:56:31.1266824Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:56:31Z logger.go:67: undefined2026-08-03T01:56:31.1310147Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:57:01.1314228Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:57:01.2477617Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:01Z logger.go:67: undefined2026-08-03T01:57:01.2522343Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:57:31.2543455Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:57:31.3692500Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:57:31Z logger.go:67: undefined2026-08-03T01:57:31.3743595Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:58:01.3761660Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:58:01Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:58:01.4959619Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:58:01Z logger.go:67: undefined2026-08-03T01:58:01.5002048Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-vt2ll failed. Retrying in 30s.2026-08-03T01:58:31.5026158Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:58:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej exec test-cm-54b7d49f85-vt2ll -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-pxzcs",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-03T01:58:31.6290182Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-03T01:58:31Z logger.go:67: true2026-08-03T01:58:31.6346318Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.6418815Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.7434733Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.8561566Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.9428896Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.9452026Z === NAME TestOSPoolEP2026-08-03T01:58:31.9452451Z ospool_ep_test.go:101: ---2026-08-03T01:58:31.9452913Z Events for pod idtoken-generator:2026-08-03T01:58:31.9453923Z 2026-08-03 01:51:59 +0000 UTC Normal Successfully assigned test-ospool-ep-msvyej/idtoken-generator to minikube2026-08-03T01:58:31.9454851Z 2026-08-03 01:52:07 +0000 UTC Normal Pulling image "htcondor/base"2026-08-03T01:58:31.9456091Z 2026-08-03 01:52:17 +0000 UTC Normal Successfully pulled image "htcondor/base" in 10.237s (10.237s including waiting). Image size: 560910730 bytes.2026-08-03T01:58:31.9457055Z 2026-08-03 01:52:17 +0000 UTC Normal Container created2026-08-03T01:58:31.9457761Z 2026-08-03 01:52:17 +0000 UTC Normal Container started2026-08-03T01:58:31.9458135Z 2026-08-03T01:58:31.9458361Z ---2026-08-03T01:58:31.9458811Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.9476646Z ospool_ep_test.go:101: ---2026-08-03T01:58:31.9477709Z Events for pod ospool-ep-6c7d796845-pxzcs:2026-08-03T01:58:31.9479123Z 2026-08-03 01:52:20 +0000 UTC Normal Successfully assigned test-ospool-ep-msvyej/ospool-ep-6c7d796845-pxzcs to minikube2026-08-03T01:58:31.9480602Z 2026-08-03 01:52:20 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-03T01:58:31.9482929Z 2026-08-03 01:52:30 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 3.266s (9.652s including waiting). Image size: 5590942 bytes.2026-08-03T01:58:31.9484582Z 2026-08-03 01:52:30 +0000 UTC Normal Container created2026-08-03T01:58:31.9485406Z 2026-08-03 01:52:30 +0000 UTC Normal Container started2026-08-03T01:58:31.9486739Z 2026-08-03 01:52:30 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-08-03T01:58:31.9489647Z 2026-08-03 01:52:50 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 20.057s (20.057s including waiting). Image size: 1383210994 bytes.2026-08-03T01:58:31.9491085Z 2026-08-03 01:52:50 +0000 UTC Normal Container created2026-08-03T01:58:31.9491928Z 2026-08-03 01:52:50 +0000 UTC Normal Container started2026-08-03T01:58:31.9492570Z 2026-08-03T01:58:31.9493039Z ---2026-08-03T01:58:31.9493778Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.9494971Z ospool_ep_test.go:101: ---2026-08-03T01:58:31.9496492Z Events for pod test-cm-54b7d49f85-vt2ll:2026-08-03T01:58:31.9497502Z 2026-08-03 01:52:20 +0000 UTC Normal Successfully assigned test-ospool-ep-msvyej/test-cm-54b7d49f85-vt2ll to minikube2026-08-03T01:58:31.9498364Z 2026-08-03 01:52:20 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-08-03T01:58:31.9499380Z 2026-08-03 01:52:26 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 6.385s (6.385s including waiting). Image size: 573055668 bytes.2026-08-03T01:58:31.9500555Z 2026-08-03 01:52:26 +0000 UTC Normal Container created2026-08-03T01:58:31.9501397Z 2026-08-03 01:52:27 +0000 UTC Normal Container started2026-08-03T01:58:31.9502004Z 2026-08-03T01:58:31.9502470Z ---2026-08-03T01:58:31.9503357Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-03T01:58:31.9530015Z TestOSPoolEP 2026-08-03T01:58:31Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej delete -f /tmp/TestOSPoolEP1089224527]2026-08-03T01:58:32.0425376Z TestOSPoolEP 2026-08-03T01:58:32Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-msvyej namespace2026-08-03T01:58:32.0464370Z TestOSPoolEP 2026-08-03T01:58:32Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-msvyej namespace2026-08-03T01:58:33.4902644Z TestOSPoolEP 2026-08-03T01:58:33Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej delete -f /tmp/TestOSPoolEP770327333]2026-08-03T01:58:33.5626291Z TestOSPoolEP 2026-08-03T01:58:33Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-msvyej namespace2026-08-03T01:58:33.5663225Z TestOSPoolEP 2026-08-03T01:58:33Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-msvyej delete -k /tmp/kustomize-template-394735525]2026-08-03T01:58:33.6507126Z TestOSPoolEP 2026-08-03T01:58:33Z logger.go:67: service "test-cm" deleted from test-ospool-ep-msvyej namespace2026-08-03T01:58:33.6558703Z TestOSPoolEP 2026-08-03T01:58:33Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-msvyej namespace2026-08-03T01:58:33.6585643Z TestOSPoolEP 2026-08-03T01:58:33Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-msvyej namespace2026-08-03T01:58:33.6666386Z --- PASS: TestOSPoolEP (62.84s)2026-08-03T01:58:33.6690738Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (40.03s)2026-08-03T01:58:33.6718408Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.52s)2026-08-03T01:58:33.6748514Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.52s)2026-08-03T01:58:33.6778472Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (331.56s)2026-08-03T01:58:33.6794145Z PASS2026-08-03T01:58:33.6796559Z ok github.com/osg-htc/k8s-integration-tests/test 394.410s2026-08-03T01:58:33.7677486Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-03T01:58:33.7678197Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-03T01:58:33.7737191Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-03T01:58:33.7737727Z env:2026-08-03T01:58:33.7737961Z GOTOOLCHAIN: local2026-08-03T01:58:33.7738474Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-03T01:58:33.7739038Z ##[endgroup]2026-08-03T01:58:33.7902990Z 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-03T01:58:33.7903991Z ##[group]Run actions/upload-artifact@v42026-08-03T01:58:33.7904225Z with:2026-08-03T01:58:33.7904539Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-08-03T01:58:33.7904906Z path: /tmp/k8s-tests2026-08-03T01:58:33.7905105Z retention-days: 52026-08-03T01:58:33.7905284Z if-no-files-found: warn2026-08-03T01:58:33.7905482Z compression-level: 62026-08-03T01:58:33.7905672Z overwrite: false2026-08-03T01:58:33.7905851Z include-hidden-files: false2026-08-03T01:58:33.7906050Z env:2026-08-03T01:58:33.7906202Z GOTOOLCHAIN: local2026-08-03T01:58:33.7906381Z ##[endgroup]2026-08-03T01:58:33.9422365Z (node:18196) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-03T01:58:33.9423638Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-03T01:58:33.9577481Z With the provided path, there will be 6 files uploaded2026-08-03T01:58:33.9582802Z Artifact name is valid!2026-08-03T01:58:33.9583579Z Root directory input is valid!2026-08-03T01:58:34.2353809Z Beginning upload of artifact content to blob storage2026-08-03T01:58:34.2592642Z (node:18196) [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-03T01:58:34.5316348Z Uploaded bytes 140932026-08-03T01:58:34.5942758Z Finished uploading artifact content to blob storage!2026-08-03T01:58:34.5943635Z SHA256 digest of uploaded artifact zip is 3bad53c5626e3b1f89a20e41ea273425610d8e4691a679dffe244d13cd0b54f32026-08-03T01:58:34.5944384Z Finalizing artifact upload2026-08-03T01:58:34.7846097Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 88427432032026-08-03T01:58:34.7847878Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14093 bytes. Artifact ID is 88427432032026-08-03T01:58:34.7852719Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30777819006/artifacts/88427432032026-08-03T01:58:34.8016809Z Post job cleanup.2026-08-03T01:58:34.9109957Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-03T01:58:34.9145377Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-03T01:58:34.9172406Z /home/runner/go/pkg/mod2026-08-03T01:58:34.9195304Z /home/runner/.cache/go-build2026-08-03T01:58:34.9202187Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-03T01:58:34.9350709Z Post job cleanup.2026-08-03T01:58:35.0085379Z [command]/usr/bin/git version2026-08-03T01:58:35.0127078Z git version 2.54.02026-08-03T01:58:35.0166723Z Temporarily overriding HOME='/home/runner/work/_temp/7bd070bc-6b3f-488e-99a0-b70cca436228' before making global git config changes2026-08-03T01:58:35.0168175Z Adding repository directory to the temporary git global config as a safe directory2026-08-03T01:58:35.0173796Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-03T01:58:35.0217519Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-03T01:58:35.0254370Z [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-03T01:58:35.0576796Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-03T01:58:35.0599694Z http.https://github.com/.extraheader2026-08-03T01:58:35.0619809Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-03T01:58:35.0647921Z [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-03T01:58:35.0878735Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-03T01:58:35.0910189Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-03T01:58:35.1263596Z Cleaning up orphan processes2026-08-03T01:58:35.1829267Z ##[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/