2026-08-02T01:47:59.6515948Z Current runner version: '2.336.0'2026-08-02T01:47:59.6540020Z ##[group]Runner Image Provisioner2026-08-02T01:47:59.6540867Z Hosted Compute Agent2026-08-02T01:47:59.6541515Z Version: 20260707.5632026-08-02T01:47:59.6542118Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-02T01:47:59.6542829Z Build Date: 2026-07-07T19:33:50Z2026-08-02T01:47:59.6543485Z Worker ID: {267e288e-1787-4e29-99d4-f27575d84069}2026-08-02T01:47:59.6544380Z Azure Region: westus32026-08-02T01:47:59.6544955Z ##[endgroup]2026-08-02T01:47:59.6546398Z ##[group]Operating System2026-08-02T01:47:59.6546955Z Ubuntu2026-08-02T01:47:59.6547493Z 22.04.52026-08-02T01:47:59.6547970Z LTS2026-08-02T01:47:59.6548484Z ##[endgroup]2026-08-02T01:47:59.6549019Z ##[group]Runner Image2026-08-02T01:47:59.6549579Z Image: ubuntu-22.042026-08-02T01:47:59.6550149Z Version: 20260720.234.22026-08-02T01:47:59.6551392Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-02T01:47:59.6552805Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-02T01:47:59.6553693Z ##[endgroup]2026-08-02T01:47:59.6555036Z ##[group]GITHUB_TOKEN Permissions2026-08-02T01:47:59.6557257Z Contents: read2026-08-02T01:47:59.6557822Z Metadata: read2026-08-02T01:47:59.6558352Z Packages: read2026-08-02T01:47:59.6558881Z ##[endgroup]2026-08-02T01:47:59.6561179Z Secret source: Actions2026-08-02T01:47:59.6562227Z Prepare workflow directory2026-08-02T01:47:59.6883019Z Prepare all required actions2026-08-02T01:47:59.6934009Z Getting action download info2026-08-02T01:48:00.0731248Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-02T01:48:00.8959586Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-02T01:48:01.6076125Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-02T01:48:02.1701491Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-02T01:48:02.4027548Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-02T01:48:02.4031516Z ##[group] Inputs2026-08-02T01:48:02.4031931Z testName: TestOSPoolEP2026-08-02T01:48:02.4032483Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-02T01:48:02.4033085Z setupScript: 2026-08-02T01:48:02.4033393Z setupEnv: {}2026-08-02T01:48:02.4033667Z ##[endgroup]2026-08-02T01:48:02.4034336Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-testing) / Run Tests2026-08-02T01:48:02.4608722Z ##[group]Run actions/checkout@v52026-08-02T01:48:02.4609363Z with:2026-08-02T01:48:02.4609728Z repository: mwestphall/k8s-integration-tests2026-08-02T01:48:02.4612419Z token: ***2026-08-02T01:48:02.4612754Z ssh-strict: true2026-08-02T01:48:02.4613085Z ssh-user: git2026-08-02T01:48:02.4613415Z persist-credentials: true2026-08-02T01:48:02.4613757Z clean: true2026-08-02T01:48:02.4614223Z sparse-checkout-cone-mode: true2026-08-02T01:48:02.4614630Z fetch-depth: 12026-08-02T01:48:02.4614926Z fetch-tags: false2026-08-02T01:48:02.4615233Z show-progress: true2026-08-02T01:48:02.4615563Z lfs: false2026-08-02T01:48:02.4615849Z submodules: false2026-08-02T01:48:02.4616197Z set-safe-directory: true2026-08-02T01:48:02.4616524Z allow-unsafe-pr-checkout: false2026-08-02T01:48:02.4616987Z ##[endgroup]2026-08-02T01:48:02.5649560Z Syncing repository: mwestphall/k8s-integration-tests2026-08-02T01:48:02.5652216Z ##[group]Getting Git version info2026-08-02T01:48:02.5653320Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-02T01:48:02.5654944Z [command]/usr/bin/git version2026-08-02T01:48:02.5804311Z git version 2.54.02026-08-02T01:48:02.5823140Z ##[endgroup]2026-08-02T01:48:02.5836730Z Temporarily overriding HOME='/home/runner/work/_temp/d8456949-af69-4d85-892e-da9b4da342ec' before making global git config changes2026-08-02T01:48:02.5838062Z Adding repository directory to the temporary git global config as a safe directory2026-08-02T01:48:02.5843251Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-02T01:48:02.5880481Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-02T01:48:02.5885035Z ##[group]Initializing the repository2026-08-02T01:48:02.5890407Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-02T01:48:02.5987078Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-02T01:48:02.5990456Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-02T01:48:02.5991234Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-02T01:48:02.5991886Z hint: call:2026-08-02T01:48:02.5992280Z hint:2026-08-02T01:48:02.5992876Z hint: git config --global init.defaultBranch <name>2026-08-02T01:48:02.5993352Z hint:2026-08-02T01:48:02.5994120Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-02T01:48:02.5994939Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-02T01:48:02.5995697Z hint:2026-08-02T01:48:02.5996252Z hint: git branch -m <name>2026-08-02T01:48:02.5996904Z hint:2026-08-02T01:48:02.5997590Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-02T01:48:02.5998986Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-02T01:48:02.6001165Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-02T01:48:02.6020411Z ##[endgroup]2026-08-02T01:48:02.6021090Z ##[group]Disabling automatic garbage collection2026-08-02T01:48:02.6027081Z [command]/usr/bin/git config --local gc.auto 02026-08-02T01:48:02.6073956Z ##[endgroup]2026-08-02T01:48:02.6074777Z ##[group]Setting up auth2026-08-02T01:48:02.6083581Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-02T01:48:02.6116273Z [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-02T01:48:02.6481599Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-02T01:48:02.6518093Z [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-02T01:48:02.6733413Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-02T01:48:02.6764934Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-02T01:48:02.6960374Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-02T01:48:02.6995688Z ##[endgroup]2026-08-02T01:48:02.6996826Z ##[group]Fetching the repository2026-08-02T01:48:02.7004267Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-02T01:48:03.7609642Z From https://github.com/mwestphall/k8s-integration-tests2026-08-02T01:48:03.7610606Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-02T01:48:03.7631185Z ##[endgroup]2026-08-02T01:48:03.7632014Z ##[group]Determining the checkout info2026-08-02T01:48:03.7633094Z ##[endgroup]2026-08-02T01:48:03.7639426Z [command]/usr/bin/git sparse-checkout disable2026-08-02T01:48:03.7675869Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-02T01:48:03.7701704Z ##[group]Checking out the ref2026-08-02T01:48:03.7725297Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-02T01:48:03.7767101Z Switched to a new branch 'main'2026-08-02T01:48:03.7768155Z branch 'main' set up to track 'origin/main'.2026-08-02T01:48:03.7775899Z ##[endgroup]2026-08-02T01:48:03.7816967Z [command]/usr/bin/git log -1 --format=%H2026-08-02T01:48:03.7838393Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-02T01:48:03.8043109Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-02T01:48:03.8044127Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-02T01:48:03.8044814Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-02T01:48:04.2018497Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-02T01:48:04.2019170Z env:2026-08-02T01:48:04.2019719Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-02T01:48:04.2020303Z SETUP_ENV_YAML: {}2026-08-02T01:48:04.2020631Z ##[endgroup]2026-08-02T01:48:04.7873157Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-02T01:48:04.7874022Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-02T01:48:04.7906369Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-02T01:48:04.7906875Z env:2026-08-02T01:48:04.7907418Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-testing", "OSPOOL_EP_CMTag": "25.x-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-02T01:48:04.7907986Z ##[endgroup]2026-08-02T01:48:04.8013356Z ##[group]Run true2026-08-02T01:48:04.8013734Z [36;1mtrue[0m2026-08-02T01:48:04.8042233Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-02T01:48:04.8042667Z ##[endgroup]2026-08-02T01:48:04.8174401Z ##[group]Run medyagh/setup-minikube@latest2026-08-02T01:48:04.8174863Z with:2026-08-02T01:48:04.8175182Z start: true2026-08-02T01:48:04.8175495Z cache: true2026-08-02T01:48:04.8175819Z minikube-version: latest2026-08-02T01:48:04.8176177Z wait: all2026-08-02T01:48:04.8176482Z ##[endgroup]2026-08-02T01:48:04.9414516Z (node:1946) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-02T01:48:04.9415759Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-02T01:48:05.9402013Z [command]/usr/bin/chmod +x /home/runner/work/_temp/2297d066-2265-4e82-a291-f4252db411fc2026-08-02T01:48:06.9207270Z [command]/home/runner/bin/minikube version --short2026-08-02T01:48:07.0462428Z v1.38.12026-08-02T01:48:07.2512337Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-02T01:48:07.2520332Z Cache hit for: kic-v1.38.1-x642026-08-02T01:48:07.2628307Z (node:1946) [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-02T01:48:08.4823037Z Received 58720256 of 544082657 (10.8%), 56.0 MBs/sec2026-08-02T01:48:08.5016347Z Received 41943040 of 224218863 (18.7%), 40.0 MBs/sec2026-08-02T01:48:09.4821219Z Received 188743680 of 544082657 (34.7%), 90.0 MBs/sec2026-08-02T01:48:09.5016334Z Received 167772160 of 224218863 (74.8%), 80.0 MBs/sec2026-08-02T01:48:09.9147396Z Received 224218863 of 224218863 (100.0%), 88.6 MBs/sec2026-08-02T01:48:09.9148527Z Cache Size: ~214 MB (224218863 B)2026-08-02T01:48:09.9337906Z [command]/usr/bin/tar -xf /home/runner/work/_temp/56fa4a28-e47b-4907-8732-269d3f85ec27/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-02T01:48:10.4619568Z Cache restored successfully2026-08-02T01:48:10.4822632Z Received 306184192 of 544082657 (56.3%), 97.3 MBs/sec2026-08-02T01:48:11.4820713Z Received 440401920 of 544082657 (80.9%), 105.0 MBs/sec2026-08-02T01:48:12.2297274Z Received 544082657 of 544082657 (100.0%), 109.3 MBs/sec2026-08-02T01:48:12.2298300Z Cache Size: ~519 MB (544082657 B)2026-08-02T01:48:12.2329246Z [command]/usr/bin/tar -xf /home/runner/work/_temp/d21685b1-cc3c-41ce-8147-10daa7e8e2c6/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-02T01:48:12.7198657Z Cache restored successfully2026-08-02T01:48:12.7882797Z [command]/home/runner/bin/minikube start --wait all2026-08-02T01:48:12.8291476Z * minikube v1.38.1 on Ubuntu 22.042026-08-02T01:48:13.2225728Z ! 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-02T01:48:13.3299110Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-02T01:48:13.3762554Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-02T01:48:13.3801699Z * Using Docker driver with root privileges2026-08-02T01:48:13.3816008Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-02T01:48:13.3884185Z * Pulling base image v0.0.50 ...2026-08-02T01:48:49.0135490Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-02T01:48:53.1513990Z * Verifying Kubernetes components...2026-08-02T01:48:53.1901869Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-02T01:48:53.6946812Z * Enabled addons: storage-provisioner, default-storageclass2026-08-02T01:49:30.0703544Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-02T01:49:30.0804589Z [command]/home/runner/bin/minikube version --short2026-08-02T01:49:30.1150791Z v1.38.12026-08-02T01:49:30.1372454Z ##[group]Run actions/setup-go@v62026-08-02T01:49:30.1372755Z with:2026-08-02T01:49:30.1372967Z go-version: 1.262026-08-02T01:49:30.1373192Z cache: true2026-08-02T01:49:30.1373423Z cache-dependency-path: go.sum2026-08-02T01:49:30.1373684Z check-latest: false2026-08-02T01:49:30.1376429Z token: ***2026-08-02T01:49:30.1376643Z ##[endgroup]2026-08-02T01:49:30.2652683Z Setup go version spec 1.262026-08-02T01:49:30.3133673Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-02T01:49:30.3136537Z Added go to the path2026-08-02T01:49:30.3140130Z Successfully set up Go version 1.262026-08-02T01:49:30.4908409Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-02T01:49:30.4937574Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-02T01:49:30.4964166Z /home/runner/go/pkg/mod2026-08-02T01:49:30.4983696Z /home/runner/.cache/go-build2026-08-02T01:49:30.7294458Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-02T01:49:31.9725520Z Received 54525952 of 251839808 (21.7%), 52.0 MBs/sec2026-08-02T01:49:32.9688659Z Received 176160768 of 251839808 (69.9%), 84.0 MBs/sec2026-08-02T01:49:33.3894669Z Received 251839808 of 251839808 (100.0%), 99.2 MBs/sec2026-08-02T01:49:33.3895365Z Cache Size: ~240 MB (251839808 B)2026-08-02T01:49:33.3930378Z [command]/usr/bin/tar -xf /home/runner/work/_temp/4125582a-c211-4165-ad71-311ace231404/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-02T01:49:35.2210974Z Cache restored successfully2026-08-02T01:49:35.2555360Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-02T01:49:35.2588404Z go version go1.26.5 linux/amd642026-08-02T01:49:35.2588798Z 2026-08-02T01:49:35.2589374Z ##[group]go env2026-08-02T01:49:35.2791895Z AR='ar'2026-08-02T01:49:35.2792599Z CC='gcc'2026-08-02T01:49:35.2793197Z CGO_CFLAGS='-O2 -g'2026-08-02T01:49:35.2794189Z CGO_CPPFLAGS=''2026-08-02T01:49:35.2794649Z CGO_CXXFLAGS='-O2 -g'2026-08-02T01:49:35.2795085Z CGO_ENABLED='1'2026-08-02T01:49:35.2795547Z CGO_FFLAGS='-O2 -g'2026-08-02T01:49:35.2795856Z CGO_LDFLAGS='-O2 -g'2026-08-02T01:49:35.2796161Z CXX='g++'2026-08-02T01:49:35.2796444Z GCCGO='gccgo'2026-08-02T01:49:35.2796722Z GO111MODULE=''2026-08-02T01:49:35.2797012Z GOAMD64='v1'2026-08-02T01:49:35.2797330Z GOARCH='amd64'2026-08-02T01:49:35.2797607Z GOAUTH='netrc'2026-08-02T01:49:35.2797883Z GOBIN=''2026-08-02T01:49:35.2798214Z GOCACHE='/home/runner/.cache/go-build'2026-08-02T01:49:35.2798618Z GOCACHEPROG=''2026-08-02T01:49:35.2798906Z GODEBUG=''2026-08-02T01:49:35.2799243Z GOENV='/home/runner/.config/go/env'2026-08-02T01:49:35.2799634Z GOEXE=''2026-08-02T01:49:35.2799903Z GOEXPERIMENT=''2026-08-02T01:49:35.2800207Z GOFIPS140='off'2026-08-02T01:49:35.2800506Z GOFLAGS=''2026-08-02T01:49:35.2801455Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build2749508901=/tmp/go-build -gno-record-gcc-switches'2026-08-02T01:49:35.2802428Z GOHOSTARCH='amd64'2026-08-02T01:49:35.2802744Z GOHOSTOS='linux'2026-08-02T01:49:35.2803043Z GOINSECURE=''2026-08-02T01:49:35.2803552Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-02T01:49:35.2804313Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-02T01:49:35.2804713Z GONOPROXY=''2026-08-02T01:49:35.2804977Z GONOSUMDB=''2026-08-02T01:49:35.2805269Z GOOS='linux'2026-08-02T01:49:35.2805569Z GOPATH='/home/runner/go'2026-08-02T01:49:35.2805874Z GOPRIVATE=''2026-08-02T01:49:35.2806228Z GOPROXY='https://proxy.golang.org,direct'2026-08-02T01:49:35.2806691Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-02T01:49:35.2807093Z GOSUMDB='sum.golang.org'2026-08-02T01:49:35.2807440Z GOTELEMETRY='local'2026-08-02T01:49:35.2807842Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-02T01:49:35.2808258Z GOTMPDIR=''2026-08-02T01:49:35.2808803Z GOTOOLCHAIN='local'2026-08-02T01:49:35.2809250Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-02T01:49:35.2809695Z GOVCS=''2026-08-02T01:49:35.2810009Z GOVERSION='go1.26.5'2026-08-02T01:49:35.2810335Z GOWORK=''2026-08-02T01:49:35.2810641Z PKG_CONFIG='pkg-config'2026-08-02T01:49:35.2810848Z 2026-08-02T01:49:35.2811291Z ##[endgroup]2026-08-02T01:49:35.2963448Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-02T01:49:35.2964292Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-02T01:49:35.2998574Z shell: /usr/bin/bash -e {0}2026-08-02T01:49:35.2998857Z env:2026-08-02T01:49:35.2999075Z GOTOOLCHAIN: local2026-08-02T01:49:35.2999327Z OSPOOL_EP_OSPoolEPTag: 25-testing2026-08-02T01:49:35.2999609Z OSPOOL_EP_CMTag: 25.x-el92026-08-02T01:49:35.2999864Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-02T01:49:35.3000124Z ##[endgroup]2026-08-02T01:49:41.9347353Z === RUN TestOSPoolEP2026-08-02T01:49:41.9349093Z === PAUSE TestOSPoolEP2026-08-02T01:49:41.9349464Z === CONT TestOSPoolEP2026-08-02T01:49:41.9355810Z TestOSPoolEP 2026-08-02T01:49:41Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:49:41.9476601Z TestOSPoolEP 2026-08-02T01:49:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x apply -f /tmp/TestOSPoolEP1240771469]2026-08-02T01:49:42.3623742Z TestOSPoolEP 2026-08-02T01:49:42Z logger.go:67: pod/idtoken-generator created2026-08-02T01:49:42.4104267Z TestOSPoolEP 2026-08-02T01:49:42Z logger.go:67: secret/pool-passwd created2026-08-02T01:49:42.4136606Z TestOSPoolEP 2026-08-02T01:49:42Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-02T01:49:42.4138441Z TestOSPoolEP 2026-08-02T01:49:42Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:49:42.4258407Z TestOSPoolEP 2026-08-02T01:49:42Z 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-02T01:49:52.4259946Z TestOSPoolEP 2026-08-02T01:49:52Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-02T01:49:52.4262921Z TestOSPoolEP 2026-08-02T01:49:52Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:49:52.4289803Z TestOSPoolEP 2026-08-02T01:49:52Z 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-02T01:50:02.4291221Z TestOSPoolEP 2026-08-02T01:50:02Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-02T01:50:02.4292223Z TestOSPoolEP 2026-08-02T01:50:02Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:02.4810302Z TestOSPoolEP 2026-08-02T01:50: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-02T01:50:12.4810665Z TestOSPoolEP 2026-08-02T01:50:12Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-02T01:50:12.4811711Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:12.4840185Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Pod is now available2026-08-02T01:50:12.4841424Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-02T01:50:12.6213661Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: ***2026-08-02T01:50:12.6252428Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x apply -f /tmp/TestOSPoolEP4283509996]2026-08-02T01:50:12.8096548Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: secret/pool-token created2026-08-02T01:50:12.8108282Z TestOSPoolEP 2026-08-02T01:50:12Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-02T01:50:12.8109933Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:12.8130020Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Secret is now available2026-08-02T01:50:12.8155885Z TestOSPoolEP 2026-08-02T01:50:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x apply -k /tmp/kustomize-template-1377592795]2026-08-02T01:50:13.0621836Z TestOSPoolEP 2026-08-02T01:50:13Z logger.go:67: service/test-cm created2026-08-02T01:50:13.0754277Z TestOSPoolEP 2026-08-02T01:50:13Z 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-02T01:50:13.0756983Z TestOSPoolEP 2026-08-02T01:50:13Z logger.go:67: deployment.apps/ospool-ep created2026-08-02T01:50:13.1678038Z TestOSPoolEP 2026-08-02T01:50:13Z logger.go:67: deployment.apps/test-cm created2026-08-02T01:50:13.1738651Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-02T01:50:13.1740134Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:13.1782501Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-02T01:50:13.1784874Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:13.1796769Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-02T01:50:13.1798840Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:13.2055277Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z 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-02T01:50:13.2059580Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:13Z 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-02T01:50:23.2057475Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:23Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-02T01:50:23.2061399Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:23.2067272Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:23Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-02T01:50:23.2069046Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:23Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:23.2118562Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50: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: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-65c844ddbb" is progressing.. Sleeping for 10s and will try again.2026-08-02T01:50:23.2131779Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:23Z logger.go:67: Deployment is now available2026-08-02T01:50:33.2137546Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:33Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-02T01:50:33.2138948Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:33Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:33.2167414Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50: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-02T01:50:43.2178575Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:43Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-02T01:50:43.2180368Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2211383Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-02T01:50:43Z logger.go:67: Deployment is now available2026-08-02T01:50:43.2212361Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-02T01:50:43.2213029Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-02T01:50:43.2213651Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-02T01:50:43.2214574Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-02T01:50:43.2215314Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-02T01:50:43.2216352Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-02T01:50:43.2217019Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-02T01:50:43.2218188Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2224670Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-02T01:50:43.2226487Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2227851Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-02T01:50:43.2229212Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2253387Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2259751Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2262061Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:50:43Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:50:43.2285722Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:50:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)']2026-08-02T01:50:43.2319907Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:50:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:50:43.2333409Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-02T01:50:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_SINGULARITY]2026-08-02T01:50:43.4477718Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-02T01:50:43.4480961Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:50:43.4618944Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-02T01:50:43.4620281Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)']' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 10s.2026-08-02T01:50:43.4621408Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-02T01:50:43.4622808Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 10s.2026-08-02T01:50:53.4629034Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-02T01:50:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_SINGULARITY]2026-08-02T01:50:53.4635146Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:50:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)']2026-08-02T01:50:53.6445028Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_SINGULARITY]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 10s.2026-08-02T01:50:53.6449054Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-02T01:50:53.6450016Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)']' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 10s.2026-08-02T01:51:03.6448152Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-02T01:51:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_SINGULARITY]2026-08-02T01:51:03.6452315Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)']2026-08-02T01:51:03.8298848Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-02T01:51:03.8300944Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: 2026-08-02T01:51:03.8303338Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-65c844ddbb-hfnsp LINUX X86_64 Unclaimed Idle 0.000 15990 0+00:00:002026-08-02T01:51:03.8306666Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: 2026-08-02T01:51:03.8308171Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-02T01:51:03.8309733Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: 2026-08-02T01:51:03.8311226Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-02T01:51:03.8313034Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: 2026-08-02T01:51:03.8314809Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-02T01:51:03Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-02T01:51:03.8317604Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-02T01:51:03Z logger.go:67: true2026-08-02T01:51:13.4488800Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:51:13Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:51:13.5936947Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:51:13Z logger.go:67: undefined2026-08-02T01:51:13.5976325Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-02T01:51:13.5977526Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:51:43.5985091Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:51:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:51:43.7435222Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:51:43Z logger.go:67: undefined2026-08-02T01:51:43.7473211Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:52:13.7475482Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:52:13Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:52:13.8898455Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:52:13Z logger.go:67: undefined2026-08-02T01:52:13.8938797Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:52:43.8942662Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:52:43Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:52:44.0346509Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:52:44Z logger.go:67: undefined2026-08-02T01:52:44.0388410Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:53:14.0408694Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:53:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:53:14.1825715Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:53:14Z logger.go:67: undefined2026-08-02T01:53:14.1861999Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:53:44.1883171Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:53:44Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:53:44.3296611Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:53:44Z logger.go:67: undefined2026-08-02T01:53:44.3343499Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:54:14.3361508Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:54:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:54:14.4748535Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:54:14Z logger.go:67: undefined2026-08-02T01:54:14.4796722Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:54:44.4799902Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:54:44Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:54:44.6244386Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:54:44Z logger.go:67: undefined2026-08-02T01:54:44.6285899Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:55:14.6307971Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:55:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:55:14.7750767Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:55:14Z logger.go:67: undefined2026-08-02T01:55:14.7794514Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:55:44.7802153Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:55:44Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:55:44.9289768Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:55:44Z logger.go:67: undefined2026-08-02T01:55:44.9323533Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-5bb545475c-mp6bt failed. Retrying in 30s.2026-08-02T01:56:14.9329597Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:56:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x exec test-cm-5bb545475c-mp6bt -- sh -c condor_status -const 'regexp("ospool-ep-65c844ddbb-hfnsp",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-02T01:56:15.0878544Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-02T01:56:15Z logger.go:67: true2026-08-02T01:56:15.0939460Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.1013141Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.2123167Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.3298091Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.4492943Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.4525973Z === NAME TestOSPoolEP2026-08-02T01:56:15.4526673Z ospool_ep_test.go:101: ---2026-08-02T01:56:15.4532667Z Events for pod idtoken-generator:2026-08-02T01:56:15.4534636Z 2026-08-02 01:49:42 +0000 UTC Normal Successfully assigned test-ospool-ep-ngsz8x/idtoken-generator to minikube2026-08-02T01:56:15.4536021Z 2026-08-02 01:49:48 +0000 UTC Normal Pulling image "htcondor/base"2026-08-02T01:56:15.4538767Z 2026-08-02 01:50:02 +0000 UTC Normal Successfully pulled image "htcondor/base" in 13.166s (13.166s including waiting). Image size: 560910730 bytes.2026-08-02T01:56:15.4540288Z 2026-08-02 01:50:02 +0000 UTC Normal Container created2026-08-02T01:56:15.4541052Z 2026-08-02 01:50:02 +0000 UTC Normal Container started2026-08-02T01:56:15.4541578Z 2026-08-02T01:56:15.4541920Z ---2026-08-02T01:56:15.4542861Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.4547199Z ospool_ep_test.go:101: ---2026-08-02T01:56:15.4548418Z Events for pod ospool-ep-65c844ddbb-hfnsp:2026-08-02T01:56:15.4550747Z 2026-08-02 01:50:13 +0000 UTC Normal Successfully assigned test-ospool-ep-ngsz8x/ospool-ep-65c844ddbb-hfnsp to minikube2026-08-02T01:56:15.4552484Z 2026-08-02 01:50:13 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-02T01:56:15.4554713Z 2026-08-02 01:50:16 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.954s (2.954s including waiting). Image size: 5590942 bytes.2026-08-02T01:56:15.4556283Z 2026-08-02 01:50:16 +0000 UTC Normal Container created2026-08-02T01:56:15.4557324Z 2026-08-02 01:50:17 +0000 UTC Normal Container started2026-08-02T01:56:15.4558877Z 2026-08-02 01:50:17 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing"2026-08-02T01:56:15.4566852Z 2026-08-02 01:50:41 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-testing" in 23.997s (24.008s including waiting). Image size: 1250776798 bytes.2026-08-02T01:56:15.4568610Z 2026-08-02 01:50:41 +0000 UTC Normal Container created2026-08-02T01:56:15.4569511Z 2026-08-02 01:50:41 +0000 UTC Normal Container started2026-08-02T01:56:15.4570230Z 2026-08-02T01:56:15.4571268Z ---2026-08-02T01:56:15.4572278Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.4573367Z ospool_ep_test.go:101: ---2026-08-02T01:56:15.4574275Z Events for pod test-cm-5bb545475c-mp6bt:2026-08-02T01:56:15.4575898Z 2026-08-02 01:50:13 +0000 UTC Normal Successfully assigned test-ospool-ep-ngsz8x/test-cm-5bb545475c-mp6bt to minikube2026-08-02T01:56:15.4577408Z 2026-08-02 01:50:13 +0000 UTC Normal Pulling image "htcondor/cm:25.x-el9"2026-08-02T01:56:15.4585631Z 2026-08-02 01:50:17 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.x-el9" in 1.044s (3.998s including waiting). Image size: 560910756 bytes.2026-08-02T01:56:15.4587145Z 2026-08-02 01:50:17 +0000 UTC Normal Container created2026-08-02T01:56:15.4587921Z 2026-08-02 01:50:17 +0000 UTC Normal Container started2026-08-02T01:56:15.4588574Z 2026-08-02T01:56:15.4588918Z ---2026-08-02T01:56:15.4590423Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-02T01:56:15.4616991Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x delete -f /tmp/TestOSPoolEP1145221524]2026-08-02T01:56:15.5590109Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-ngsz8x namespace2026-08-02T01:56:15.5674810Z TestOSPoolEP 2026-08-02T01:56:15Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-ngsz8x namespace2026-08-02T01:56:16.9158476Z TestOSPoolEP 2026-08-02T01:56:16Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x delete -f /tmp/TestOSPoolEP3537718096]2026-08-02T01:56:17.0067710Z TestOSPoolEP 2026-08-02T01:56:17Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-ngsz8x namespace2026-08-02T01:56:17.0099692Z TestOSPoolEP 2026-08-02T01:56:17Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-ngsz8x delete -k /tmp/kustomize-template-1377592795]2026-08-02T01:56:17.1418683Z TestOSPoolEP 2026-08-02T01:56:17Z logger.go:67: service "test-cm" deleted from test-ospool-ep-ngsz8x namespace2026-08-02T01:56:17.1501486Z TestOSPoolEP 2026-08-02T01:56:17Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-ngsz8x namespace2026-08-02T01:56:17.1536835Z TestOSPoolEP 2026-08-02T01:56:17Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-ngsz8x namespace2026-08-02T01:56:17.1626940Z --- PASS: TestOSPoolEP (63.36s)2026-08-02T01:56:17.1647170Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.05s)2026-08-02T01:56:17.1648625Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.61s)2026-08-02T01:56:17.1650230Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.61s)2026-08-02T01:56:17.1655234Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (331.87s)2026-08-02T01:56:17.1656097Z PASS2026-08-02T01:56:17.1658212Z ok github.com/osg-htc/k8s-integration-tests/test 395.243s2026-08-02T01:56:17.2338829Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-02T01:56:17.2339605Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-02T01:56:17.2382658Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-02T01:56:17.2383267Z env:2026-08-02T01:56:17.2383628Z GOTOOLCHAIN: local2026-08-02T01:56:17.2384528Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-testingOSPOOL_EP_CMTag: 25.x-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-02T01:56:17.2385276Z ##[endgroup]2026-08-02T01:56:17.2536136Z 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-02T01:56:17.2537414Z ##[group]Run actions/upload-artifact@v42026-08-02T01:56:17.2537700Z with:2026-08-02T01:56:17.2538086Z name: TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe52026-08-02T01:56:17.2538558Z path: /tmp/k8s-tests2026-08-02T01:56:17.2538804Z retention-days: 52026-08-02T01:56:17.2539075Z if-no-files-found: warn2026-08-02T01:56:17.2539332Z compression-level: 62026-08-02T01:56:17.2539563Z overwrite: false2026-08-02T01:56:17.2539799Z include-hidden-files: false2026-08-02T01:56:17.2540056Z env:2026-08-02T01:56:17.2540261Z GOTOOLCHAIN: local2026-08-02T01:56:17.2540490Z ##[endgroup]2026-08-02T01:56:17.4505781Z (node:17885) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-02T01:56:17.4513218Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-02T01:56:17.4627673Z With the provided path, there will be 6 files uploaded2026-08-02T01:56:17.4644626Z Artifact name is valid!2026-08-02T01:56:17.4657633Z Root directory input is valid!2026-08-02T01:56:17.7802886Z Beginning upload of artifact content to blob storage2026-08-02T01:56:17.8234366Z (node:17885) [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-02T01:56:18.1106233Z Uploaded bytes 140912026-08-02T01:56:18.1732126Z Finished uploading artifact content to blob storage!2026-08-02T01:56:18.1733113Z SHA256 digest of uploaded artifact zip is 617708bd2082d819f940119a94e19b97d0ade55b3bcca57e9c27b9d43d0d61bd2026-08-02T01:56:18.1735320Z Finalizing artifact upload2026-08-02T01:56:18.3707168Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5.zip successfully finalized. Artifact ID 88270107692026-08-02T01:56:18.3708927Z Artifact TestOSPoolEP-83f3fae8a83028eb31b2597512f6f7a53bfc6a7f71d209ee39c64644a27cfbe5 has been successfully uploaded! Final size is 14091 bytes. Artifact ID is 88270107692026-08-02T01:56:18.3714004Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30727694946/artifacts/88270107692026-08-02T01:56:18.3853705Z Post job cleanup.2026-08-02T01:56:18.5174977Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-02T01:56:18.5235837Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-02T01:56:18.5259438Z /home/runner/go/pkg/mod2026-08-02T01:56:18.5283341Z /home/runner/.cache/go-build2026-08-02T01:56:18.5290843Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-02T01:56:18.5466364Z Post job cleanup.2026-08-02T01:56:18.6399758Z [command]/usr/bin/git version2026-08-02T01:56:18.6445384Z git version 2.54.02026-08-02T01:56:18.6487421Z Temporarily overriding HOME='/home/runner/work/_temp/f6d9d21c-1ff4-46a5-9d00-4a711c3b6ada' before making global git config changes2026-08-02T01:56:18.6488384Z Adding repository directory to the temporary git global config as a safe directory2026-08-02T01:56:18.6494639Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-02T01:56:18.6535550Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-02T01:56:18.6567887Z [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-02T01:56:18.6791011Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-02T01:56:18.6822633Z http.https://github.com/.extraheader2026-08-02T01:56:18.6832254Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-02T01:56:18.6878683Z [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-02T01:56:18.7135465Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-02T01:56:18.7166456Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-02T01:56:18.7614192Z Cleaning up orphan processes2026-08-02T01:56:18.8240920Z ##[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/