2026-08-01T01:51:15.1023409Z Current runner version: '2.336.0'2026-08-01T01:51:15.1047432Z ##[group]Runner Image Provisioner2026-08-01T01:51:15.1048235Z Hosted Compute Agent2026-08-01T01:51:15.1048803Z Version: 20260707.5632026-08-01T01:51:15.1049380Z Commit: 02667638d2b423fbc733a8e32a88b44996a3ba6e2026-08-01T01:51:15.1050154Z Build Date: 2026-07-07T19:33:50Z2026-08-01T01:51:15.1050816Z Worker ID: {9442e5c5-fe28-4a9b-a404-2493cce91cf6}2026-08-01T01:51:15.1051470Z Azure Region: westus32026-08-01T01:51:15.1051977Z ##[endgroup]2026-08-01T01:51:15.1053426Z ##[group]Operating System2026-08-01T01:51:15.1053977Z Ubuntu2026-08-01T01:51:15.1054481Z 22.04.52026-08-01T01:51:15.1054930Z LTS2026-08-01T01:51:15.1055386Z ##[endgroup]2026-08-01T01:51:15.1055885Z ##[group]Runner Image2026-08-01T01:51:15.1056417Z Image: ubuntu-22.042026-08-01T01:51:15.1056920Z Version: 20260720.234.22026-08-01T01:51:15.1058078Z Included Software: https://github.com/actions/runner-images/blob/ubuntu22/20260720.234/images/ubuntu/Ubuntu2204-Readme.md2026-08-01T01:51:15.1059394Z Image Release: https://github.com/actions/runner-images/releases/tag/ubuntu22%2F20260720.2342026-08-01T01:51:15.1060462Z ##[endgroup]2026-08-01T01:51:15.1061579Z ##[group]GITHUB_TOKEN Permissions2026-08-01T01:51:15.1063686Z Contents: read2026-08-01T01:51:15.1064224Z Metadata: read2026-08-01T01:51:15.1064775Z Packages: read2026-08-01T01:51:15.1065279Z ##[endgroup]2026-08-01T01:51:15.1067639Z Secret source: Actions2026-08-01T01:51:15.1068736Z Prepare workflow directory2026-08-01T01:51:15.1383820Z Prepare all required actions2026-08-01T01:51:15.1435099Z Getting action download info2026-08-01T01:51:15.5146463Z Download action repository 'actions/checkout@v5' (SHA:fbc6f3992d24b796d5a048ff273f7fcc4a7b6c09)2026-08-01T01:51:16.0925182Z Download action repository 'medyagh/setup-minikube@latest' (SHA:e9e035a86bbc3caea26a450bd4dbf9d0c453682e)2026-08-01T01:51:16.6680134Z Download action repository 'actions/setup-go@v6' (SHA:924ae3a1cded613372ab5595356fb5720e22ba16)2026-08-01T01:51:17.2258352Z Download action repository 'actions/upload-artifact@v4' (SHA:ea165f8d65b6e75b540449e92b4886f43607fa02)2026-08-01T01:51:17.4877818Z Uses: mwestphall/k8s-integration-tests/.github/workflows/test-harness.yaml@refs/heads/main (ecca15d77f3152eb2336d20b91eb226964f888bf)2026-08-01T01:51:17.4883701Z ##[group] Inputs2026-08-01T01:51:17.4884681Z testName: TestOSPoolEP2026-08-01T01:51:17.4886415Z testEnv: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-01T01:51:17.4888174Z setupScript: 2026-08-01T01:51:17.4889019Z setupEnv: {}2026-08-01T01:51:17.4890010Z ##[endgroup]2026-08-01T01:51:17.4891284Z Complete job name: ospool-ep / Run OSPool EP test (cvmfsexec, 25-release) / Run Tests2026-08-01T01:51:17.5859169Z ##[group]Run actions/checkout@v52026-08-01T01:51:17.5860674Z with:2026-08-01T01:51:17.5861530Z repository: mwestphall/k8s-integration-tests2026-08-01T01:51:17.5869440Z token: ***2026-08-01T01:51:17.5870309Z ssh-strict: true2026-08-01T01:51:17.5871110Z ssh-user: git2026-08-01T01:51:17.5871876Z persist-credentials: true2026-08-01T01:51:17.5872732Z clean: true2026-08-01T01:51:17.5873517Z sparse-checkout-cone-mode: true2026-08-01T01:51:17.5874437Z fetch-depth: 12026-08-01T01:51:17.5875177Z fetch-tags: false2026-08-01T01:51:17.5875944Z show-progress: true2026-08-01T01:51:17.5876712Z lfs: false2026-08-01T01:51:17.5877411Z submodules: false2026-08-01T01:51:17.5878188Z set-safe-directory: true2026-08-01T01:51:17.5879082Z allow-unsafe-pr-checkout: false2026-08-01T01:51:17.5880420Z ##[endgroup]2026-08-01T01:51:17.6987903Z Syncing repository: mwestphall/k8s-integration-tests2026-08-01T01:51:17.6990778Z ##[group]Getting Git version info2026-08-01T01:51:17.6992322Z Working directory is '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-01T01:51:17.6994414Z [command]/usr/bin/git version2026-08-01T01:51:17.7077953Z git version 2.54.02026-08-01T01:51:17.7107381Z ##[endgroup]2026-08-01T01:51:17.7121693Z Temporarily overriding HOME='/home/runner/work/_temp/5b361208-e875-4c2b-93b9-a77ed18047d6' before making global git config changes2026-08-01T01:51:17.7125788Z Adding repository directory to the temporary git global config as a safe directory2026-08-01T01:51:17.7130072Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-01T01:51:17.7156337Z Deleting the contents of '/home/runner/work/k8s-integration-tests/k8s-integration-tests'2026-08-01T01:51:17.7161952Z ##[group]Initializing the repository2026-08-01T01:51:17.7167626Z [command]/usr/bin/git init /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-01T01:51:17.7243244Z hint: Using 'master' as the name for the initial branch. This default branch name2026-08-01T01:51:17.7246975Z hint: will change to "main" in Git 3.0. To configure the initial branch name2026-08-01T01:51:17.7250717Z hint: to use in all of your new repositories, which will suppress this warning,2026-08-01T01:51:17.7253573Z hint: call:2026-08-01T01:51:17.7254976Z hint:2026-08-01T01:51:17.7256801Z hint: git config --global init.defaultBranch <name>2026-08-01T01:51:17.7258955Z hint:2026-08-01T01:51:17.7261150Z hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and2026-08-01T01:51:17.7264577Z hint: 'development'. The just-created branch can be renamed via this command:2026-08-01T01:51:17.7267352Z hint:2026-08-01T01:51:17.7268848Z hint: git branch -m <name>2026-08-01T01:51:17.7270749Z hint:2026-08-01T01:51:17.7273013Z hint: Disable this message with "git config set advice.defaultBranchName false"2026-08-01T01:51:17.7277131Z Initialized empty Git repository in /home/runner/work/k8s-integration-tests/k8s-integration-tests/.git/2026-08-01T01:51:17.7284675Z [command]/usr/bin/git remote add origin https://github.com/mwestphall/k8s-integration-tests2026-08-01T01:51:17.7319077Z ##[endgroup]2026-08-01T01:51:17.7321370Z ##[group]Disabling automatic garbage collection2026-08-01T01:51:17.7322992Z [command]/usr/bin/git config --local gc.auto 02026-08-01T01:51:17.7352023Z ##[endgroup]2026-08-01T01:51:17.7354072Z ##[group]Setting up auth2026-08-01T01:51:17.7359491Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-01T01:51:17.7389342Z [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-01T01:51:17.7701847Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-01T01:51:17.7735279Z [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-01T01:51:17.7957957Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-01T01:51:17.7993385Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-01T01:51:17.8174738Z [command]/usr/bin/git config --local http.https://github.com/.extraheader AUTHORIZATION: basic ***2026-08-01T01:51:17.8208585Z ##[endgroup]2026-08-01T01:51:17.8210879Z ##[group]Fetching the repository2026-08-01T01:51:17.8220423Z [command]/usr/bin/git -c protocol.version=2 fetch --no-tags --prune --no-recurse-submodules --depth=1 origin +ecca15d77f3152eb2336d20b91eb226964f888bf:refs/remotes/origin/main2026-08-01T01:51:18.3157711Z From https://github.com/mwestphall/k8s-integration-tests2026-08-01T01:51:18.3158648Z * [new ref] ecca15d77f3152eb2336d20b91eb226964f888bf -> origin/main2026-08-01T01:51:18.3176860Z ##[endgroup]2026-08-01T01:51:18.3177660Z ##[group]Determining the checkout info2026-08-01T01:51:18.3180231Z ##[endgroup]2026-08-01T01:51:18.3186553Z [command]/usr/bin/git sparse-checkout disable2026-08-01T01:51:18.3223279Z [command]/usr/bin/git config --local --unset-all extensions.worktreeConfig2026-08-01T01:51:18.3247255Z ##[group]Checking out the ref2026-08-01T01:51:18.3251089Z [command]/usr/bin/git checkout --progress --force -B main refs/remotes/origin/main2026-08-01T01:51:18.3313552Z Switched to a new branch 'main'2026-08-01T01:51:18.3315873Z branch 'main' set up to track 'origin/main'.2026-08-01T01:51:18.3319433Z ##[endgroup]2026-08-01T01:51:18.3352801Z [command]/usr/bin/git log -1 --format=%H2026-08-01T01:51:18.3373187Z ecca15d77f3152eb2336d20b91eb226964f888bf2026-08-01T01:51:18.3575988Z ##[group]Run echo "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT2026-08-01T01:51:18.3576689Z [36;1mecho "TEST_ENV=$(echo "$TEST_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-01T01:51:18.3577267Z [36;1mecho "SETUP_ENV=$(echo "$SETUP_ENV_YAML" | yq -o json | tr -d '\n')" >> $GITHUB_OUTPUT[0m2026-08-01T01:51:18.3654134Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-01T01:51:18.3654550Z env:2026-08-01T01:51:18.3655006Z TEST_ENV_YAML: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-01T01:51:18.3655516Z SETUP_ENV_YAML: {}2026-08-01T01:51:18.3655772Z ##[endgroup]2026-08-01T01:51:18.8097148Z ##[group]Run echo "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT2026-08-01T01:51:18.8097745Z [36;1mecho "TEST_ENV=$(echo -n "$TEST_ENV" | tr '\n' ' ' | sed 's/\s\+/ /g')" >> $GITHUB_OUTPUT[0m2026-08-01T01:51:18.8126671Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-01T01:51:18.8127012Z env:2026-08-01T01:51:18.8127455Z TEST_ENV: { "OSPOOL_EP_OSPoolEPTag": "25-release", "OSPOOL_EP_CMTag": "25.0-el9", "OSPOOL_EP_CvmfsType": "cvmfsexec"}2026-08-01T01:51:18.8127949Z ##[endgroup]2026-08-01T01:51:18.8224253Z ##[group]Run true2026-08-01T01:51:18.8224492Z [36;1mtrue[0m2026-08-01T01:51:18.8252229Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-01T01:51:18.8252595Z ##[endgroup]2026-08-01T01:51:18.8373342Z ##[group]Run medyagh/setup-minikube@latest2026-08-01T01:51:18.8373638Z with:2026-08-01T01:51:18.8373833Z start: true2026-08-01T01:51:18.8374033Z cache: true2026-08-01T01:51:18.8374244Z minikube-version: latest2026-08-01T01:51:18.8374475Z wait: all2026-08-01T01:51:18.8374660Z ##[endgroup]2026-08-01T01:51:18.9617223Z (node:2049) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-01T01:51:18.9618729Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-01T01:51:20.0704818Z [command]/usr/bin/chmod +x /home/runner/work/_temp/cb00f005-fe8b-4b98-a804-03e769647ad02026-08-01T01:51:20.1778214Z [command]/home/runner/bin/minikube version --short2026-08-01T01:51:20.2135045Z v1.38.12026-08-01T01:51:20.4423806Z Cache hit for: preloaded-tarball-v1.38.1-x64-stable-docker2026-08-01T01:51:20.4534987Z (node:2049) [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-01T01:51:20.4543968Z Cache hit for: kic-v1.38.1-x642026-08-01T01:51:21.7153349Z Received 20971520 of 224218863 (9.4%), 20.0 MBs/sec2026-08-01T01:51:21.7409356Z Received 12582912 of 544082657 (2.3%), 12.0 MBs/sec2026-08-01T01:51:22.7155160Z Received 134217728 of 224218863 (59.9%), 64.0 MBs/sec2026-08-01T01:51:22.7408377Z Received 130023424 of 544082657 (23.9%), 62.0 MBs/sec2026-08-01T01:51:23.4235235Z Received 224218863 of 224218863 (100.0%), 78.9 MBs/sec2026-08-01T01:51:23.4236167Z Cache Size: ~214 MB (224218863 B)2026-08-01T01:51:23.4423763Z [command]/usr/bin/tar -xf /home/runner/work/_temp/5c3cd84e-ed34-408d-b306-c957960f3dca/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-01T01:51:23.7686560Z Received 268435456 of 544082657 (49.3%), 84.5 MBs/sec2026-08-01T01:51:23.9411649Z Cache restored successfully2026-08-01T01:51:24.7867233Z Received 402653184 of 544082657 (74.0%), 94.9 MBs/sec2026-08-01T01:51:25.7920835Z Received 536870912 of 544082657 (98.7%), 101.5 MBs/sec2026-08-01T01:51:25.9873956Z Received 544082657 of 544082657 (100.0%), 98.9 MBs/sec2026-08-01T01:51:25.9874935Z Cache Size: ~519 MB (544082657 B)2026-08-01T01:51:26.0014179Z [command]/usr/bin/tar -xf /home/runner/work/_temp/c083c064-ebbf-4404-bc21-6ece3a3dda70/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-01T01:51:26.4537466Z Cache restored successfully2026-08-01T01:51:26.5151736Z [command]/home/runner/bin/minikube start --wait all2026-08-01T01:51:26.5511455Z * minikube v1.38.1 on Ubuntu 22.042026-08-01T01:51:27.0409381Z ! 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-01T01:51:27.0418896Z * Automatically selected the docker driver. Other choices: podman, none, ssh2026-08-01T01:51:27.1049097Z ! Starting v1.39.0, minikube will default to "containerd" container runtime. See #21973 for more info.2026-08-01T01:51:27.1056662Z * Using Docker driver with root privileges2026-08-01T01:51:27.1069043Z * Starting "minikube" primary control-plane node in "minikube" cluster2026-08-01T01:51:27.1076121Z * Pulling base image v0.0.50 ...2026-08-01T01:51:52.8890762Z * Configuring bridge CNI (Container Networking Interface) ...2026-08-01T01:51:58.0747335Z * Verifying Kubernetes components...2026-08-01T01:51:58.0999091Z - Using image gcr.io/k8s-minikube/storage-provisioner:v52026-08-01T01:51:58.7294498Z * Enabled addons: default-storageclass, storage-provisioner2026-08-01T01:52:36.8244286Z * Done! kubectl is now configured to use "minikube" cluster and "default" namespace by default2026-08-01T01:52:36.8324968Z [command]/home/runner/bin/minikube version --short2026-08-01T01:52:36.8667457Z v1.38.12026-08-01T01:52:36.8882958Z ##[group]Run actions/setup-go@v62026-08-01T01:52:36.8883228Z with:2026-08-01T01:52:36.8883426Z go-version: 1.262026-08-01T01:52:36.8883643Z cache: true2026-08-01T01:52:36.8883852Z cache-dependency-path: go.sum2026-08-01T01:52:36.8884101Z check-latest: false2026-08-01T01:52:36.8886472Z token: ***2026-08-01T01:52:36.8886670Z ##[endgroup]2026-08-01T01:52:37.0158368Z Setup go version spec 1.262026-08-01T01:52:37.0211774Z Found in cache @ /opt/hostedtoolcache/go/1.26.5/x642026-08-01T01:52:37.0214209Z Added go to the path2026-08-01T01:52:37.0216858Z Successfully set up Go version 1.262026-08-01T01:52:37.4920612Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-01T01:52:37.4952415Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-01T01:52:37.4977604Z /home/runner/go/pkg/mod2026-08-01T01:52:37.4999131Z /home/runner/.cache/go-build2026-08-01T01:52:37.7047199Z Cache hit for: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-01T01:52:38.9440784Z Received 16777216 of 251839808 (6.7%), 16.0 MBs/sec2026-08-01T01:52:39.9471235Z Received 125829120 of 251839808 (50.0%), 60.0 MBs/sec2026-08-01T01:52:40.9445235Z Received 125829120 of 251839808 (50.0%), 40.0 MBs/sec2026-08-01T01:52:41.9457144Z Received 125829120 of 251839808 (50.0%), 30.0 MBs/sec2026-08-01T01:52:42.9469097Z Received 125829120 of 251839808 (50.0%), 24.0 MBs/sec2026-08-01T01:52:43.9478328Z Received 134217728 of 251839808 (53.3%), 21.3 MBs/sec2026-08-01T01:52:44.9521211Z Received 247645504 of 251839808 (98.3%), 33.7 MBs/sec2026-08-01T01:52:45.4148097Z Received 251839808 of 251839808 (100.0%), 32.1 MBs/sec2026-08-01T01:52:45.4149487Z Cache Size: ~240 MB (251839808 B)2026-08-01T01:52:45.4185944Z [command]/usr/bin/tar -xf /home/runner/work/_temp/ef33683a-09c5-4267-aef8-9cfe8e99ee53/cache.tzst -P -C /home/runner/work/k8s-integration-tests/k8s-integration-tests --use-compress-program unzstd2026-08-01T01:52:47.1331903Z Cache restored successfully2026-08-01T01:52:47.1674433Z Cache restored from key: setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a02026-08-01T01:52:47.1708076Z go version go1.26.5 linux/amd642026-08-01T01:52:47.1708841Z 2026-08-01T01:52:47.1709407Z ##[group]go env2026-08-01T01:52:47.2821206Z AR='ar'2026-08-01T01:52:47.2821493Z CC='gcc'2026-08-01T01:52:47.2821713Z CGO_CFLAGS='-O2 -g'2026-08-01T01:52:47.2821962Z CGO_CPPFLAGS=''2026-08-01T01:52:47.2822185Z CGO_CXXFLAGS='-O2 -g'2026-08-01T01:52:47.2822426Z CGO_ENABLED='1'2026-08-01T01:52:47.2822640Z CGO_FFLAGS='-O2 -g'2026-08-01T01:52:47.2822857Z CGO_LDFLAGS='-O2 -g'2026-08-01T01:52:47.2823075Z CXX='g++'2026-08-01T01:52:47.2823268Z GCCGO='gccgo'2026-08-01T01:52:47.2823465Z GO111MODULE=''2026-08-01T01:52:47.2823670Z GOAMD64='v1'2026-08-01T01:52:47.2823883Z GOARCH='amd64'2026-08-01T01:52:47.2824083Z GOAUTH='netrc'2026-08-01T01:52:47.2824273Z GOBIN=''2026-08-01T01:52:47.2824527Z GOCACHE='/home/runner/.cache/go-build'2026-08-01T01:52:47.2824803Z GOCACHEPROG=''2026-08-01T01:52:47.2825001Z GODEBUG=''2026-08-01T01:52:47.2825244Z GOENV='/home/runner/.config/go/env'2026-08-01T01:52:47.2825491Z GOEXE=''2026-08-01T01:52:47.2825685Z GOEXPERIMENT=''2026-08-01T01:52:47.2825910Z GOFIPS140='off'2026-08-01T01:52:47.2826113Z GOFLAGS=''2026-08-01T01:52:47.2826763Z GOGCCFLAGS='-fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/tmp/go-build4197714097=/tmp/go-build -gno-record-gcc-switches'2026-08-01T01:52:47.2827396Z GOHOSTARCH='amd64'2026-08-01T01:52:47.2827615Z GOHOSTOS='linux'2026-08-01T01:52:47.2827977Z GOINSECURE=''2026-08-01T01:52:47.2828438Z GOMOD='/home/runner/work/k8s-integration-tests/k8s-integration-tests/go.mod'2026-08-01T01:52:47.2828847Z GOMODCACHE='/home/runner/go/pkg/mod'2026-08-01T01:52:47.2829116Z GONOPROXY=''2026-08-01T01:52:47.2829316Z GONOSUMDB=''2026-08-01T01:52:47.2829507Z GOOS='linux'2026-08-01T01:52:47.2830259Z GOPATH='/home/runner/go'2026-08-01T01:52:47.2830519Z GOPRIVATE=''2026-08-01T01:52:47.2830789Z GOPROXY='https://proxy.golang.org,direct'2026-08-01T01:52:47.2831109Z GOROOT='/opt/hostedtoolcache/go/1.26.5/x64'2026-08-01T01:52:47.2831408Z GOSUMDB='sum.golang.org'2026-08-01T01:52:47.2831642Z GOTELEMETRY='local'2026-08-01T01:52:47.2831904Z GOTELEMETRYDIR='/home/runner/.config/go/telemetry'2026-08-01T01:52:47.2832212Z GOTMPDIR=''2026-08-01T01:52:47.2832415Z GOTOOLCHAIN='local'2026-08-01T01:52:47.2832746Z GOTOOLDIR='/opt/hostedtoolcache/go/1.26.5/x64/pkg/tool/linux_amd64'2026-08-01T01:52:47.2833084Z GOVCS=''2026-08-01T01:52:47.2833280Z GOVERSION='go1.26.5'2026-08-01T01:52:47.2833497Z GOWORK=''2026-08-01T01:52:47.2833698Z PKG_CONFIG='pkg-config'2026-08-01T01:52:47.2833845Z 2026-08-01T01:52:47.2834194Z ##[endgroup]2026-08-01T01:52:47.2969345Z ##[group]Run go test ./test -v -count 1 -run TestOSPoolEP -timeout 15m2026-08-01T01:52:47.2970082Z [36;1mgo test ./test -v -count 1 -run TestOSPoolEP -timeout 15m[0m2026-08-01T01:52:47.2999660Z shell: /usr/bin/bash -e {0}2026-08-01T01:52:47.3000049Z env:2026-08-01T01:52:47.3000264Z GOTOOLCHAIN: local2026-08-01T01:52:47.3000513Z OSPOOL_EP_OSPoolEPTag: 25-release2026-08-01T01:52:47.3000794Z OSPOOL_EP_CMTag: 25.0-el92026-08-01T01:52:47.3001054Z OSPOOL_EP_CvmfsType: cvmfsexec2026-08-01T01:52:47.3001314Z ##[endgroup]2026-08-01T01:52:53.1800430Z === RUN TestOSPoolEP2026-08-01T01:52:53.1811108Z === PAUSE TestOSPoolEP2026-08-01T01:52:53.1811572Z === CONT TestOSPoolEP2026-08-01T01:52:53.1812522Z TestOSPoolEP 2026-08-01T01:52:53Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:52:53.1914449Z TestOSPoolEP 2026-08-01T01:52:53Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk apply -f /tmp/TestOSPoolEP3027016810]2026-08-01T01:52:54.6016971Z TestOSPoolEP 2026-08-01T01:52:54Z logger.go:67: pod/idtoken-generator created2026-08-01T01:52:54.6062826Z TestOSPoolEP 2026-08-01T01:52:54Z logger.go:67: secret/pool-passwd created2026-08-01T01:52:54.6105025Z TestOSPoolEP 2026-08-01T01:52:54Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-01T01:52:54.6106406Z TestOSPoolEP 2026-08-01T01:52:54Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:52:54.6122421Z TestOSPoolEP 2026-08-01T01:52:54Z 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-01T01:53:04.6116434Z TestOSPoolEP 2026-08-01T01:53:04Z retry.go:91: Wait for pod idtoken-generator to be provisioned.2026-08-01T01:53:04.6117753Z TestOSPoolEP 2026-08-01T01:53:04Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:04.6142241Z TestOSPoolEP 2026-08-01T01:53:04Z logger.go:67: Pod is now available2026-08-01T01:53:04.6143554Z TestOSPoolEP 2026-08-01T01:53:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec idtoken-generator -- condor_token_create -authz READ -authz ADVERTISE_STARTD -authz ADVERTISE_MASTER -identity condor@test-cm]2026-08-01T01:53:04.9417012Z TestOSPoolEP 2026-08-01T01:53:04Z logger.go:67: ***2026-08-01T01:53:04.9462066Z TestOSPoolEP 2026-08-01T01:53:04Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk apply -f /tmp/TestOSPoolEP3850912091]2026-08-01T01:53:05.1130218Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: secret/pool-token created2026-08-01T01:53:05.1149395Z TestOSPoolEP 2026-08-01T01:53:05Z retry.go:91: Wait for secret pool-token to be provisioned.2026-08-01T01:53:05.1150825Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:05.1169121Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: Secret is now available2026-08-01T01:53:05.1196836Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk apply -k /tmp/kustomize-template-2241621445]2026-08-01T01:53:05.3836433Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: service/test-cm created2026-08-01T01:53:05.3920899Z TestOSPoolEP 2026-08-01T01:53:05Z 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-01T01:53:05.3923136Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: deployment.apps/ospool-ep created2026-08-01T01:53:05.4017630Z TestOSPoolEP 2026-08-01T01:53:05Z logger.go:67: deployment.apps/test-cm created2026-08-01T01:53:05.4062108Z === RUN TestOSPoolEP/Confirm_deployments_become_ready.2026-08-01T01:53:05.4064126Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:05.4126334Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-01T01:53:05.4128657Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:05.4153069Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-01T01:53:05.4154864Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:05.4157472Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z retry.go:103: Wait for deployment test-cm to be provisioned. returned an error: Deployment test-cm is not available, missing 'Progressing' condition. Sleeping for 10s and will try again.2026-08-01T01:53:05.4219166Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:05Z 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-01T01:53:15.4164854Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:15Z retry.go:91: Wait for deployment test-cm to be provisioned.2026-08-01T01:53:15.4166996Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:15.4187471Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:15Z logger.go:67: Deployment is now available2026-08-01T01:53:15.4228089Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:15Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-01T01:53:15.4230189Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:15Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:15.4252093Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:15Z 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-01T01:53:25.4268272Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:25Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-01T01:53:25.4270451Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:25Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:25.4308985Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:25Z 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-01T01:53:35.4323204Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:35Z retry.go:91: Wait for deployment ospool-ep to be provisioned.2026-08-01T01:53:35.4324495Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4348163Z TestOSPoolEP/Confirm_deployments_become_ready. 2026-08-01T01:53:35Z logger.go:67: Deployment is now available2026-08-01T01:53:35.4349235Z === RUN TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-01T01:53:35.4350082Z === PAUSE TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-01T01:53:35.4350809Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-01T01:53:35.4351565Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-01T01:53:35.4352311Z === RUN TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-01T01:53:35.4352972Z === PAUSE TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-01T01:53:35.4353569Z === CONT TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-01T01:53:35.4356337Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4357935Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-01T01:53:35.4358915Z === CONT TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-01T01:53:35.4360950Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4368415Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4381214Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4383755Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4397677Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:53:35Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:53:35.4404643Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)']2026-08-01T01:53:35.4408835Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-01T01:53:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_SINGULARITY]2026-08-01T01:53:35.4433518Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:53:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:53:35.6522877Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 10s.2026-08-01T01:53:35.6773360Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-01T01:53:35.6775174Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:53:35.6790171Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-01T01:53:35.6791501Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)']' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 10s.2026-08-01T01:53:45.6533999Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-01T01:53:45Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_SINGULARITY]2026-08-01T01:53:45.6799748Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:45Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)']2026-08-01T01:53:45.8267285Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_singularity.2026-08-01T01:53:45.8269044Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_SINGULARITY]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 10s.2026-08-01T01:53:45.8636472Z === NAME TestOSPoolEP/Confirm_condor_status_lists_the_EP.2026-08-01T01:53:45.8638042Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)']' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 10s.2026-08-01T01:53:55.8279082Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-01T01:53:55Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_SINGULARITY]2026-08-01T01:53:55.8642301Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:55Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)']2026-08-01T01:53:56.0052518Z TestOSPoolEP/Confirm_EP_container_advertises_singularity. 2026-08-01T01:53:56Z logger.go:67: true2026-08-01T01:53:56.0431170Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: Name OpSys Arch State Activity LoadAv Mem ActvtyTime2026-08-01T01:53:56.0432484Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: 2026-08-01T01:53:56.0433969Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: slot1@CHTC-DEV-EP-mwestphall.ospool-ep-6c7d796845-d9fvd LINUX X86_64 Unclaimed Idle 0.000 15994 0+00:00:002026-08-01T01:53:56.0435416Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: 2026-08-01T01:53:56.0436526Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: Total Owner Claimed Unclaimed Matched Preempting Drain Backfill BkIdle2026-08-01T01:53:56.0437724Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: 2026-08-01T01:53:56.0438860Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: X86_64/LINUX 1 0 0 1 0 0 0 0 02026-08-01T01:53:56.0440121Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: 2026-08-01T01:53:56.0441404Z TestOSPoolEP/Confirm_condor_status_lists_the_EP. 2026-08-01T01:53:56Z logger.go:67: Total 1 0 0 1 0 0 0 0 02026-08-01T01:54:05.6785918Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:54:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:54:05.8131409Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:54:05Z logger.go:67: undefined2026-08-01T01:54:05.8172455Z === NAME TestOSPoolEP/Confirm_EP_container_advertises_CVMFS2026-08-01T01:54:05.8173601Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:54:35.8185662Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:54:35Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:54:35.9547414Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:54:35Z logger.go:67: undefined2026-08-01T01:54:35.9582571Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:55:05.9588813Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:55:05Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:55:06.0989509Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:55:06Z logger.go:67: undefined2026-08-01T01:55:06.1026638Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:55:36.1032045Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:55:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:55:36.2426901Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:55:36Z logger.go:67: undefined2026-08-01T01:55:36.2463451Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:56:06.2488577Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:56:06Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:56:06.3845528Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:56:06Z logger.go:67: undefined2026-08-01T01:56:06.3879523Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:56:36.3885031Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:56:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:56:36.5318494Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:56:36Z logger.go:67: undefined2026-08-01T01:56:36.5348442Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:57:06.5377174Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:57:06Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:57:06.6865904Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:57:06Z logger.go:67: undefined2026-08-01T01:57:06.6905337Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:57:36.6907239Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:57:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:57:36.8266141Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:57:36Z logger.go:67: undefined2026-08-01T01:57:36.8304671Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:58:06.8321473Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:58:06Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:58:06.9749416Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:58:06Z logger.go:67: undefined2026-08-01T01:58:06.9785815Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:58:36.9822626Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:58:36Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:58:37.1172625Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:58:37Z logger.go:67: undefined2026-08-01T01:58:37.1216398Z test_utils.go:102: Exec '[sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]' in pod test-cm-54b7d49f85-27hwz failed. Retrying in 30s.2026-08-01T01:59:07.1218646Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:59:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk exec test-cm-54b7d49f85-27hwz -- sh -c condor_status -const 'regexp("ospool-ep-6c7d796845-d9fvd",Machine)' -af HAS_CVMFS_singularity_opensciencegrid_org]2026-08-01T01:59:07.2789288Z TestOSPoolEP/Confirm_EP_container_advertises_CVMFS 2026-08-01T01:59:07Z logger.go:67: true2026-08-01T01:59:07.2836584Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.2912208Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.4201499Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.5476749Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.6553171Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.6566736Z === NAME TestOSPoolEP2026-08-01T01:59:07.6567310Z ospool_ep_test.go:101: ---2026-08-01T01:59:07.6567746Z Events for pod idtoken-generator:2026-08-01T01:59:07.6568484Z 2026-08-01 01:52:54 +0000 UTC Normal Successfully assigned test-ospool-ep-cc1njk/idtoken-generator to minikube2026-08-01T01:59:07.6569154Z 2026-08-01 01:52:55 +0000 UTC Normal Pulling image "htcondor/base"2026-08-01T01:59:07.6570667Z 2026-08-01 01:53:02 +0000 UTC Normal Successfully pulled image "htcondor/base" in 7.553s (7.553s including waiting). Image size: 560910730 bytes.2026-08-01T01:59:07.6571716Z 2026-08-01 01:53:02 +0000 UTC Normal Container created2026-08-01T01:59:07.6572186Z 2026-08-01 01:53:02 +0000 UTC Normal Container started2026-08-01T01:59:07.6572642Z 2026-08-01T01:59:07.6572974Z ---2026-08-01T01:59:07.6573795Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.6590860Z ospool_ep_test.go:101: ---2026-08-01T01:59:07.6591449Z Events for pod ospool-ep-6c7d796845-d9fvd:2026-08-01T01:59:07.6592208Z 2026-08-01 01:53:05 +0000 UTC Normal Successfully assigned test-ospool-ep-cc1njk/ospool-ep-6c7d796845-d9fvd to minikube2026-08-01T01:59:07.6593051Z 2026-08-01 01:53:05 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/library/alpine:3"2026-08-01T01:59:07.6594085Z 2026-08-01 01:53:08 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/library/alpine:3" in 2.403s (2.403s including waiting). Image size: 5590942 bytes.2026-08-01T01:59:07.6594930Z 2026-08-01 01:53:08 +0000 UTC Normal Container created2026-08-01T01:59:07.6595510Z 2026-08-01 01:53:08 +0000 UTC Normal Container started2026-08-01T01:59:07.6596659Z 2026-08-01 01:53:08 +0000 UTC Normal Pulling image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release"2026-08-01T01:59:07.6598045Z 2026-08-01 01:53:28 +0000 UTC Normal Successfully pulled image "hub.opensciencegrid.org/osg-htc/ospool-ep:25-release" in 14.947s (19.802s including waiting). Image size: 1383210994 bytes.2026-08-01T01:59:07.6598979Z 2026-08-01 01:53:28 +0000 UTC Normal Container created2026-08-01T01:59:07.6599408Z 2026-08-01 01:53:28 +0000 UTC Normal Container started2026-08-01T01:59:07.6599981Z 2026-08-01T01:59:07.6600255Z ---2026-08-01T01:59:07.6601021Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.6617545Z ospool_ep_test.go:101: ---2026-08-01T01:59:07.6618179Z Events for pod test-cm-54b7d49f85-27hwz:2026-08-01T01:59:07.6619379Z 2026-08-01 01:53:05 +0000 UTC Normal Successfully assigned test-ospool-ep-cc1njk/test-cm-54b7d49f85-27hwz to minikube2026-08-01T01:59:07.6620667Z 2026-08-01 01:53:05 +0000 UTC Normal Pulling image "htcondor/cm:25.0-el9"2026-08-01T01:59:07.6622137Z 2026-08-01 01:53:13 +0000 UTC Normal Successfully pulled image "htcondor/cm:25.0-el9" in 5.175s (7.559s including waiting). Image size: 573055668 bytes.2026-08-01T01:59:07.6623271Z 2026-08-01 01:53:13 +0000 UTC Normal Container created2026-08-01T01:59:07.6623987Z 2026-08-01 01:53:13 +0000 UTC Normal Container started2026-08-01T01:59:07.6624462Z 2026-08-01T01:59:07.6624767Z ---2026-08-01T01:59:07.6625574Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Configuring Kubernetes client using config file /home/runner/.kube/config with context 2026-08-01T01:59:07.6656476Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk delete -f /tmp/TestOSPoolEP2916223089]2026-08-01T01:59:07.7585355Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: pod "idtoken-generator" deleted from test-ospool-ep-cc1njk namespace2026-08-01T01:59:07.7662265Z TestOSPoolEP 2026-08-01T01:59:07Z logger.go:67: secret "pool-passwd" deleted from test-ospool-ep-cc1njk namespace2026-08-01T01:59:08.8499578Z TestOSPoolEP 2026-08-01T01:59:08Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk delete -f /tmp/TestOSPoolEP1725794073]2026-08-01T01:59:08.9503537Z TestOSPoolEP 2026-08-01T01:59:08Z logger.go:67: secret "pool-token" deleted from test-ospool-ep-cc1njk namespace2026-08-01T01:59:08.9541742Z TestOSPoolEP 2026-08-01T01:59:08Z logger.go:67: Running command kubectl with args [--namespace test-ospool-ep-cc1njk delete -k /tmp/kustomize-template-2241621445]2026-08-01T01:59:09.0803199Z TestOSPoolEP 2026-08-01T01:59:09Z logger.go:67: service "test-cm" deleted from test-ospool-ep-cc1njk namespace2026-08-01T01:59:09.0854727Z TestOSPoolEP 2026-08-01T01:59:09Z logger.go:67: deployment.apps "ospool-ep" deleted from test-ospool-ep-cc1njk namespace2026-08-01T01:59:09.0898587Z TestOSPoolEP 2026-08-01T01:59:09Z logger.go:67: deployment.apps "test-cm" deleted from test-ospool-ep-cc1njk namespace2026-08-01T01:59:09.1037740Z --- PASS: TestOSPoolEP (44.07s)2026-08-01T01:59:09.1039127Z --- PASS: TestOSPoolEP/Confirm_deployments_become_ready. (30.03s)2026-08-01T01:59:09.1040373Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_singularity. (20.57s)2026-08-01T01:59:09.1041395Z --- PASS: TestOSPoolEP/Confirm_condor_status_lists_the_EP. (20.61s)2026-08-01T01:59:09.1042365Z --- PASS: TestOSPoolEP/Confirm_EP_container_advertises_CVMFS (331.85s)2026-08-01T01:59:09.1043122Z PASS2026-08-01T01:59:09.1058168Z ok github.com/osg-htc/k8s-integration-tests/test 375.936s2026-08-01T01:59:09.1796777Z ##[group]Run echo "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT2026-08-01T01:59:09.1797362Z [36;1mecho "HASH=$(echo -n "$TEST_ENV" | sha256sum | cut -d ' ' -f 1)" >> $GITHUB_OUTPUT[0m2026-08-01T01:59:09.1831538Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}2026-08-01T01:59:09.1831886Z env:2026-08-01T01:59:09.1832112Z GOTOOLCHAIN: local2026-08-01T01:59:09.1832542Z TEST_ENV: OSPOOL_EP_OSPoolEPTag: 25-releaseOSPOOL_EP_CMTag: 25.0-el9OSPOOL_EP_CvmfsType: cvmfsexec2026-08-01T01:59:09.1832993Z ##[endgroup]2026-08-01T01:59:09.1982939Z 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-01T01:59:09.2011135Z ##[group]Run actions/upload-artifact@v42026-08-01T01:59:09.2011613Z with:2026-08-01T01:59:09.2012153Z name: TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce72322026-08-01T01:59:09.2012812Z path: /tmp/k8s-tests2026-08-01T01:59:09.2013133Z retention-days: 52026-08-01T01:59:09.2013454Z if-no-files-found: warn2026-08-01T01:59:09.2013807Z compression-level: 62026-08-01T01:59:09.2014134Z overwrite: false2026-08-01T01:59:09.2014447Z include-hidden-files: false2026-08-01T01:59:09.2014784Z env:2026-08-01T01:59:09.2015053Z GOTOOLCHAIN: local2026-08-01T01:59:09.2015347Z ##[endgroup]2026-08-01T01:59:09.3865488Z (node:17849) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead.2026-08-01T01:59:09.3866921Z (Use `node --trace-deprecation ...` to show where the warning was created)2026-08-01T01:59:09.3941872Z With the provided path, there will be 6 files uploaded2026-08-01T01:59:09.3948026Z Artifact name is valid!2026-08-01T01:59:09.3948674Z Root directory input is valid!2026-08-01T01:59:09.6804095Z Beginning upload of artifact content to blob storage2026-08-01T01:59:09.7230332Z (node:17849) [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-01T01:59:10.0553286Z Uploaded bytes 140602026-08-01T01:59:10.1339727Z Finished uploading artifact content to blob storage!2026-08-01T01:59:10.1341360Z SHA256 digest of uploaded artifact zip is 6214521e715f174a7f7b673596abc30d6c8523a557303e42cab94244779b995a2026-08-01T01:59:10.1343571Z Finalizing artifact upload2026-08-01T01:59:10.3315041Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232.zip successfully finalized. Artifact ID 88115528402026-08-01T01:59:10.3316464Z Artifact TestOSPoolEP-4209b6e6ca3892f0c35b39cc3f79d8ed7c833a027d74e27ee4db89bbc1ce7232 has been successfully uploaded! Final size is 14060 bytes. Artifact ID is 88115528402026-08-01T01:59:10.3323617Z Artifact download URL: https://github.com/mwestphall/k8s-integration-tests/actions/runs/30678801319/artifacts/88115528402026-08-01T01:59:10.3463019Z Post job cleanup.2026-08-01T01:59:10.4657399Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOMODCACHE2026-08-01T01:59:10.4698174Z [command]/opt/hostedtoolcache/go/1.26.5/x64/bin/go env GOCACHE2026-08-01T01:59:10.4768691Z /home/runner/go/pkg/mod2026-08-01T01:59:10.4793685Z /home/runner/.cache/go-build2026-08-01T01:59:10.4807117Z Cache hit occurred on the primary key setup-go-Linux-x64-ubuntu22-go-1.26.5-45f66c1d266a58b1f936914236dfb6a4ee1f407b6f36668ccfaf557ac52c82a0, not saving cache.2026-08-01T01:59:10.4953828Z Post job cleanup.2026-08-01T01:59:10.5862836Z [command]/usr/bin/git version2026-08-01T01:59:10.5910575Z git version 2.54.02026-08-01T01:59:10.5956697Z Temporarily overriding HOME='/home/runner/work/_temp/b97ceb28-c16d-47f6-9f9d-c5880128f1b7' before making global git config changes2026-08-01T01:59:10.5958006Z Adding repository directory to the temporary git global config as a safe directory2026-08-01T01:59:10.5964208Z [command]/usr/bin/git config --global --add safe.directory /home/runner/work/k8s-integration-tests/k8s-integration-tests2026-08-01T01:59:10.6004669Z [command]/usr/bin/git config --local --name-only --get-regexp core\.sshCommand2026-08-01T01:59:10.6038028Z [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-01T01:59:10.6247895Z [command]/usr/bin/git config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader2026-08-01T01:59:10.6271110Z http.https://github.com/.extraheader2026-08-01T01:59:10.6283426Z [command]/usr/bin/git config --local --unset-all http.https://github.com/.extraheader2026-08-01T01:59:10.6316398Z [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-01T01:59:10.6531255Z [command]/usr/bin/git config --local --name-only --get-regexp ^includeIf\.gitdir:2026-08-01T01:59:10.6563893Z [command]/usr/bin/git submodule foreach --recursive git config --local --show-origin --name-only --get-regexp remote.origin.url2026-08-01T01:59:10.6905055Z Cleaning up orphan processes2026-08-01T01:59:10.7549464Z ##[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/