2026-08-15T00:37:31.1863079Z Current runner version: '2.336.0'2026-08-15T00:37:31.1890600Z ##[group]Runner Image Provisioner2026-08-15T00:37:31.1891776Z Hosted Compute Agent2026-08-15T00:37:31.1892461Z Version: 20260729.5662026-08-15T00:37:31.1893268Z Commit: cf7153fe6e25b664e8693c24944bf2b00355d1092026-08-15T00:37:31.1894029Z Build Date: 2026-07-29T19:17:02Z2026-08-15T00:37:31.1894833Z Worker ID: {3779a0d1-656b-43d2-a83d-8cee8fcfc9e6}2026-08-15T00:37:31.1895614Z Azure Region: westcentralus2026-08-15T00:37:31.1896257Z ##[endgroup]2026-08-15T00:37:31.1897861Z ##[group]Operating System2026-08-15T00:37:31.1898511Z Ubuntu2026-08-15T00:37:31.1899066Z 22.04.52026-08-15T00:37:31.1899722Z LTS2026-08-15T00:37:31.1900287Z ##[endgroup]2026-08-15T00:37:31.1900931Z ##[group]Runner Image2026-08-15T00:37:31.1901838Z Image: ubuntu-22.042026-08-15T00:37:31.1902496Z Version: 20260810.260.12026-08-15T00:37:31.1903939Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260810.260/images/ubuntu/Ubuntu2204-Readme.md2026-08-15T00:37:31.1905595Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260810.2602026-08-15T00:37:31.1906622Z ##[endgroup]2026-08-15T00:37:31.1907943Z ##[group]GITHUB_TOKEN Permissions2026-08-15T00:37:31.1910283Z Contents: read2026-08-15T00:37:31.1910928Z Metadata: read2026-08-15T00:37:31.1911846Z Packages: read2026-08-15T00:37:31.1912446Z ##[endgroup]2026-08-15T00:37:31.1914638Z Secret source: Actions2026-08-15T00:37:31.1916394Z Prepare workflow directory2026-08-15T00:37:31.2278554Z Prepare all required actions2026-08-15T00:37:31.2328535Z Getting action download info2026-08-15T00:37:31.5272465Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-15T00:37:31.6637770Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-15T00:37:32.1467987Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-15T00:37:32.6980694Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-15T00:37:32.9692865Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-15T00:37:32.9699105Z ##[group] Inputs2026-08-15T00:37:32.9700122Z testName: TestOSPoolEP2026-08-15T00:37:32.9702056Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:37:32.9703950Z setupScript: 2026-08-15T00:37:32.9704895Z setupEnv: {}2026-08-15T00:37:32.9705753Z ##[endgroup]2026-08-15T00:37:32.9707107Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-08-15T00:37:33.0623567Z ##[group]Run actions/checkout@v52026-08-15T00:37:33.0624981Z with:2026-08-15T00:37:33.0625933Z repository: mwestphall/k8s-integration-tests2026-08-15T00:37:33.0635191Z token: ***2026-08-15T00:37:33.0636057Z ssh-strict: true2026-08-15T00:37:33.0636986Z ssh-user: git2026-08-15T00:37:33.0637881Z persist-credentials: true2026-08-15T00:37:33.0638851Z clean: true2026-08-15T00:37:33.0639734Z sparse-checkout-cone-mode: true2026-08-15T00:37:33.0640769Z fetch-depth: 12026-08-15T00:37:33.0641721Z fetch-tags: false2026-08-15T00:37:33.0642604Z show-progress: true2026-08-15T00:37:33.0643495Z lfs: false2026-08-15T00:37:33.0644325Z submodules: false2026-08-15T00:37:33.0645210Z set-safe-directory: true2026-08-15T00:37:33.0646233Z allow-unsafe-pr-checkout: false2026-08-15T00:37:33.0647500Z ##[endgroup]2026-08-15T00:37:33.1776800Z Syncing repository: mwestphall/k8s-integration-tests2026-08-15T00:37:33.1780883Z ##[group]Getting Git version info2026-08-15T00:37:33.1783275Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-15T00:37:33.1785864Z [command]/usr/bin/git version2026-08-15T00:37:33.1818637Z git version 2.54.02026-08-15T00:37:33.1845277Z ##[endgroup]2026-08-15T00:37:33.1885036Z Temporarily overriding HOME='/home/runner/work/_temp/449a3033-c79a-4b25-99b8-b448a9be89fc' before making global git config changes2026-08-15T00:37:33.1890392Z Adding repository directory to the temporary git global config as a safe directory2026-08-15T00:37:33.1894332Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-15T00:37:33.1918676Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-15T00:37:33.1934689Z ##[group]Initializing the repository2026-08-15T00:37:33.1941688Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-15T00:37:33.2048374Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-15T00:37:33.2051797Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-15T00:37:33.2054519Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-15T00:37:33.2056225Z hint: call:2026-08-15T00:37:33.2057155Z hint:2026-08-15T00:37:33.2058320Z hint: git config --global init.defaultBranch <name>2026-08-15T00:37:33.2059667Z hint:2026-08-15T00:37:33.2061090Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-15T00:37:33.2063363Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-15T00:37:33.2064956Z hint:2026-08-15T00:37:33.2065935Z hint: git branch -m <name>2026-08-15T00:37:33.2067021Z hint:2026-08-15T00:37:33.2068398Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-15T00:37:33.2071019Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-15T00:37:33.2077513Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-15T00:37:33.2115674Z ##[endgroup]2026-08-15T00:37:33.2117178Z ##[group]Disabling automatic garbage collection2026-08-15T00:37:33.2120902Z [command]/usr/bin/git config --local gc.auto 02026-08-15T00:37:33.2164304Z ##[endgroup]2026-08-15T00:37:33.2165696Z ##[group]Setting up auth2026-08-15T00:37:33.2173907Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-15T00:37:33.2230561Z [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-15T00:37:33.2789582Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-15T00:37:33.2852104Z [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-15T00:37:33.3191115Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-15T00:37:33.3239157Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-15T00:37:33.3533126Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-15T00:37:33.3582346Z ##[endgroup]2026-08-15T00:37:33.3583795Z ##[group]Fetching the repository2026-08-15T00:37:33.3592767Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-15T00:37:33.8460359Z From https://github.com/mwestphall/k8s-integration-tests2026-08-15T00:37:33.8462013Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-15T00:37:33.8489647Z ##[endgroup]2026-08-15T00:37:33.8490355Z ##[group]Determining the checkout info2026-08-15T00:37:33.8492143Z ##[endgroup]2026-08-15T00:37:33.8498133Z [command]/usr/bin/git sparse-checkout disable2026-08-15T00:37:33.8553055Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-15T00:37:33.8594058Z ##[group]Checking out the ref2026-08-15T00:37:33.8599013Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-15T00:37:33.8702867Z Switched to a new branch 'main'2026-08-15T00:37:33.8705088Z branch 'main' set up to track 'origin/main'.2026-08-15T00:37:33.8748751Z ##[endgroup]2026-08-15T00:37:33.8800369Z [command]/usr/bin/git log -1 --format=%H2026-08-15T00:37:33.8835441Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-15T00:37:33.9174695Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-15T00:37:33.9175532Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-15T00:37:33.9176166Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-15T00:37:33.9477420Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:37:33.9477912Z env:2026-08-15T00:37:33.9478414Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:37:33.9478940Z SETUP_ENV_YAML: {}2026-08-15T00:37:33.9479208Z ##[endgroup]2026-08-15T00:37:34.7068876Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-15T00:37:34.7069816Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-15T00:37:34.7147538Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:37:34.7147959Z env:2026-08-15T00:37:34.7148466Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-15T00:37:34.7149024Z ##[endgroup]2026-08-15T00:37:34.7316618Z ##[group]Run true2026-08-15T00:37:34.7316921Z [36;1mtrue[0m2026-08-15T00:37:34.7389068Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:37:34.7389512Z ##[endgroup]2026-08-15T00:37:34.7576075Z ##[group]Run medyagh/setup-minikube@latest2026-08-15T00:37:34.7576437Z with:2026-08-15T00:37:34.7576689Z start: true2026-08-15T00:37:34.7576929Z cache: true2026-08-15T00:37:34.7577210Z minikube-version: latest2026-08-15T00:37:34.7577514Z wait: all2026-08-15T00:37:34.7577756Z ##[endgroup]2026-08-15T00:37:34.8959409Z (node:1951) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-15T00:37:34.8960621Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-15T00:37:36.1959599Z [command]/usr/bin/chmod +x /home/runner/work/_temp/7c1e899b-7e60-4116-ae74-2ef2ccb8f2f42026-08-15T00:37:36.3678043Z [command]/home/runner/bin/minikube version --short2026-08-15T00:37:36.4258789Z v1.38.12026-08-15T00:37:36.6109070Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-15T00:37:36.6223671Z (node:1951) [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-15T00:37:36.6255822Z Cache hit for: kic-v1.38.1-x642026-08-15T00:37:37.8322529Z Received 41943040 of 544082657 (7.7%), 39.9 MBs/sec2026-08-15T00:37:37.8505373Z Received 37748736 of 224218863 (16.8%), 36.0 MBs/sec2026-08-15T00:37:38.8339916Z Received 142606336 of 544082657 (26.2%), 67.9 MBs/sec2026-08-15T00:37:38.8545594Z Received 167772160 of 224218863 (74.8%), 79.8 MBs/sec2026-08-15T00:37:39.1791484Z Received 224218863 of 224218863 (100.0%), 91.8 MBs/sec2026-08-15T00:37:39.1792536Z Cache Size: ~214 MB (224218863 B)2026-08-15T00:37:39.2429275Z [command]/usr/bin/tar -xf /home/runner/work/_temp/808a7885-31e2-4652-8b15-9a97e43b9d33/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-15T00:37:39.8404167Z Received 268435456 of 544082657 (49.3%), 85.0 MBs/sec2026-08-15T00:37:39.9413566Z Cache restored successfully2026-08-15T00:37:40.8415763Z Received 402653184 of 544082657 (74.0%), 95.7 MBs/sec2026-08-15T00:37:41.8424722Z Received 536870912 of 544082657 (98.7%), 102.1 MBs/sec2026-08-15T00:37:41.9808951Z Received 544082657 of 544082657 (100.0%), 100.7 MBs/sec2026-08-15T00:37:41.9810215Z Cache Size: ~519 MB (544082657 B)2026-08-15T00:37:41.9967874Z [command]/usr/bin/tar -xf /home/runner/work/_temp/10b237ae-c5b4-4645-9905-76c66c49a518/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-15T00:37:42.9851905Z Cache restored successfully2026-08-15T00:37:43.1117356Z [command]/home/runner/bin/minikube start --wait all2026-08-15T00:37:43.1606761Z * minikube v1.38.1 on Ubuntu 22.042026-08-15T00:37:46.2765444Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-15T00:37:46.3284115Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-15T00:37:46.3291082Z * Using Docker driver with root privileges2026-08-15T00:37:46.3299709Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-15T00:37:46.3306510Z * Pulling base image v0.0.50 ...2026-08-15T00:38:18.5943735Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-15T00:38:23.7672410Z * Verifying Kubernetes components...2026-08-15T00:38:23.7949315Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-15T00:38:24.6093981Z * Enabled addons: storage-provisioner, default-storageclass2026-08-15T00:39:08.2916282Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-15T00:39:08.3049247Z [command]/home/runner/bin/minikube version --short2026-08-15T00:39:08.3676954Z v1.38.12026-08-15T00:39:08.4017820Z ##[group]Run actions/setup-go@v62026-08-15T00:39:08.4018276Z with:2026-08-15T00:39:08.4018558Z go-version: 1.262026-08-15T00:39:08.4018856Z cache: true2026-08-15T00:39:08.4019206Z cache-dependency-path: go.sum2026-08-15T00:39:08.4019581Z check-latest: false2026-08-15T00:39:08.4023820Z token: ***2026-08-15T00:39:08.4024153Z ##[endgroup]2026-08-15T00:39:08.5462154Z Setup go version spec 1.262026-08-15T00:39:08.6018034Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-15T00:39:08.6021129Z Added go to the path2026-08-15T00:39:08.6025528Z Successfully set up Go version 1.262026-08-15T00:39:09.3869869Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-15T00:39:09.3910613Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-15T00:39:09.3943247Z /home/runner/go/pkg/mod2026-08-15T00:39:09.3971812Z /home/runner/.cache/go-build2026-08-15T00:39:09.5971864Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-15T00:39:10.8393787Z Received 37748736 of 251839808 (15.0%), 36.0 MBs/sec2026-08-15T00:39:11.8459460Z Received 121634816 of 251839808 (48.3%), 57.9 MBs/sec2026-08-15T00:39:12.8418806Z Received 121634816 of 251839808 (48.3%), 38.6 MBs/sec2026-08-15T00:39:13.8430926Z Received 121634816 of 251839808 (48.3%), 29.0 MBs/sec2026-08-15T00:39:14.8443151Z Received 121634816 of 251839808 (48.3%), 23.2 MBs/sec2026-08-15T00:39:15.8459980Z Received 146800640 of 251839808 (58.3%), 23.3 MBs/sec2026-08-15T00:39:16.6169345Z Received 251839808 of 251839808 (100.0%), 35.4 MBs/sec2026-08-15T00:39:16.6170566Z Cache Size: ~240 MB (251839808 B)2026-08-15T00:39:16.6238866Z [command]/usr/bin/tar -xf /home/runner/work/_temp/dcdf808a-570a-4f75-9122-13052171cded/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-15T00:39:19.8614559Z Cache restored successfully2026-08-15T00:39:19.9231707Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-15T00:39:19.9258400Z go version go1.26.5 linux/amd642026-08-15T00:39:19.9258638Z 2026-08-15T00:39:19.9259295Z ##[group]go env2026-08-15T00:39:20.0762435Z AR='ar'2026-08-15T00:39:20.0762849Z CC='gcc'2026-08-15T00:39:20.0763187Z CGO_CFLAGS='-O2 -g'2026-08-15T00:39:20.0763570Z CGO_CPPFLAGS=''2026-08-15T00:39:20.0763944Z CGO_CXXFLAGS='-O2 -g'2026-08-15T00:39:20.0764324Z CGO_ENABLED='1'2026-08-15T00:39:20.0785521Z CGO_FFLAGS='-O2 -g'2026-08-15T00:39:20.0785963Z CGO_LDFLAGS='-O2 -g'2026-08-15T00:39:20.0786299Z CXX='g++'2026-08-15T00:39:20.0786607Z GCCGO='gccgo'2026-08-15T00:39:20.0786921Z GO111MODULE=''2026-08-15T00:39:20.0787268Z GOAMD64='v1'2026-08-15T00:39:20.0787618Z GOARCH='amd64'2026-08-15T00:39:20.0787893Z GOAUTH='netrc'2026-08-15T00:39:20.0788098Z GOBIN=''2026-08-15T00:39:20.0788347Z GOCACHE='/home/runner/.cache/go-build'2026-08-15T00:39:20.0788644Z GOCACHEPROG=''2026-08-15T00:39:20.0788884Z GODEBUG=''2026-08-15T00:39:20.0789141Z GOENV='/home/runner/.config/go/env'2026-08-15T00:39:20.0789423Z GOEXE=''2026-08-15T00:39:20.0789628Z GOEXPERIMENT=''2026-08-15T00:39:20.0789857Z GOFIPS140='off'2026-08-15T00:39:20.0790070Z GOFLAGS=''2026-08-15T00:39:20.0790770Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build1068022096=/tmp/go-build -gno-record-gcc-switches'2026-08-15T00:39:20.0791461Z GOHOSTARCH='amd64'2026-08-15T00:39:20.0792042Z GOHOSTOS='linux'2026-08-15T00:39:20.0792267Z GOINSECURE=''2026-08-15T00:39:20.0792634Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-15T00:39:20.0793062Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-15T00:39:20.0793344Z GONOPROXY=''2026-08-15T00:39:20.0793550Z GONOSUMDB=''2026-08-15T00:39:20.0793761Z GOOS='linux'2026-08-15T00:39:20.0793975Z GOPATH='/home/runner/go'2026-08-15T00:39:20.0794215Z GOPRIVATE=''2026-08-15T00:39:20.0794846Z GOPROXY='https://proxy.golang.org,direct'2026-08-15T00:39:20.0795211Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-15T00:39:20.0795527Z GOSUMDB='sum.golang.org'2026-08-15T00:39:20.0795771Z GOTELEMETRY='local'2026-08-15T00:39:20.0796068Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-15T00:39:20.0796383Z GOTMPDIR=''2026-08-15T00:39:20.0796592Z GOTOOLCHAIN='local'2026-08-15T00:39:20.0796927Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-15T00:39:20.0797287Z GOVCS=''2026-08-15T00:39:20.0797499Z GOVERSION='go1.26.5'2026-08-15T00:39:20.0797842Z GOWORK=''2026-08-15T00:39:20.0798161Z PKG_CONFIG='pkg-config'2026-08-15T00:39:20.0798398Z 2026-08-15T00:39:20.0798853Z ##[endgroup]2026-08-15T00:39:20.1073069Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-15T00:39:20.1073604Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-15T00:39:20.1150951Z shell: /usr/bin/bash -e {0}2026-08-15T00:39:20.1151227Z env:2026-08-15T00:39:20.1151454Z GOTOOLCHAIN: local2026-08-15T00:39:20.1151923Z OSPOOL_EP_OSPoolEPTag: 25-release2026-08-15T00:39:20.1152222Z OSPOOL_EP_CMTag: 25.0-el92026-08-15T00:39:20.1152486Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:39:20.1152777Z ##[endgroup]2026-08-15T00:39:30.0893491Z === RUN TestOSPoolEP2026-08-15T00:39:30.0895920Z === PAUSE TestOSPoolEP2026-08-15T00:39:30.0896366Z === CONT TestOSPoolEP2026-08-15T00:39:30.0897417Z TestOSPoolEP 2026-08-15T00:39:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:30.1000111Z TestOSPoolEP 2026-08-15T00:39:30Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 apply -f /tmp/TestOSPoolEP3518042057]2026-08-15T00:39:30.7765032Z TestOSPoolEP 2026-08-15T00:39:30Z logger.go:67: pod/idtoken-generator created2026-08-15T00:39:30.7880534Z TestOSPoolEP 2026-08-15T00:39:30Z logger.go:67: secret/pool-passwd created2026-08-15T00:39:30.7930209Z TestOSPoolEP 2026-08-15T00:39:30Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-15T00:39:30.7931174Z TestOSPoolEP 2026-08-15T00:39:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:30.7953568Z TestOSPoolEP 2026-08-15T00:39:30Z 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-15T00:39:40.7964581Z TestOSPoolEP 2026-08-15T00:39:40Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-15T00:39:40.7967975Z TestOSPoolEP 2026-08-15T00:39:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:40.7993362Z TestOSPoolEP 2026-08-15T00:39:40Z 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-15T00:39:50.8003755Z TestOSPoolEP 2026-08-15T00:39:50Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-15T00:39:50.8005060Z TestOSPoolEP 2026-08-15T00:39:50Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:50.8031688Z TestOSPoolEP 2026-08-15T00:39:50Z logger.go:67: Pod is now available2026-08-15T00:39:50.8033137Z TestOSPoolEP 2026-08-15T00:39:50Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-15T00:39:51.3766367Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: ***2026-08-15T00:39:51.3843410Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 apply -f /tmp/TestOSPoolEP571932620]2026-08-15T00:39:51.5792845Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: secret/pool-token created2026-08-15T00:39:51.5837452Z TestOSPoolEP 2026-08-15T00:39:51Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-15T00:39:51.5838449Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:51.5861143Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: Secret is now available2026-08-15T00:39:51.5892203Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 apply -k /tmp/kustomize-template-1080294882]2026-08-15T00:39:51.8647845Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: service/test-cm created2026-08-15T00:39:51.8776663Z TestOSPoolEP 2026-08-15T00:39:51Z 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-15T00:39:51.8779282Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: deployment.apps/ospool-ep created2026-08-15T00:39:51.8886285Z TestOSPoolEP 2026-08-15T00:39:51Z logger.go:67: deployment.apps/test-cm created2026-08-15T00:39:51.8952536Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-15T00:39:51.8961050Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:51.9048692Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-15T00:39:51.9050540Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:51.9053955Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:39:51.9062027Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:39:51.9153547Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z 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-15T00:39:51.9194665Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:39:51Z 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-15T00:40:01.9163506Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:01Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:40:01.9164631Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:01Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:01.9196258Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:01Z 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-15T00:40:01.9199318Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:01Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-15T00:40:01.9201143Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:01Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:01.9218091Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:01Z logger.go:67: Deployment is now available2026-08-15T00:40:11.9207292Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:11Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:40:11.9208978Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:11Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:11.9256835Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:11Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-15T00:40:21.9263288Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:21Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-15T00:40:21.9264991Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9302012Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-15T00:40:21Z logger.go:67: Deployment is now available2026-08-15T00:40:21.9303019Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:21.9312636Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:21.9313627Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:21.9314458Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:21.9315345Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:21.9316075Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:21.9316836Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:21.9332161Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9334060Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:21.9335339Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9336608Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:21.9338059Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9361364Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9364703Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9367024Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:21Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:40:21.9427297Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:21Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:40:21.9446338Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:21Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)']2026-08-15T00:40:21.9451300Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:21Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_SINGULARITY]2026-08-15T00:40:22.2405337Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 10s.2026-08-15T00:40:22.2435685Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:22.2439852Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)']' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 10s.2026-08-15T00:40:22.2526455Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:22.2527871Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:40:32.2405433Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_SINGULARITY]2026-08-15T00:40:32.2439293Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:32Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)']2026-08-15T00:40:32.4380922Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-15T00:40:32.4382962Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 10s.2026-08-15T00:40:32.4387177Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-15T00:40:32.4388234Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)']' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 10s.2026-08-15T00:40:42.4391727Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)']2026-08-15T00:40:42.4394944Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:42Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_SINGULARITY]2026-08-15T00:40:42.6332227Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-15T00:40:42Z logger.go:67: true2026-08-15T00:40:42.6337312Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-15T00:40:42.6338582Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: 2026-08-15T00:40:42.6340722Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-kxrs6 LINUX X86_64 Unclaimed Idle 0.000 15988 0+00:00:002026-08-15T00:40:42.6342519Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: 2026-08-15T00:40:42.6343791Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-15T00:40:42.6345106Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: 2026-08-15T00:40:42.6346367Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-15T00:40:42.6347563Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: 2026-08-15T00:40:42.6348736Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-15T00:40:42Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-15T00:40:52.2536187Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:40:52.4175983Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:40:52Z logger.go:67: undefined2026-08-15T00:40:52.4243067Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-15T00:40:52.4244337Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:41:22.4254747Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:41:22.6105914Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:22Z logger.go:67: undefined2026-08-15T00:41:22.6175907Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:41:52.6183063Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:41:52.7832902Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:41:52Z logger.go:67: undefined2026-08-15T00:41:52.7892670Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:42:22.7896867Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:22Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:42:22.9528706Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:22Z logger.go:67: undefined2026-08-15T00:42:22.9601012Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:42:52.9625747Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:52Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:42:53.1268078Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:42:53Z logger.go:67: undefined2026-08-15T00:42:53.1343309Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:43:23.1365696Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:43:23.2992437Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:23Z logger.go:67: undefined2026-08-15T00:43:23.3068896Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:43:53.3093666Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:43:53.4753738Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:43:53Z logger.go:67: undefined2026-08-15T00:43:53.4826610Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:44:23.4831352Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:44:23.6459089Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:23Z logger.go:67: undefined2026-08-15T00:44:23.6535479Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:44:53.6555984Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:44:53.8175136Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:44:53Z logger.go:67: undefined2026-08-15T00:44:53.8251855Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:45:23.8285321Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:23Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:45:24.0257671Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:24Z logger.go:67: undefined2026-08-15T00:45:24.0348382Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-fxvgp failed. Retrying in 30s.2026-08-15T00:45:54.0386606Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:54Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 exec test-cm-54b7d49f85-fxvgp -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-kxrs6",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-15T00:45:54.2185774Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-15T00:45:54Z logger.go:67: true2026-08-15T00:45:54.2287278Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.2382454Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.4421706Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.6032887Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.7341059Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.7372552Z === NAME TestOSPoolEP2026-08-15T00:45:54.7373304Z ospool_ep_test.go:101: ---2026-08-15T00:45:54.7374033Z Events for pod idtoken-generator:2026-08-15T00:45:54.7376006Z 2026-08-15 00:39:30 +0000 UTC Normal Successfully assigned test-ospool-ep-jnwt45/idtoken-generator to minikube2026-08-15T00:45:54.7379574Z 2026-08-15 00:39:31 +0000 UTC Normal Pulling image "htcondor/base"2026-08-15T00:45:54.7381902Z 2026-08-15 00:39:40 +0000 UTC Normal Successfully pulled image "htcondor/base" in 8.857s (8.857s including waiting). Image size: 560910730 bytes.2026-08-15T00:45:54.7383532Z 2026-08-15 00:39:40 +0000 UTC Normal Container created2026-08-15T00:45:54.7384411Z 2026-08-15 00:39:40 +0000 UTC Normal Container started2026-08-15T00:45:54.7385235Z 2026-08-15T00:45:54.7385629Z ---2026-08-15T00:45:54.7386629Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.7407735Z ospool_ep_test.go:101: ---2026-08-15T00:45:54.7408411Z Events for pod ospool-ep-6c7d796845-kxrs6:2026-08-15T00:45:54.7409788Z 2026-08-15 00:39:51 +0000 UTC Normal Successfully assigned test-ospool-ep-jnwt45/ospool-ep-6c7d796845-kxrs6 to minikube2026-08-15T00:45:54.7411292Z 2026-08-15 00:39:52 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-15T00:45:54.7413436Z 2026-08-15 00:39:54 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 1.989s (1.989s including waiting). Image size: 5590942 bytes.2026-08-15T00:45:54.7415233Z 2026-08-15 00:39:54 +0000 UTC Normal Container created2026-08-15T00:45:54.7416005Z 2026-08-15 00:39:54 +0000 UTC Normal Container started2026-08-15T00:45:54.7417076Z 2026-08-15 00:39:54 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-08-15T00:45:54.7418393Z 2026-08-15 00:40:14 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 14.523s (20.218s including waiting). Image size: 1273000231 bytes.2026-08-15T00:45:54.7419263Z 2026-08-15 00:40:14 +0000 UTC Normal Container created2026-08-15T00:45:54.7419737Z 2026-08-15 00:40:15 +0000 UTC Normal Container started2026-08-15T00:45:54.7420058Z 2026-08-15T00:45:54.7420273Z ---2026-08-15T00:45:54.7420883Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.7437590Z ospool_ep_test.go:101: ---2026-08-15T00:45:54.7438286Z Events for pod test-cm-54b7d49f85-fxvgp:2026-08-15T00:45:54.7439666Z 2026-08-15 00:39:51 +0000 UTC Normal Successfully assigned test-ospool-ep-jnwt45/test-cm-54b7d49f85-fxvgp to minikube2026-08-15T00:45:54.7441003Z 2026-08-15 00:39:52 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-08-15T00:45:54.7443131Z 2026-08-15 00:40:00 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 5.978s (7.938s including waiting). Image size: 573055668 bytes.2026-08-15T00:45:54.7444482Z 2026-08-15 00:40:00 +0000 UTC Normal Container created2026-08-15T00:45:54.7445303Z 2026-08-15 00:40:00 +0000 UTC Normal Container started2026-08-15T00:45:54.7445838Z 2026-08-15T00:45:54.7446190Z ---2026-08-15T00:45:54.7447127Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-15T00:45:54.7482513Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 delete -f /tmp/TestOSPoolEP2371300210]2026-08-15T00:45:54.8584916Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-jnwt45 namespace2026-08-15T00:45:54.8669244Z TestOSPoolEP 2026-08-15T00:45:54Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-jnwt45 namespace2026-08-15T00:45:55.8203305Z TestOSPoolEP 2026-08-15T00:45:55Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 delete -f /tmp/TestOSPoolEP331591558]2026-08-15T00:45:55.9210104Z TestOSPoolEP 2026-08-15T00:45:55Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-jnwt45 namespace2026-08-15T00:45:55.9269802Z TestOSPoolEP 2026-08-15T00:45:55Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-jnwt45 delete -k /tmp/kustomize-template-1080294882]2026-08-15T00:45:56.0531370Z TestOSPoolEP 2026-08-15T00:45:56Z logger.go:67: service "test-cm" deleted from test-ospool-ep-jnwt45 namespace2026-08-15T00:45:56.0583468Z TestOSPoolEP 2026-08-15T00:45:56Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-jnwt45 namespace2026-08-15T00:45:56.0634345Z TestOSPoolEP 2026-08-15T00:45:56Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-jnwt45 namespace2026-08-15T00:45:56.0792191Z --- PASS: TestOSPoolEP (53.69s)2026-08-15T00:45:56.0806011Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-08-15T00:45:56.0810787Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.71s)2026-08-15T00:45:56.0812002Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.71s)2026-08-15T00:45:56.0812937Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.30s)2026-08-15T00:45:56.0813674Z PASS2026-08-15T00:45:56.0830108Z ok github.com/osg-htc/k8s-integration-tests/test 386.007s2026-08-15T00:45:56.1953775Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-15T00:45:56.1954450Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-15T00:45:56.2037242Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-15T00:45:56.2037622Z env:2026-08-15T00:45:56.2037837Z GOTOOLCHAIN: local2026-08-15T00:45:56.2038299Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-15T00:45:56.2038774Z ##[endgroup]2026-08-15T00:45:56.2295565Z 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-15T00:45:56.2297482Z ##[group]Run actions/upload-artifact@v42026-08-15T00:45:56.2298261Z with:2026-08-15T00:45:56.2298969Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-08-15T00:45:56.2299748Z path: /tmp/k8s-tests2026-08-15T00:45:56.2314217Z retention-days: 52026-08-15T00:45:56.2314497Z if-no-files-found: warn2026-08-15T00:45:56.2314758Z compression-level: 62026-08-15T00:45:56.2314994Z overwrite: false2026-08-15T00:45:56.2315240Z include-hidden-files: false2026-08-15T00:45:56.2315494Z env:2026-08-15T00:45:56.2315703Z GOTOOLCHAIN: local2026-08-15T00:45:56.2315928Z ##[endgroup]2026-08-15T00:45:56.4584255Z (node:18095) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-15T00:45:56.4585617Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-15T00:45:56.4698496Z With the provided path, there will be 6 files uploaded2026-08-15T00:45:56.4706354Z Artifact name is valid!2026-08-15T00:45:56.4706969Z Root directory input is valid!2026-08-15T00:45:56.7426320Z Beginning upload of artifact content to blob storage2026-08-15T00:45:56.7719950Z (node:18095) [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-15T00:45:57.0237758Z Uploaded bytes 141702026-08-15T00:45:57.0823789Z Finished uploading artifact content to blob storage!2026-08-15T00:45:57.0824696Z SHA256 digest of uploaded artifact zip is 522b39d8d48cbf1ed55eed246fa14a02c704667a28b11fd636fdf13f8de9f28e2026-08-15T00:45:57.0825598Z Finalizing artifact upload2026-08-15T00:45:57.2829886Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 92386187732026-08-15T00:45:57.2832939Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14170 bytes. Artifact ID is 92386187732026-08-15T00:45:57.2834846Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/31854176039/artifacts/92386187732026-08-15T00:45:57.3069348Z Post job cleanup.2026-08-15T00:45:57.4642364Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-15T00:45:57.4691784Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-15T00:45:57.4731465Z /home/runner/go/pkg/mod2026-08-15T00:45:57.4766274Z /home/runner/.cache/go-build2026-08-15T00:45:57.4778564Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-15T00:45:57.4983134Z Post job cleanup.2026-08-15T00:45:57.6034529Z [command]/usr/bin/git version2026-08-15T00:45:57.6086728Z git version 2.54.02026-08-15T00:45:57.6127777Z Temporarily overriding HOME='/home/runner/work/_temp/d764d534-c912-40e2-8e7a-c2585b311cf8' before making global git config changes2026-08-15T00:45:57.6129482Z Adding repository directory to the temporary git global config as a safe directory2026-08-15T00:45:57.6136219Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-15T00:45:57.6189336Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-15T00:45:57.6247292Z [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-15T00:45:57.6581101Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-15T00:45:57.6618778Z http.https://github.com/.extraheader2026-08-15T00:45:57.6632166Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-15T00:45:57.6680884Z [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-15T00:45:57.7029790Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-15T00:45:57.7096602Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-15T00:45:57.7660255Z Cleaning up orphan processes2026-08-15T00:45:57.8615955Z ##[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/