Page MenuHomePhabricator

Timeouts when pulling artifacts from github with toolforge build
Closed, ResolvedPublicBUG REPORT

Description

Steps to replicate the issue (include links if applicable):

What happens?:

Deleted 1 artifacts from harbor repository tool-spacemedia/tool-spacemedia

If you still need more space, please contact a toolforge maintainer (https://w.wiki/6Zuu).
Waiting for the logs... if the build just started this might take a minute
[prepare] 2024-10-15T18:29:49.652725020Z 2024/10/15 18:29:49 Entrypoint initialization
[place-scripts] 2024-10-15T18:29:50.202567273Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-0-5xn52
[place-scripts] 2024-10-15T18:29:50.247844195Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-2-585rn
[place-scripts] 2024-10-15T18:29:50.292877321Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-3-jnbzh
[place-scripts] 2024-10-15T18:29:50.343726868Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-4-w4lkj
[place-scripts] 2024-10-15T18:29:50.381335371Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-9-wh278
[place-scripts] 2024-10-15T18:29:50.412142466Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-10-sfjlr
[place-scripts] 2024-10-15T18:29:50.456506356Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-11-v7pcr
[place-scripts] 2024-10-15T18:29:50.488363530Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-12-mkb9h
[place-scripts] 2024-10-15T18:29:50.520312168Z 2024/10/15 18:29:50 Decoded script /tekton/scripts/script-13-bc9zl
[step-clone] 2024-10-15T18:29:59.181086489Z + '[' false '=' true ]
[step-clone] 2024-10-15T18:29:59.181200234Z + '[' false '=' true ]
[step-clone] 2024-10-15T18:29:59.181219629Z + '[' '$(workspaces.ssl-ca-directory.bound)' '=' true ]
[step-clone] 2024-10-15T18:29:59.181234642Z + CHECKOUT_DIR=/workspace/
[step-clone] 2024-10-15T18:29:59.181246253Z + '[' true '=' true ]
[step-clone] 2024-10-15T18:29:59.181258022Z + cleandir
[step-clone] 2024-10-15T18:29:59.181269560Z + '[' -d /workspace/ ]
[step-clone] 2024-10-15T18:29:59.181281638Z + rm -rf '/workspace//*'
[step-clone] 2024-10-15T18:29:59.183213863Z + rm -rf '/workspace//.[!.]*'
[step-clone] 2024-10-15T18:29:59.185410784Z + rm -rf '/workspace//..?*'
[step-clone] 2024-10-15T18:29:59.187241914Z + test -z
[step-clone] 2024-10-15T18:29:59.187291106Z + test -z
[step-clone] 2024-10-15T18:29:59.187364846Z + test -z
[step-clone] 2024-10-15T18:29:59.187488052Z + git config --global --add safe.directory /workspace
[step-clone] 2024-10-15T18:29:59.192828560Z + /ko-app/git-init '-url=https://gitlab.wikimedia.org/toolforge-repos/spacemedia' '-revision=main' '-refspec=' '-path=/workspace/' '-sslVerify=true' '-submodules=true' '-depth=1' '-sparseCheckoutDirectories='
[step-clone] 2024-10-15T18:30:01.791674001Z {"level":"info","ts":1729017001.7909424,"caller":"git/git.go:176","msg":"Successfully cloned https://gitlab.wikimedia.org/toolforge-repos/spacemedia @ fe5fd01d4620e804daa39d34b190f5ed12337cc6 (grafted, HEAD, origin/main) in path /workspace/"}
[step-clone] 2024-10-15T18:30:01.853989091Z {"level":"info","ts":1729017001.8536747,"caller":"git/git.go:215","msg":"Successfully initialized and updated submodules in path /workspace/"}
[step-clone] 2024-10-15T18:30:01.891899681Z + cd /workspace/
[step-clone] 2024-10-15T18:30:01.892635171Z + git rev-parse HEAD
[step-clone] 2024-10-15T18:30:01.896328415Z + RESULT_SHA=fe5fd01d4620e804daa39d34b190f5ed12337cc6
[step-clone] 2024-10-15T18:30:01.896390548Z + EXIT_CODE=0
[step-clone] 2024-10-15T18:30:01.896791393Z + '[' 0 '!=' 0 ]
[step-copy-builder-to-tmp] 2024-10-15T18:29:52.781388201Z 2024/10/15 18:29:52 warning: unsuccessful cred copy: ".docker" from "/tekton/creds" to "/tekton/home": unable to open destination: open /tekton/home/.docker/config.json: permission denied
[step-copy-builder-to-tmp] 2024-10-15T18:30:02.791488590Z + cp -a /cnb/buildpacks /tmp_builder/
[step-copy-builder-to-tmp] 2024-10-15T18:30:02.990968071Z + cp /cnb/order.toml /tmp_builder/
[step-prepare] 2024-10-15T18:30:03.008609926Z Running on node tools-k8s-worker-105
[step-prepare] 2024-10-15T18:30:03.008723452Z > Setting permissions on '/cache'...
[step-prepare] 2024-10-15T18:30:03.013026642Z > Setting permissions on '/tekton/home'...
[step-prepare] 2024-10-15T18:30:03.015149056Z > Setting permissions on '/layers'...
[step-prepare] 2024-10-15T18:30:03.017269623Z > Setting permissions on '/workspace'...
[step-prepare] 2024-10-15T18:30:03.042572945Z > Parsing additional configuration...
[step-prepare] 2024-10-15T18:30:03.042647899Z -> Parsing env variables...
[step-prepare] 2024-10-15T18:30:03.043072403Z > Processing any environment variables...
[step-prepare] 2024-10-15T18:30:03.043130980Z --> Creating 'env' directory: /platform/env
[step-prepare] 2024-10-15T18:30:03.044312094Z --> Writing /platform/env/MAVEN_CUSTOM_OPTS...
[step-inject-buildpacks] 2024-10-15T18:30:03.218698356Z + set -o nounset
[step-inject-buildpacks] 2024-10-15T18:30:03.218799278Z + set -o errexit
[step-inject-buildpacks] 2024-10-15T18:30:03.218814990Z + set -o pipefail
[step-inject-buildpacks] 2024-10-15T18:30:03.218852273Z + JSON_TOML_URL=https://github.com/pelletier/go-toml/releases/download/v2.1.1/jsontoml_2.1.1_linux_amd64.tar.xz
[step-inject-buildpacks] 2024-10-15T18:30:03.218918739Z + TOML_JSON_URL=https://github.com/pelletier/go-toml/releases/download/v2.1.1/tomljson_2.1.1_linux_amd64.tar.xz
[step-inject-buildpacks] 2024-10-15T18:30:03.219494344Z + JQ_URL=https://github.com/jqlang/jq/releases/download/jq-1.7.1/jq-linux-amd64
[step-inject-buildpacks] 2024-10-15T18:30:03.223064292Z + main
[step-inject-buildpacks] 2024-10-15T18:30:03.223333869Z + local buildpacks_dir=/tmp_builder/buildpacks
[step-inject-buildpacks] 2024-10-15T18:30:03.223873682Z + local order_toml=/tmp_builder/order.toml
[step-inject-buildpacks] 2024-10-15T18:30:03.223908775Z + download_parsing_tools
[step-inject-buildpacks] 2024-10-15T18:30:03.224774171Z + wget -O - https://github.com/pelletier/go-toml/releases/download/v2.1.1/jsontoml_2.1.1_linux_amd64.tar.xz
[step-inject-buildpacks] 2024-10-15T18:30:03.224828655Z + tar xvJ
[step-inject-buildpacks] 2024-10-15T18:30:03.241941594Z Connecting to github.com (140.82.114.3:443)
[step-inject-buildpacks] 2024-10-15T18:32:14.103875383Z wget: can't connect to remote host (140.82.114.3): Operation timed out
[step-inject-buildpacks] 2024-10-15T18:32:14.104443355Z tar: short read
[step-set-stack-toml] 2024-10-15T18:29:53.657266897Z 2024/10/15 18:29:53 warning: unsuccessful cred copy: ".docker" from "/tekton/creds" to "/tekton/home": unable to open destination: open /tekton/home/.docker/config.json: permission denied
[step-set-stack-toml] 2024-10-15T18:32:14.731110578Z 2024/10/15 18:32:14 Skipping step because a previous step failed
[step-analyze] 2024-10-15T18:29:54.120887046Z 2024/10/15 18:29:54 warning: unsuccessful cred copy: ".docker" from "/tekton/creds" to "/tekton/home": unable to open destination: open /tekton/home/.docker/config.json: permission denied
[step-analyze] 2024-10-15T18:32:15.201568012Z 2024/10/15 18:32:15 Skipping step because a previous step failed
[step-detect] 2024-10-15T18:32:15.609868559Z 2024/10/15 18:32:15 Skipping step because a previous step failed
[step-restore] 2024-10-15T18:29:55.045370390Z 2024/10/15 18:29:55 warning: unsuccessful cred copy: ".docker" from "/tekton/creds" to "/tekton/home": unable to open destination: open /tekton/home/.docker/config.json: permission denied
[step-restore] 2024-10-15T18:32:16.106177514Z 2024/10/15 18:32:16 Skipping step because a previous step failed
[step-build] 2024-10-15T18:32:16.608510173Z 2024/10/15 18:32:16 Skipping step because a previous step failed
[step-fix-nested-procfile-launcher] 2024-10-15T18:32:16.842281967Z 2024/10/15 18:32:16 Skipping step because a previous step failed
[step-fix-imagemagick-config] 2024-10-15T18:32:17.057585756Z 2024/10/15 18:32:17 Skipping step because a previous step failed
[step-fix-permissions] 2024-10-15T18:32:17.305964680Z 2024/10/15 18:32:17 Skipping step because a previous step failed
[step-export] 2024-10-15T18:29:56.690534159Z 2024/10/15 18:29:56 warning: unsuccessful cred copy: ".docker" from "/tekton/creds" to "/tekton/home": unable to open destination: open /tekton/home/.docker/config.json: permission denied
[step-export] 2024-10-15T18:32:17.759868405Z 2024/10/15 18:32:17 Skipping step because a previous step failed
[step-results] 2024-10-15T18:32:17.971738968Z 2024/10/15 18:32:17 Skipping step because a previous step failed

What should have happened instead?:
No error. I runthis command several times a week without problem.

Software version (on Special:Version page; skip for WMF-hosted wikis like Wikipedia):

tools.spacemedia@tools-bastion-12:~$ toolforge --version
Toolforge CLI, version 0.3.5

Other information (browser name/version, screenshots, etc.):

Event Timeline

Restricted Application added a subscriber: Aklapper. · View Herald Transcript

The cred copy is a warning (annoying, but not so easy to get rid of), the actual error is buried a bit there:

[step-inject-buildpacks] 2024-10-15T18:30:03.241941594Z Connecting to github.com (140.82.114.3:443)
[step-inject-buildpacks] 2024-10-15T18:32:14.103875383Z wget: can't connect to remote host (140.82.114.3): Operation timed out
[step-inject-buildpacks] 2024-10-15T18:32:14.104443355Z tar: short read

It timed out connecting to github :/

dcaro renamed this task from "unsuccessful cred copy" with toolforge build to Timeouts when pulling artifacts from github with toolforge build.Oct 15 2024, 6:40 PM
Don-vip renamed this task from Timeouts when pulling artifacts from github with toolforge build to "can't connect to remote host" with toolforge build.Oct 15 2024, 6:40 PM

Yes sorry I just seen it. FYI I just relaunched the job and it worked :)

Don-vip renamed this task from "can't connect to remote host" with toolforge build to Timeouts when pulling artifacts from github with toolforge build.Oct 15 2024, 6:41 PM

@Don-vip has this happened again? Otherwise it might have been a temporary blip

@dcaro no it happened only once for me. Feel free to close the ticket :)

dcaro claimed this task.

Ack, please reference this task it if it happens again (and you remember xd).