2026-08-06T01:28:16.6106044Z Current runner version: '2.336.0'2026-08-06T01:28:16.6129664Z ##[group]Runner Image Provisioner2026-08-06T01:28:16.6130522Z Hosted Compute Agent2026-08-06T01:28:16.6131227Z Version: 20260707.5632026-08-06T01:28:16.6131871Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-06T01:28:16.6132652Z Build Date: 2026-07-07T19:33:50Z2026-08-06T01:28:16.6133605Z Worker ID: {26e14eb6-fd57-4473-8f9b-73b42839412e}2026-08-06T01:28:16.6134332Z Azure Region: westus22026-08-06T01:28:16.6134971Z ##[endgroup]2026-08-06T01:28:16.6136494Z ##[group]Operating System2026-08-06T01:28:16.6137104Z Ubuntu2026-08-06T01:28:16.6137758Z 22.04.52026-08-06T01:28:16.6138271Z LTS2026-08-06T01:28:16.6138829Z ##[endgroup]2026-08-06T01:28:16.6139416Z ##[group]Runner Image2026-08-06T01:28:16.6140014Z Image: ubuntu-22.042026-08-06T01:28:16.6140654Z Version: 20260720.234.22026-08-06T01:28:16.6141899Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-06T01:28:16.6143757Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-06T01:28:16.6144726Z ##[endgroup]2026-08-06T01:28:16.6145959Z ##[group]GITHUB_TOKEN Permissions2026-08-06T01:28:16.6148048Z Contents: read2026-08-06T01:28:16.6148735Z Metadata: read2026-08-06T01:28:16.6149317Z Packages: read2026-08-06T01:28:16.6149894Z ##[endgroup]2026-08-06T01:28:16.6152307Z Secret source: Actions2026-08-06T01:28:16.6153734Z Prepare workflow directory2026-08-06T01:28:16.6490978Z Prepare all required actions2026-08-06T01:28:16.6541499Z Getting action download info2026-08-06T01:28:17.0066404Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-06T01:28:17.2939152Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-06T01:28:17.5114815Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-06T01:28:17.7428078Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-06T01:28:18.0475395Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-06T01:28:18.0480622Z ##[group] Inputs2026-08-06T01:28:18.0481370Z testName: TestOSPoolEP2026-08-06T01:28:18.0482615Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-06T01:28:18.0484409Z setupScript: 2026-08-06T01:28:18.0485058Z setupEnv: {}2026-08-06T01:28:18.0485701Z ##[endgroup]2026-08-06T01:28:18.0486680Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-08-06T01:28:18.1292037Z ##[group]Run actions/checkout@v52026-08-06T01:28:18.1293617Z with:2026-08-06T01:28:18.1294395Z repository: mwestphall/k8s-integration-tests2026-08-06T01:28:18.1299700Z token: ***2026-08-06T01:28:18.1300363Z ssh-strict: true2026-08-06T01:28:18.1301078Z ssh-user: git2026-08-06T01:28:18.1301749Z persist-credentials: true2026-08-06T01:28:18.1302482Z clean: true2026-08-06T01:28:18.1303354Z sparse-checkout-cone-mode: true2026-08-06T01:28:18.1304144Z fetch-depth: 12026-08-06T01:28:18.1304806Z fetch-tags: false2026-08-06T01:28:18.1305490Z show-progress: true2026-08-06T01:28:18.1306172Z lfs: false2026-08-06T01:28:18.1306804Z submodules: false2026-08-06T01:28:18.1307486Z set-safe-directory: true2026-08-06T01:28:18.1308241Z allow-unsafe-pr-checkout: false2026-08-06T01:28:18.1309359Z ##[endgroup]2026-08-06T01:28:18.2356210Z Syncing repository: mwestphall/k8s-integration-tests2026-08-06T01:28:18.2359161Z ##[group]Getting Git version info2026-08-06T01:28:18.2360449Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-06T01:28:18.2362135Z [command]/usr/bin/git version2026-08-06T01:28:18.2492078Z git version 2.54.02026-08-06T01:28:18.2518099Z ##[endgroup]2026-08-06T01:28:18.2533476Z Temporarily overriding HOME='/home/runner/work/_temp/df7e9b2d-445d-499c-ab18-3d79e1fa42c6' before making global git config changes2026-08-06T01:28:18.2539059Z Adding repository directory to the temporary git global config as a safe directory2026-08-06T01:28:18.2540892Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-06T01:28:18.2580949Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-06T01:28:18.2584929Z ##[group]Initializing the repository2026-08-06T01:28:18.2590200Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-06T01:28:18.2723582Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-06T01:28:18.2726023Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-06T01:28:18.2728306Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-06T01:28:18.2730106Z hint: call:2026-08-06T01:28:18.2731032Z hint:2026-08-06T01:28:18.2732173Z hint: git config --global init.defaultBranch <name>2026-08-06T01:28:18.2733829Z hint:2026-08-06T01:28:18.2735073Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-06T01:28:18.2736389Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-06T01:28:18.2737441Z hint:2026-08-06T01:28:18.2738045Z hint: git branch -m <name>2026-08-06T01:28:18.2738727Z hint:2026-08-06T01:28:18.2739596Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-06T01:28:18.2741201Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-06T01:28:18.2747465Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-06T01:28:18.2780886Z ##[endgroup]2026-08-06T01:28:18.2782559Z ##[group]Disabling automatic garbage collection2026-08-06T01:28:18.2784319Z [command]/usr/bin/git config --local gc.auto 02026-08-06T01:28:18.2817713Z ##[endgroup]2026-08-06T01:28:18.2819087Z ##[group]Setting up auth2026-08-06T01:28:18.2825066Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-06T01:28:18.2868793Z [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-06T01:28:18.3297888Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-06T01:28:18.3338599Z [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-06T01:28:18.3590691Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-06T01:28:18.3627832Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-06T01:28:18.3870817Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-06T01:28:18.3911811Z ##[endgroup]2026-08-06T01:28:18.3913740Z ##[group]Fetching the repository2026-08-06T01:28:18.3921995Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-06T01:28:18.7187586Z From https://github.com/mwestphall/k8s-integration-tests2026-08-06T01:28:18.7189758Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-06T01:28:18.7227135Z ##[endgroup]2026-08-06T01:28:18.7229298Z ##[group]Determining the checkout info2026-08-06T01:28:18.7231261Z ##[endgroup]2026-08-06T01:28:18.7232626Z [command]/usr/bin/git sparse-checkout disable2026-08-06T01:28:18.7288958Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-06T01:28:18.7323173Z ##[group]Checking out the ref2026-08-06T01:28:18.7327095Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-06T01:28:18.7422324Z Switched to a new branch 'main'2026-08-06T01:28:18.7426342Z branch 'main' set up to track 'origin/main'.2026-08-06T01:28:18.7432722Z ##[endgroup]2026-08-06T01:28:18.7471011Z [command]/usr/bin/git log -1 --format=%H2026-08-06T01:28:18.7498568Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-06T01:28:18.7811911Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-06T01:28:18.7813960Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-06T01:28:18.7815523Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-06T01:28:18.7948906Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-06T01:28:18.7950379Z env:2026-08-06T01:28:18.7952170Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-06T01:28:18.7954581Z SETUP_ENV_YAML: {}2026-08-06T01:28:18.7955241Z ##[endgroup]2026-08-06T01:28:19.4180533Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-06T01:28:19.4183492Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-06T01:28:19.4246482Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-06T01:28:19.4248025Z env:2026-08-06T01:28:19.4249970Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-06T01:28:19.4252306Z ##[endgroup]2026-08-06T01:28:19.4441501Z ##[group]Run true2026-08-06T01:28:19.4442453Z [36;1mtrue[0m2026-08-06T01:28:19.4498889Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-06T01:28:19.4500335Z ##[endgroup]2026-08-06T01:28:19.4784682Z ##[group]Run medyagh/setup-minikube@latest2026-08-06T01:28:19.4785862Z with:2026-08-06T01:28:19.4786609Z start: true2026-08-06T01:28:19.4787412Z cache: true2026-08-06T01:28:19.4788240Z minikube-version: latest2026-08-06T01:28:19.4789182Z wait: all2026-08-06T01:28:19.4789959Z ##[endgroup]2026-08-06T01:28:19.6043535Z (node:2059) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-06T01:28:19.6046370Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-06T01:28:20.5826381Z [command]/usr/bin/chmod +x /home/runner/work/_temp/a3262e92-a126-4c5e-b8fe-be33758d2d3b2026-08-06T01:28:20.7491554Z [command]/home/runner/bin/minikube version --short2026-08-06T01:28:20.7950607Z v1.38.12026-08-06T01:28:21.0800395Z Cache hit for: kic-v1.38.1-x642026-08-06T01:28:21.0903267Z (node:2059) [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-06T01:28:21.0910068Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-06T01:28:22.3966707Z Received 4194304 of 544082657 (0.8%), 4.0 MBs/sec2026-08-06T01:28:22.4194185Z Received 4194304 of 224218863 (1.9%), 4.0 MBs/sec2026-08-06T01:28:23.4034352Z Received 121634816 of 544082657 (22.4%), 57.9 MBs/sec2026-08-06T01:28:23.4176301Z Received 113246208 of 224218863 (50.5%), 54.0 MBs/sec2026-08-06T01:28:24.3994826Z Received 226492416 of 544082657 (41.6%), 72.0 MBs/sec2026-08-06T01:28:24.4245737Z Received 215830255 of 224218863 (96.3%), 68.5 MBs/sec2026-08-06T01:28:24.5314012Z Received 224218863 of 224218863 (100.0%), 68.7 MBs/sec2026-08-06T01:28:24.5333325Z Cache Size: ~214 MB (224218863 B)2026-08-06T01:28:24.5713095Z [command]/usr/bin/tar -xf /home/runner/work/_temp/c8ae460c-5ca4-4917-83ce-a2d9b5ad299a/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-06T01:28:25.2203603Z Cache restored successfully2026-08-06T01:28:25.3980319Z Received 327155712 of 544082657 (60.1%), 78.0 MBs/sec2026-08-06T01:28:26.4024594Z Received 452984832 of 544082657 (83.3%), 86.3 MBs/sec2026-08-06T01:28:27.2519543Z Received 544082657 of 544082657 (100.0%), 88.6 MBs/sec2026-08-06T01:28:27.2583678Z Cache Size: ~519 MB (544082657 B)2026-08-06T01:28:27.2664207Z [command]/usr/bin/tar -xf /home/runner/work/_temp/a2dee1ea-7317-43e6-95dc-5678f9651be5/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-06T01:28:28.2379967Z Cache restored successfully2026-08-06T01:28:28.3545534Z [command]/home/runner/bin/minikube start --wait all2026-08-06T01:28:28.3941371Z * minikube v1.38.1 on Ubuntu 22.042026-08-06T01:28:28.5326380Z ! 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-06T01:28:32.3008552Z * Automatically selected the docker driver. Other choices: podman, ssh, none2026-08-06T01:28:32.3609109Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-06T01:28:32.3616577Z * Using Docker driver with root privileges2026-08-06T01:28:32.3649601Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-06T01:28:32.3658252Z * Pulling base image v0.0.50 ...2026-08-06T01:29:00.7053193Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-06T01:29:05.8671261Z * Verifying Kubernetes components...2026-08-06T01:29:05.9050240Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-06T01:29:06.5932041Z * Enabled addons: default-storageclass, storage-provisioner2026-08-06T01:29:23.9138739Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-06T01:29:23.9264760Z [command]/home/runner/bin/minikube version --short2026-08-06T01:29:23.9629116Z v1.38.12026-08-06T01:29:23.9940096Z ##[group]Run actions/setup-go@v62026-08-06T01:29:23.9940381Z with:2026-08-06T01:29:23.9940574Z go-version: 1.262026-08-06T01:29:23.9940773Z cache: true2026-08-06T01:29:23.9940990Z cache-dependency-path: go.sum2026-08-06T01:29:23.9941247Z check-latest: false2026-08-06T01:29:23.9944393Z token: ***2026-08-06T01:29:23.9944596Z ##[endgroup]2026-08-06T01:29:24.1251281Z Setup go version spec 1.262026-08-06T01:29:24.1491697Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-06T01:29:24.1493140Z Added go to the path2026-08-06T01:29:24.1496554Z Successfully set up Go version 1.262026-08-06T01:29:24.7980384Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-06T01:29:24.8022131Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-06T01:29:24.8056463Z /home/runner/go/pkg/mod2026-08-06T01:29:24.8085024Z /home/runner/.cache/go-build2026-08-06T01:29:25.0849916Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-06T01:29:26.3887965Z Received 4194304 of 251839808 (1.7%), 4.0 MBs/sec2026-08-06T01:29:27.3944156Z Received 130023424 of 251839808 (51.6%), 62.0 MBs/sec2026-08-06T01:29:28.3905813Z Received 239075328 of 251839808 (94.9%), 76.0 MBs/sec2026-08-06T01:29:28.5724369Z Received 251839808 of 251839808 (100.0%), 75.5 MBs/sec2026-08-06T01:29:28.5726238Z Cache Size: ~240 MB (251839808 B)2026-08-06T01:29:28.5846317Z [command]/usr/bin/tar -xf /home/runner/work/_temp/28aefaed-9d8f-4548-9415-b691238a2bd7/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-06T01:29:31.6779509Z Cache restored successfully2026-08-06T01:29:31.7457596Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-06T01:29:31.7481809Z go version go1.26.5 linux/amd642026-08-06T01:29:31.7482297Z 2026-08-06T01:29:31.7482608Z ##[group]go env2026-08-06T01:29:31.8280780Z AR='ar'2026-08-06T01:29:31.8281172Z CC='gcc'2026-08-06T01:29:31.8281505Z CGO_CFLAGS='-O2 -g'2026-08-06T01:29:31.8281907Z CGO_CPPFLAGS=''2026-08-06T01:29:31.8282286Z CGO_CXXFLAGS='-O2 -g'2026-08-06T01:29:31.8282670Z CGO_ENABLED='1'2026-08-06T01:29:31.8283276Z CGO_FFLAGS='-O2 -g'2026-08-06T01:29:31.8283664Z CGO_LDFLAGS='-O2 -g'2026-08-06T01:29:31.8284041Z CXX='g++'2026-08-06T01:29:31.8284364Z GCCGO='gccgo'2026-08-06T01:29:31.8284701Z GO111MODULE=''2026-08-06T01:29:31.8285016Z GOAMD64='v1'2026-08-06T01:29:31.8285334Z GOARCH='amd64'2026-08-06T01:29:31.8285664Z GOAUTH='netrc'2026-08-06T01:29:31.8285974Z GOBIN=''2026-08-06T01:29:31.8286332Z GOCACHE='/home/runner/.cache/go-build'2026-08-06T01:29:31.8286770Z GOCACHEPROG=''2026-08-06T01:29:31.8287101Z GODEBUG=''2026-08-06T01:29:31.8287465Z GOENV='/home/runner/.config/go/env'2026-08-06T01:29:31.8287894Z GOEXE=''2026-08-06T01:29:31.8288226Z GOEXPERIMENT=''2026-08-06T01:29:31.8288580Z GOFIPS140='off'2026-08-06T01:29:31.8288915Z GOFLAGS=''2026-08-06T01:29:31.8289988Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build1597010929=/tmp/go-build -gno-record-gcc-switches'2026-08-06T01:29:31.8291113Z GOHOSTARCH='amd64'2026-08-06T01:29:31.8291477Z GOHOSTOS='linux'2026-08-06T01:29:31.8291821Z GOINSECURE=''2026-08-06T01:29:31.8292416Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-06T01:29:31.8293370Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-06T01:29:31.8293805Z GONOPROXY=''2026-08-06T01:29:31.8294145Z GONOSUMDB=''2026-08-06T01:29:31.8294465Z GOOS='linux'2026-08-06T01:29:31.8294809Z GOPATH='/home/runner/go'2026-08-06T01:29:31.8295305Z GOPRIVATE=''2026-08-06T01:29:31.8295719Z GOPROXY='https://proxy.golang.org,direct'2026-08-06T01:29:31.8296263Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-06T01:29:31.8296760Z GOSUMDB='sum.golang.org'2026-08-06T01:29:31.8297167Z GOTELEMETRY='local'2026-08-06T01:29:31.8297916Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-06T01:29:31.8298450Z GOTMPDIR=''2026-08-06T01:29:31.8298796Z GOTOOLCHAIN='local'2026-08-06T01:29:31.8299347Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-06T01:29:31.8299919Z GOVCS=''2026-08-06T01:29:31.8300244Z GOVERSION='go1.26.5'2026-08-06T01:29:31.8300597Z GOWORK=''2026-08-06T01:29:31.8300930Z PKG_CONFIG='pkg-config'2026-08-06T01:29:31.8301177Z 2026-08-06T01:29:31.8301627Z ##[endgroup]2026-08-06T01:29:31.8474782Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-06T01:29:31.8475304Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-06T01:29:31.8531400Z shell: /usr/bin/bash -e {0}2026-08-06T01:29:31.8531653Z env:2026-08-06T01:29:31.8531855Z GOTOOLCHAIN: local2026-08-06T01:29:31.8532105Z OSPOOL_EP_OSPoolEPTag: 25-release2026-08-06T01:29:31.8532394Z OSPOOL_EP_CMTag: 25.0-el92026-08-06T01:29:31.8532653Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-06T01:29:31.8533039Z ##[endgroup]2026-08-06T01:29:38.4153403Z === RUN TestOSPoolEP2026-08-06T01:29:38.4155496Z === PAUSE TestOSPoolEP2026-08-06T01:29:38.4156110Z === CONT TestOSPoolEP2026-08-06T01:29:38.4473009Z TestOSPoolEP 2026-08-06T01:29:38Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:29:38.4644265Z TestOSPoolEP 2026-08-06T01:29:38Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt apply -f /tmp/TestOSPoolEP2868007205]2026-08-06T01:29:39.5108686Z TestOSPoolEP 2026-08-06T01:29:39Z logger.go:67: pod/idtoken-generator created2026-08-06T01:29:39.5208379Z TestOSPoolEP 2026-08-06T01:29:39Z logger.go:67: secret/pool-passwd created2026-08-06T01:29:39.5243775Z TestOSPoolEP 2026-08-06T01:29:39Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-06T01:29:39.5245899Z TestOSPoolEP 2026-08-06T01:29:39Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:29:39.5267754Z TestOSPoolEP 2026-08-06T01:29:39Z retry.go:103: Wait for pod idtoken-generator to be provisioned. returned an error: Pod idtoken-generator is not available, reason: , message: . Sleeping for 10s and will try again.2026-08-06T01:29:49.5277551Z TestOSPoolEP 2026-08-06T01:29:49Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-06T01:29:49.5279071Z TestOSPoolEP 2026-08-06T01:29:49Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:29:49.5315514Z TestOSPoolEP 2026-08-06T01:29:49Z retry.go:103: Wait for pod idtoken-generator to be provisioned. returned an error: Pod idtoken-generator is not available, reason: , message: . Sleeping for 10s and will try again.2026-08-06T01:29:59.5317179Z TestOSPoolEP 2026-08-06T01:29:59Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-06T01:29:59.5318561Z TestOSPoolEP 2026-08-06T01:29:59Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:29:59.5339132Z TestOSPoolEP 2026-08-06T01:29:59Z logger.go:67: Pod is now available2026-08-06T01:29:59.5341186Z TestOSPoolEP 2026-08-06T01:29:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-06T01:29:59.9864981Z TestOSPoolEP 2026-08-06T01:29:59Z logger.go:67: ***2026-08-06T01:29:59.9927174Z TestOSPoolEP 2026-08-06T01:29:59Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt apply -f /tmp/TestOSPoolEP393435814]2026-08-06T01:30:00.1866663Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: secret/pool-token created2026-08-06T01:30:00.1952457Z TestOSPoolEP 2026-08-06T01:30:00Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-06T01:30:00.1975651Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:00.1978144Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: Secret is now available2026-08-06T01:30:00.2035902Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt apply -k /tmp/kustomize-template-2720209349]2026-08-06T01:30:00.5416520Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: service/test-cm created2026-08-06T01:30:00.5513639Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: deployment.apps/ospool-ep created2026-08-06T01:30:00.5518426Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: Warning: spec.template.spec.containers[0].env[8]: hides previous definition of "OSG_SQUID_LOCATION", which may be dropped when using apply2026-08-06T01:30:00.5638401Z TestOSPoolEP 2026-08-06T01:30:00Z logger.go:67: deployment.apps/test-cm created2026-08-06T01:30:00.5701519Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-06T01:30:00.5705246Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:00.5796059Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-06T01:30:00.5817264Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:00.5819373Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-06T01:30:00.5821287Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:00.5848294Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "ospool-ep-6c7d796845". Sleeping for 10s and will try again.2026-08-06T01:30:00.5876813Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:00Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: NewReplicaSetCreated, message: Created new replica set "test-cm-54b7d49f85". Sleeping for 10s and will try again.2026-08-06T01:30:10.5849237Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:10Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-06T01:30:10.5851195Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:10.5874870Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:10Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-06T01:30:10.5877192Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:10Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:10.5885963Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:10Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-06T01:30:10.5896238Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:10Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "test-cm-54b7d49f85" is progressing.. Sleeping for 10s and will try again.2026-08-06T01:30:20.5900422Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:20Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-06T01:30:20.5903767Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:20.5908478Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:20Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-06T01:30:20.5916485Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:20Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:20.5952425Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:20Z logger.go:67: Deployment is now available2026-08-06T01:30:20.5955619Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:20Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-06T01:30:30.5964586Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:30Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-06T01:30:30.5966916Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:30Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:30.5998429Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:30Z retry.go:103: Wait for deployment ospool-ep to be provisioned. returned an error: Deployment ospool-ep is not available as 'Progressing' condition indicates that the Deployment is not complete, status: True, reason: ReplicaSetUpdated, message: ReplicaSet "ospool-ep-6c7d796845" is progressing.. Sleeping for 10s and will try again.2026-08-06T01:30:40.6016126Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:40Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-06T01:30:40.6017984Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6047551Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-06T01:30:40Z logger.go:67: Deployment is now available2026-08-06T01:30:40.6048587Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-06T01:30:40.6049283Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-06T01:30:40.6050070Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-06T01:30:40.6050858Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-06T01:30:40.6051591Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-06T01:30:40.6052308Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-06T01:30:40.6054224Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-06T01:30:40.6062563Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6064264Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-06T01:30:40.6068523Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6070409Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-06T01:30:40.6072268Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6100883Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6103894Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6106121Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-06T01:30:40Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:30:40.6127184Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:30:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:30:40.6137834Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)']2026-08-06T01:30:40.6143448Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-06T01:30:40Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_SINGULARITY]2026-08-06T01:30:40.9073924Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 10s.2026-08-06T01:30:40.9097168Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-06T01:30:40.9099877Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)']' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 10s.2026-08-06T01:30:40.9126833Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-06T01:30:40.9128527Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:30:50.9082529Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-06T01:30:50Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_SINGULARITY]2026-08-06T01:30:50.9107626Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:50Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)']2026-08-06T01:30:51.2056895Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-06T01:30:51Z logger.go:67: true2026-08-06T01:30:51.2063852Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-06T01:30:51.2065137Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: 2026-08-06T01:30:51.2066475Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-gcmdj LINUX X86_64 Unclaimed Idle 0.000 15991 0+00:00:002026-08-06T01:30:51.2067606Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: 2026-08-06T01:30:51.2068562Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-06T01:30:51.2071938Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: 2026-08-06T01:30:51.2074537Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-06T01:30:51.2075994Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: 2026-08-06T01:30:51.2077872Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-06T01:30:51Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-06T01:31:10.9136408Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:31:10Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:31:11.0575774Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:31:11Z logger.go:67: undefined2026-08-06T01:31:11.0645845Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:31:41.0649657Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:31:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:31:41.2160902Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:31:41Z logger.go:67: undefined2026-08-06T01:31:41.2216052Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:32:11.2218548Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:32:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:32:11.3687726Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:32:11Z logger.go:67: undefined2026-08-06T01:32:11.3755333Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:32:41.3760615Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:32:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:32:41.5257747Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:32:41Z logger.go:67: undefined2026-08-06T01:32:41.5312389Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:33:11.5326030Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:33:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:33:11.6837693Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:33:11Z logger.go:67: undefined2026-08-06T01:33:11.6892080Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:33:41.6908608Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:33:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:33:41.8377108Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:33:41Z logger.go:67: undefined2026-08-06T01:33:41.8436726Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:34:11.8463646Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:34:11Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:34:11.9921028Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:34:11Z logger.go:67: undefined2026-08-06T01:34:11.9976783Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:34:41.9981217Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:34:41Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:34:42.1520137Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:34:42Z logger.go:67: undefined2026-08-06T01:34:42.1576038Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:35:12.1579444Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:35:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:35:12.3183817Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:35:12Z logger.go:67: undefined2026-08-06T01:35:12.3240580Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:35:42.3247317Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:35:42Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:35:42.4686389Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:35:42Z logger.go:67: undefined2026-08-06T01:35:42.4760515Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-q8r2w failed. Retrying in 30s.2026-08-06T01:36:12.4797160Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:36:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt exec test-cm-54b7d49f85-q8r2w -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-gcmdj",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-06T01:36:12.6214101Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-06T01:36:12Z logger.go:67: true2026-08-06T01:36:12.6270836Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.6340990Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.7586669Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.8739118Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.9756244Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.9791368Z === NAME TestOSPoolEP2026-08-06T01:36:12.9792082Z ospool_ep_test.go:101: ---2026-08-06T01:36:12.9792714Z Events for pod idtoken-generator:2026-08-06T01:36:12.9794272Z 2026-08-06 01:29:39 +0000 UTC Normal Successfully assigned test-ospool-ep-wx3jyt/idtoken-generator to minikube2026-08-06T01:36:12.9795025Z 2026-08-06 01:29:40 +0000 UTC Normal Pulling image "htcondor/base"2026-08-06T01:36:12.9796080Z 2026-08-06 01:29:49 +0000 UTC Normal Successfully pulled image "htcondor/base" in 9.295s (9.295s including waiting). Image size: 560910730 bytes.2026-08-06T01:36:12.9797485Z 2026-08-06 01:29:49 +0000 UTC Normal Container created2026-08-06T01:36:12.9798375Z 2026-08-06 01:29:49 +0000 UTC Normal Container started2026-08-06T01:36:12.9798940Z 2026-08-06T01:36:12.9799301Z ---2026-08-06T01:36:12.9800214Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.9814983Z ospool_ep_test.go:101: ---2026-08-06T01:36:12.9815996Z Events for pod ospool-ep-6c7d796845-gcmdj:2026-08-06T01:36:12.9818232Z 2026-08-06 01:30:00 +0000 UTC Normal Successfully assigned test-ospool-ep-wx3jyt/ospool-ep-6c7d796845-gcmdj to minikube2026-08-06T01:36:12.9820146Z 2026-08-06 01:30:01 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-06T01:36:12.9822404Z 2026-08-06 01:30:04 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.929s (2.929s including waiting). Image size: 5590942 bytes.2026-08-06T01:36:12.9823997Z 2026-08-06 01:30:04 +0000 UTC Normal Container created2026-08-06T01:36:12.9824874Z 2026-08-06 01:30:04 +0000 UTC Normal Container started2026-08-06T01:36:12.9826187Z 2026-08-06 01:30:04 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-08-06T01:36:12.9828233Z 2026-08-06 01:30:29 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 16.09s (25.57s including waiting). Image size: 1391641574 bytes.2026-08-06T01:36:12.9829861Z 2026-08-06 01:30:30 +0000 UTC Normal Container created2026-08-06T01:36:12.9830729Z 2026-08-06 01:30:30 +0000 UTC Normal Container started2026-08-06T01:36:12.9831333Z 2026-08-06T01:36:12.9831705Z ---2026-08-06T01:36:12.9832691Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.9838563Z ospool_ep_test.go:101: ---2026-08-06T01:36:12.9839499Z Events for pod test-cm-54b7d49f85-q8r2w:2026-08-06T01:36:12.9840976Z 2026-08-06 01:30:00 +0000 UTC Normal Successfully assigned test-ospool-ep-wx3jyt/test-cm-54b7d49f85-q8r2w to minikube2026-08-06T01:36:12.9842373Z 2026-08-06 01:30:01 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-08-06T01:36:12.9843759Z 2026-08-06 01:30:13 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 9.893s (12.808s including waiting). Image size: 573055668 bytes.2026-08-06T01:36:12.9845159Z 2026-08-06 01:30:13 +0000 UTC Normal Container created2026-08-06T01:36:12.9845923Z 2026-08-06 01:30:13 +0000 UTC Normal Container started2026-08-06T01:36:12.9846246Z 2026-08-06T01:36:12.9846450Z ---2026-08-06T01:36:12.9846999Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-06T01:36:12.9870881Z TestOSPoolEP 2026-08-06T01:36:12Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt delete -f /tmp/TestOSPoolEP3348364687]2026-08-06T01:36:13.0780501Z TestOSPoolEP 2026-08-06T01:36:13Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-wx3jyt namespace2026-08-06T01:36:13.0824956Z TestOSPoolEP 2026-08-06T01:36:13Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-wx3jyt namespace2026-08-06T01:36:14.4376407Z TestOSPoolEP 2026-08-06T01:36:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt delete -f /tmp/TestOSPoolEP257963209]2026-08-06T01:36:14.5252037Z TestOSPoolEP 2026-08-06T01:36:14Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-wx3jyt namespace2026-08-06T01:36:14.5292569Z TestOSPoolEP 2026-08-06T01:36:14Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-wx3jyt delete -k /tmp/kustomize-template-2720209349]2026-08-06T01:36:14.6377448Z TestOSPoolEP 2026-08-06T01:36:14Z logger.go:67: service "test-cm" deleted from test-ospool-ep-wx3jyt namespace2026-08-06T01:36:14.6426992Z TestOSPoolEP 2026-08-06T01:36:14Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-wx3jyt namespace2026-08-06T01:36:14.6463167Z TestOSPoolEP 2026-08-06T01:36:14Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-wx3jyt namespace2026-08-06T01:36:14.6573336Z --- PASS: TestOSPoolEP (64.22s)2026-08-06T01:36:14.6597709Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (40.03s)2026-08-06T01:36:14.6599449Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (10.61s)2026-08-06T01:36:14.6600783Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (10.61s)2026-08-06T01:36:14.6602062Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (332.02s)2026-08-06T01:36:14.6603188Z PASS2026-08-06T01:36:14.6615522Z ok github.com/osg-htc/k8s-integration-tests/test 396.259s2026-08-06T01:36:14.7704735Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-06T01:36:14.7705412Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-06T01:36:14.7765724Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-06T01:36:14.7766084Z env:2026-08-06T01:36:14.7766281Z GOTOOLCHAIN: local2026-08-06T01:36:14.7766732Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-06T01:36:14.7767209Z ##[endgroup]2026-08-06T01:36:14.7997509Z 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-06T01:36:14.8000049Z ##[group]Run actions/upload-artifact@v42026-08-06T01:36:14.8000677Z with:2026-08-06T01:36:14.8001410Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-08-06T01:36:14.8001913Z path: /tmp/k8s-tests2026-08-06T01:36:14.8002149Z retention-days: 52026-08-06T01:36:14.8002369Z if-no-files-found: warn2026-08-06T01:36:14.8003204Z compression-level: 62026-08-06T01:36:14.8003501Z overwrite: false2026-08-06T01:36:14.8003730Z include-hidden-files: false2026-08-06T01:36:14.8003987Z env:2026-08-06T01:36:14.8004176Z GOTOOLCHAIN: local2026-08-06T01:36:14.8004396Z ##[endgroup]2026-08-06T01:36:15.0002596Z (node:18034) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-06T01:36:15.0003624Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-06T01:36:15.0095736Z With the provided path, there will be 6 files uploaded2026-08-06T01:36:15.0097243Z Artifact name is valid!2026-08-06T01:36:15.0097937Z Root directory input is valid!2026-08-06T01:36:15.3448293Z Beginning upload of artifact content to blob storage2026-08-06T01:36:15.3750261Z (node:18034) [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-06T01:36:15.7560022Z Uploaded bytes 141302026-08-06T01:36:15.8435075Z Finished uploading artifact content to blob storage!2026-08-06T01:36:15.8436217Z SHA256 digest of uploaded artifact zip is 200243d13cb8adb14307d2e6dc9cd8e3a77749cddb3e17707dd1d32a798292242026-08-06T01:36:15.8438213Z Finalizing artifact upload2026-08-06T01:36:16.0555059Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 89528570902026-08-06T01:36:16.0556656Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14130 bytes. Artifact ID is 89528570902026-08-06T01:36:16.0561432Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/31062817703/artifacts/89528570902026-08-06T01:36:16.0734767Z Post job cleanup.2026-08-06T01:36:16.2025043Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-06T01:36:16.2107386Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-06T01:36:16.2163598Z /home/runner/go/pkg/mod2026-08-06T01:36:16.2166273Z /home/runner/.cache/go-build2026-08-06T01:36:16.2201865Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-06T01:36:16.2386843Z Post job cleanup.2026-08-06T01:36:16.3351277Z [command]/usr/bin/git version2026-08-06T01:36:16.3397024Z git version 2.54.02026-08-06T01:36:16.3455193Z Temporarily overriding HOME='/home/runner/work/_temp/f476cd45-8834-441c-ab03-42bcdcb96c75' before making global git config changes2026-08-06T01:36:16.3463748Z Adding repository directory to the temporary git global config as a safe directory2026-08-06T01:36:16.3465022Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-06T01:36:16.3515309Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-06T01:36:16.3574405Z [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-06T01:36:16.3988806Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-06T01:36:16.4023805Z http.https://github.com/.extraheader2026-08-06T01:36:16.4035037Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-06T01:36:16.4080028Z [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-06T01:36:16.4428517Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-06T01:36:16.4507484Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-06T01:36:16.5102596Z Cleaning up orphan processes2026-08-06T01:36:16.5880956Z ##[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/