[0KRunning with gitlab-runner 14.0.1 (c1edb478)
[0;m[0K on meson-gxl-s805x-libretech-ac v94YcyKf
[0;msection_start:1638805000:prepare_executor
[0K[0K[36;1mPreparing the "docker" executor[0;m
[0;m[0KUsing Docker executor with image registry.freedesktop.org/enunes/mesa/debian/x86_build:2021-11-25-libdrm--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd ...
[0;m[0KAuthenticating with credentials from job payload (GitLab Registry)
[0;m[0KPulling docker image registry.freedesktop.org/enunes/mesa/debian/x86_build:2021-11-25-libdrm--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd ...
[0;m[0KUsing docker image sha256:852c553f7a698f5d62af062aae2744180ea3ecdc1cc21ec0827814f1079e2c05 for registry.freedesktop.org/enunes/mesa/debian/x86_build:2021-11-25-libdrm--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd with digest registry.freedesktop.org/enunes/mesa/debian/x86_build@sha256:9201d525bdf5fdcf90e975263199113d6009c127aabf13c0deace941c12b249a ...
[0;msection_end:1638805003:prepare_executor
[0Ksection_start:1638805003:prepare_script
[0K[0K[36;1mPreparing environment[0;m
[0;mRunning on runner-v94ycykf-project-2991-concurrent-2 via meson-gxl-s805x-libretech-ac.enunes.eu...
section_end:1638805003:prepare_script
[0Ksection_start:1638805003:get_sources
[0K[0K[36;1mGetting source from Git repository[0;m
[0;m[32;1mSkipping Git repository setup[0;m
[32;1mSkipping Git checkout[0;m
[32;1mSkipping Git submodules setup[0;m
section_end:1638805004:get_sources
[0Ksection_start:1638805004:download_artifacts
[0K[0K[36;1mDownloading artifacts[0;m
[0;m[32;1mDownloading artifacts for debian-arm64 (16452364)...[0;m
Downloading artifacts from coordinator... ok [0;m id[0;m=16452364 responseStatus[0;m=200 OK token[0;m=XwmbBvRm
section_end:1638805018:download_artifacts
[0Ksection_start:1638805018:step_script
[0K[0K[36;1mExecuting "step_script" stage of the job script[0;m
[0;m[0KUsing docker image sha256:852c553f7a698f5d62af062aae2744180ea3ecdc1cc21ec0827814f1079e2c05 for registry.freedesktop.org/enunes/mesa/debian/x86_build:2021-11-25-libdrm--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd with digest registry.freedesktop.org/enunes/mesa/debian/x86_build@sha256:9201d525bdf5fdcf90e975263199113d6009c127aabf13c0deace941c12b249a ...
[0;m[32;1m$ echo -e "\e[0Ksection_start:$(date +%s):unset_env_vars_section[collapsed=true]\r\e[0KUnsetting vulnerable environment variables"[0;m
[0Ksection_start:1638805018:unset_env_vars_section[collapsed=true]
[0KUnsetting vulnerable environment variables
[32;1m$ export CI_JOB_JWT_FILE="${CI_JOB_JWT_FILE:-$(mktemp)}"[0;m
[32;1m$ echo -n "${CI_JOB_JWT}" > "${CI_JOB_JWT_FILE}"[0;m
[32;1m$ unset CI_JOB_JWT[0;m
[32;1m$ echo -e "\e[0Ksection_end:$(date +%s):unset_env_vars_section\r\e[0K"[0;m
[0Ksection_end:1638805018:unset_env_vars_section
[0K
[32;1m$ ./artifacts/lava/lava-submit.sh[0;m
+ wget -q --method=HEAD https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/done
+ BASE_SYSTEM_HOST_PATH=minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64
+ rm -rf results
+ mkdir -p results/job-rootfs-overlay/
+ export PIGLIT_REPLAY_UPLOAD_TO_MINIO=1
+ PIGLIT_REPLAY_UPLOAD_TO_MINIO=1
+ cp artifacts/ci-common/capture-devcoredump.sh results/job-rootfs-overlay/
+ cp artifacts/ci-common/init-stage1.sh artifacts/ci-common/init-stage2.sh results/job-rootfs-overlay/
+ artifacts/ci-common/generate-env.sh
+ tar zcf job-rootfs-overlay.tar.gz -C results/job-rootfs-overlay/ .
+ ci-fairy minio login --token-file /minio_jwt
+ ci-fairy minio cp job-rootfs-overlay.tar.gz minio://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399/job-rootfs-overlay.tar.gz
+ touch results/lava.log
+ tail -f results/lava.log
+ artifacts/lava/lava_job_submitter.py --dump-yaml --pipeline-info 'lima-mali450-piglit-gpu:arm64 2/2: https://gitlab.freedesktop.org/enunes/mesa/-/pipelines/460128 on lima-ci-enable-piglit 2/2' --base-system-url-prefix https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64 --mesa-build-url https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/mesa-arm64.tar.gz --job-rootfs-overlay-url https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399/job-rootfs-overlay.tar.gz --job-artifacts-base minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399 --job-timeout 30 --first-stage-init artifacts/ci-common/init-stage1.sh --ci-project-dir /builds/enunes/mesa --device-type meson-gxl-s805x-libretech-ac --dtb meson-gxl-s805x-libretech-ac --jwt-file /minio_jwt --kernel-image-name Image --kernel-image-type image --boot-method u-boot --visibility-group mesa-ci --lava-tags ''
2021-12-06 15:37:02.961193: Proxy for http://10.0.70.20 created.
actions:
- deploy:
dtb:
url: https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/meson-gxl-s805x-libretech-ac.dtb
kernel:
type: image
url: https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/Image
nfsrootfs:
compression: gz
url: https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/lava-rootfs.tgz
os: oe
timeout:
minutes: 10
to: tftp
- boot:
commands: nfs
method: u-boot
prompts:
- 'lava-shell:'
timeout:
minutes: 25
- test:
definitions:
- from: inline
name: mesa
path: inline/mesa.yaml
repository:
metadata:
description: Mesa test plan
format: Lava-Test Test Definition 1.0
name: mesa
os:
- oe
scope:
- functional
parse:
pattern: 'hwci: (?P<test_case_id>\S*):\s+(?P<result>(pass|fail))'
run:
steps:
- set -ex
- cd /
- mount -t proc none /proc
- mount -t sysfs none /sys
- mount -t devtmpfs none /dev || echo possibly already mounted
- mkdir -p /dev/pts
- mount -t devpts devpts /dev/pts
- mount -t tmpfs tmpfs /tmp
- echo "nameserver 8.8.8.8" > /etc/resolv.conf
- '[ -z "$NFS_SERVER_IP" ] || echo "$NFS_SERVER_IP caching-proxy" >> /etc/hosts'
- for i in 1 2 3; do sntp -sS pool.ntp.org && break || sleep 2; done || true
- set +x
- set -x
- mkdir -p /builds/enunes/mesa
- wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/mesa-arm64.tar.gz | tar -xz -C /builds/enunes/mesa
- wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399/job-rootfs-overlay.tar.gz | tar -xz -C /
- echo "export CI_JOB_JWT_FILE=/minio_jwt" >> /set-job-env-vars.sh
- exec /init-stage2.sh
failure_retry: 1
timeout:
minutes: 30
context:
extra_nfsroot_args: ' init=/init rootwait minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399'
device_type: meson-gxl-s805x-libretech-ac
job_name: 'mesa: lima-mali450-piglit-gpu:arm64 2/2: https://gitlab.freedesktop.org/enunes/mesa/-/pipelines/460128 on lima-ci-enable-piglit 2/2'
priority: 75
timeouts:
job:
minutes: 30
visibility:
group:
- mesa-ci
2021-12-06 15:37:03.024487: LAVA job id: 4331
2021-12-06 15:37:03.024537: Waiting for job 4331 to start.
2021-12-06 15:37:53.145321: Job 4331 started.
2021-12-06T15:37:41.179944 lava-dispatcher, installed at version: 2019.01-5
2021-12-06T15:37:41.180355 start: 0 validate
2021-12-06T15:37:41.180525 Start time: 2021-12-06 15:37:41.180518+00:00 (UTC)
2021-12-06T15:37:41.180769 Validating that https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/Image exists
2021-12-06T15:37:41.503903 Validating that https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/meson-gxl-s805x-libretech-ac.dtb exists
2021-12-06T15:37:41.828139 Validating that https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/lava-rootfs.tgz exists
2021-12-06T15:37:42.150841 validate duration: 0.97
2021-12-06T15:37:42.151012 {'case': 'validate', 'definition': 'lava', 'result': 'pass'}
2021-12-06T15:37:42.151200 start: 1 tftp-deploy (timeout 00:10:00) [common]
2021-12-06T15:37:42.151319 start: 1.1 download-retry (timeout 00:10:00) [common]
2021-12-06T15:37:42.151431 start: 1.1.1 http-download (timeout 00:10:00) [common]
2021-12-06T15:37:42.151581 downloading https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/Image
2021-12-06T15:37:42.151670 saving as /srv/tftp/4331/tftp-deploy-91vfkeac/kernel/Image
2021-12-06T15:37:42.151750 total size: 31773184 (30MB)
2021-12-06T15:37:42.151827 No compression specified
2021-12-06T15:37:42.670845 progress 0% (0MB)
2021-12-06T15:37:43.185599 progress 5% (1MB)
2021-12-06T15:37:43.497532 progress 10% (3MB)
2021-12-06T15:37:43.828859 progress 15% (4MB)
2021-12-06T15:37:44.170958 progress 20% (6MB)
2021-12-06T15:37:44.488774 progress 25% (7MB)
2021-12-06T15:37:44.806343 progress 30% (9MB)
2021-12-06T15:37:45.125601 progress 35% (10MB)
2021-12-06T15:37:45.442553 progress 40% (12MB)
2021-12-06T15:37:45.765226 progress 45% (13MB)
2021-12-06T15:37:46.085805 progress 50% (15MB)
2021-12-06T15:37:46.408409 progress 55% (16MB)
2021-12-06T15:37:46.730465 progress 60% (18MB)
2021-12-06T15:37:47.057317 progress 65% (19MB)
2021-12-06T15:37:47.377614 progress 70% (21MB)
2021-12-06T15:37:47.648588 progress 75% (22MB)
2021-12-06T15:37:47.958947 progress 80% (24MB)
2021-12-06T15:37:48.289938 progress 85% (25MB)
2021-12-06T15:37:48.620042 progress 90% (27MB)
2021-12-06T15:37:48.941749 progress 95% (28MB)
2021-12-06T15:37:49.245444 progress 100% (30MB)
2021-12-06T15:37:49.245850 30MB downloaded in 7.09s (4.27MB/s)
2021-12-06T15:37:49.246080 end: 1.1.1 http-download (duration 00:00:07) [common]
2021-12-06T15:37:49.246195 {'case': 'http-download', 'definition': 'lava', 'duration': '7.09', 'extra': OrderedDict([('label', 'kernel'), ('size', 31773184), ('md5sum', 'af1496621f1490ab89e4cedde0b8372f'), ('sha256sum', '45b7269f419a704c40493be66a165c9ff06a3e09d132dcadbcb2c2e694bb1209')]), 'level': '1.1.1', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:37:49.246557 end: 1.1 download-retry (duration 00:00:07) [common]
2021-12-06T15:37:49.246708 start: 1.2 download-retry (timeout 00:09:53) [common]
2021-12-06T15:37:49.246834 start: 1.2.1 http-download (timeout 00:09:53) [common]
2021-12-06T15:37:49.247019 downloading https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/meson-gxl-s805x-libretech-ac.dtb
2021-12-06T15:37:49.247110 saving as /srv/tftp/4331/tftp-deploy-91vfkeac/dtb/meson-gxl-s805x-libretech-ac.dtb
2021-12-06T15:37:49.247204 total size: 28020 (0MB)
2021-12-06T15:37:49.247284 No compression specified
2021-12-06T15:37:49.667590 progress 100% (0MB)
2021-12-06T15:37:49.668094 0MB downloaded in 0.42s (0.06MB/s)
2021-12-06T15:37:49.668297 end: 1.2.1 http-download (duration 00:00:00) [common]
2021-12-06T15:37:49.668409 {'case': 'http-download', 'definition': 'lava', 'duration': '0.42', 'extra': OrderedDict([('label', 'dtb'), ('size', 28020), ('md5sum', '3e4ea7cfb775508c01e97f235c520d73'), ('sha256sum', 'bd3ea04686045ebf9e082e8e5c4aa5628cd0ba2ff69a6653cdf30b06f679569e')]), 'level': '1.2.1', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:37:49.668704 end: 1.2 download-retry (duration 00:00:00) [common]
2021-12-06T15:37:49.668840 start: 1.3 download-retry (timeout 00:09:52) [common]
2021-12-06T15:37:49.668969 start: 1.3.1 http-download (timeout 00:09:52) [common]
2021-12-06T15:37:49.669163 downloading https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2021-12-02-piglit--2021-11-25-ci-fairy--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/lava-rootfs.tgz
2021-12-06T15:37:49.669275 saving as /srv/tftp/4331/tftp-deploy-91vfkeac/nfsrootfs/lava-rootfs
2021-12-06T15:37:49.669371 total size: 303507633 (289MB)
2021-12-06T15:37:49.669455 Using gunzip to decompress gz
2021-12-06T15:37:50.189669 progress 0% (0MB)
2021-12-06T15:37:53.721983 progress 5% (14MB)
2021-12-06T15:37:57.068884 progress 10% (28MB)
2021-12-06T15:38:00.305245 progress 15% (43MB)
2021-12-06T15:38:03.475174 progress 20% (57MB)
2021-12-06T15:38:06.676196 progress 25% (72MB)
2021-12-06T15:38:09.849055 progress 30% (86MB)
2021-12-06T15:38:12.932977 progress 35% (101MB)
2021-12-06T15:38:15.596833 progress 40% (115MB)
2021-12-06T15:38:17.628502 progress 45% (130MB)
2021-12-06T15:38:19.500429 progress 50% (144MB)
2021-12-06T15:38:21.334394 progress 55% (159MB)
2021-12-06T15:38:23.112120 progress 60% (173MB)
2021-12-06T15:38:24.853795 progress 65% (188MB)
2021-12-06T15:38:26.531203 progress 70% (202MB)
2021-12-06T15:38:28.172258 progress 75% (217MB)
2021-12-06T15:38:29.782234 progress 80% (231MB)
2021-12-06T15:38:31.339425 progress 85% (246MB)
2021-12-06T15:38:32.886742 progress 90% (260MB)
2021-12-06T15:38:34.381960 progress 95% (275MB)
2021-12-06T15:38:35.852419 progress 100% (289MB)
2021-12-06T15:38:35.853346 289MB downloaded in 46.18s (6.27MB/s)
2021-12-06T15:38:35.853616 end: 1.3.1 http-download (duration 00:00:46) [common]
2021-12-06T15:38:35.853737 {'case': 'http-download', 'definition': 'lava', 'duration': '46.18', 'extra': OrderedDict([('label', 'nfsrootfs'), ('size', 303507633), ('md5sum', '7c44d19f4be649f78e662d36a9a8b661'), ('sha256sum', 'a26f8a2b3596823c5ec20baf743fcdb38419115fafee5b9dac7b235f387bd166')]), 'level': '1.3.1', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:38:35.854033 end: 1.3 download-retry (duration 00:00:46) [common]
2021-12-06T15:38:35.854155 start: 1.4 prepare-tftp-overlay (timeout 00:09:06) [common]
2021-12-06T15:38:35.854275 start: 1.4.1 extract-nfsrootfs (timeout 00:09:06) [common]
2021-12-06T15:38:43.452683 Extracted nfsroot to /var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt
2021-12-06T15:38:43.453100 end: 1.4.1 extract-nfsrootfs (duration 00:00:08) [common]
2021-12-06T15:38:43.453275 start: 1.4.2 lava-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.453533 [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j
2021-12-06T15:38:43.453741 makedir: /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin
2021-12-06T15:38:43.453913 makedir: /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/tests
2021-12-06T15:38:43.454073 makedir: /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/results
2021-12-06T15:38:43.454240 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-add-keys
2021-12-06T15:38:43.454456 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-add-sources
2021-12-06T15:38:43.454735 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-background-process-start
2021-12-06T15:38:43.454932 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-background-process-stop
2021-12-06T15:38:43.455116 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-common-functions
2021-12-06T15:38:43.455295 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-echo-ipv4
2021-12-06T15:38:43.455473 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-install-packages
2021-12-06T15:38:43.455656 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-installed-packages
2021-12-06T15:38:43.455835 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-os-build
2021-12-06T15:38:43.456022 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-probe-channel
2021-12-06T15:38:43.456200 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-probe-ip
2021-12-06T15:38:43.456374 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-target-ip
2021-12-06T15:38:43.456548 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-target-mac
2021-12-06T15:38:43.456723 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-target-storage
2021-12-06T15:38:43.456913 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-case
2021-12-06T15:38:43.457091 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-event
2021-12-06T15:38:43.457272 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-feedback
2021-12-06T15:38:43.457450 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-raise
2021-12-06T15:38:43.457626 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-reference
2021-12-06T15:38:43.457803 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-runner
2021-12-06T15:38:43.457986 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-set
2021-12-06T15:38:43.458162 Creating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-test-shell
2021-12-06T15:38:43.458349 Updating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-install-packages (oe)
2021-12-06T15:38:43.458584 Updating /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/bin/lava-installed-packages (oe)
2021-12-06T15:38:43.458838 start: 1.4.2.1 ssh-authorize (timeout 00:08:59) [common]
2021-12-06T15:38:43.459150 end: 1.4.2.1 ssh-authorize (duration 00:00:00) [common]
2021-12-06T15:38:43.459300 start: 1.4.2.2 lava-vland-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.459417 skipped lava-vland-overlay
2021-12-06T15:38:43.459550 end: 1.4.2.2 lava-vland-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.459690 start: 1.4.2.3 lava-multinode-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.459803 skipped lava-multinode-overlay
2021-12-06T15:38:43.459942 end: 1.4.2.3 lava-multinode-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.460084 start: 1.4.2.4 test-definition (timeout 00:08:59) [common]
2021-12-06T15:38:43.460213 Loading test definitions
2021-12-06T15:38:43.460365 start: 1.4.2.4.1 inline-repo-action (timeout 00:08:59) [common]
2021-12-06T15:38:43.460487 Using /lava-4331 at stage 0
2021-12-06T15:38:43.463600 uuid=4331_1.4.2.4.1 testdef={'testdef_pattern': {'pattern': 'hwci: (?P<test_case_id>\\S*):\\s+(?P<result>(pass|fail))', 'fixupdict': ''}}
2021-12-06T15:38:43.463768 end: 1.4.2.4.1 inline-repo-action (duration 00:00:00) [common]
2021-12-06T15:38:43.463922 start: 1.4.2.4.2 test-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.470684 end: 1.4.2.4.2 test-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.470826 {'case': 'test-overlay', 'definition': 'lava', 'duration': '0.01', 'extra': OrderedDict([('uuid', '4331_1.4.2.4.1'), ('name', 'mesa'), ('path', 'inline/mesa.yaml'), ('from', 'inline')]), 'level': '1.4.2.4.2', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:38:43.471123 start: 1.4.2.4.3 test-install-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.481719 end: 1.4.2.4.3 test-install-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.481883 {'case': 'test-install-overlay', 'definition': 'lava', 'duration': '0.01', 'extra': OrderedDict([('uuid', '4331_1.4.2.4.1'), ('name', 'mesa'), ('path', 'inline/mesa.yaml'), ('from', 'inline'), ('skipped test-install-overlay', '4331_1.4.2.4.1')]), 'level': '1.4.2.4.3', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:38:43.482204 start: 1.4.2.4.4 test-runscript-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.492605 runner path: /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/0/tests/0_mesa test_uuid 4331_1.4.2.4.1
2021-12-06T15:38:43.492886 end: 1.4.2.4.4 test-runscript-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.493029 {'case': 'test-runscript-overlay', 'definition': 'lava', 'duration': '0.01', 'extra': OrderedDict([('uuid', '4331_1.4.2.4.1'), ('name', 'mesa'), ('path', 'inline/mesa.yaml'), ('from', 'inline'), ('filename', '/var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/0/tests/0_mesa/run.sh')]), 'level': '1.4.2.4.4', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:38:43.493301 Creating lava-test-runner.conf files
2021-12-06T15:38:43.493417 Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/4331/lava-overlay-moiu322j/lava-4331/0 for stage 0
2021-12-06T15:38:43.493561 - 0_mesa
2021-12-06T15:38:43.493719 end: 1.4.2.4 test-definition (duration 00:00:00) [common]
2021-12-06T15:38:43.493865 start: 1.4.2.5 compress-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.499874 end: 1.4.2.5 compress-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.500067 start: 1.4.2.6 persistent-nfs-overlay (timeout 00:08:59) [common]
2021-12-06T15:38:43.500213 end: 1.4.2.6 persistent-nfs-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.500359 end: 1.4.2 lava-overlay (duration 00:00:00) [common]
2021-12-06T15:38:43.500501 start: 1.4.3 extract-overlay-ramdisk (timeout 00:08:59) [common]
2021-12-06T15:38:43.500640 end: 1.4.3 extract-overlay-ramdisk (duration 00:00:00) [common]
2021-12-06T15:38:43.500781 start: 1.4.4 extract-modules (timeout 00:08:59) [common]
2021-12-06T15:38:43.500923 end: 1.4.4 extract-modules (duration 00:00:00) [common]
2021-12-06T15:38:43.501061 start: 1.4.5 apply-overlay-tftp (timeout 00:08:59) [common]
2021-12-06T15:38:43.501184 [common] Applying overlay to NFS
2021-12-06T15:38:43.501296 [common] Applying overlay /var/lib/lava/dispatcher/tmp/4331/compress-overlay-esdm9kk4/overlay-1.4.2.5.tar.gz to directory /var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt
2021-12-06T15:38:43.505628 end: 1.4.5 apply-overlay-tftp (duration 00:00:00) [common]
2021-12-06T15:38:43.505799 start: 1.4.6 prepare-kernel (timeout 00:08:59) [common]
2021-12-06T15:38:43.505952 start: 1.4.6.1 uboot-prepare-kernel (timeout 00:08:59) [common]
2021-12-06T15:38:43.506079 Converting downloaded kernel to a uImage
2021-12-06T15:38:43.506214 nice mkimage -A arm64 -O linux -T kernel -C none -a 0x8080000 -e 0x8080000 -d /srv/tftp/4331/tftp-deploy-91vfkeac/kernel/Image /srv/tftp/4331/tftp-deploy-91vfkeac/kernel/uImage
2021-12-06T15:38:44.029652 output: Image Name:
2021-12-06T15:38:44.030019 output: Created: Mon Dec 6 16:38:43 2021
2021-12-06T15:38:44.030110 output: Image Type: AArch64 Linux Kernel Image (uncompressed)
2021-12-06T15:38:44.030192 output: Data Size: 31773184 Bytes = 31028.50 KiB = 30.30 MiB
2021-12-06T15:38:44.030271 output: Load Address: 08080000
2021-12-06T15:38:44.030351 output: Entry Point: 08080000
2021-12-06T15:38:44.030427 output:
2021-12-06T15:38:44.030627 end: 1.4.6.1 uboot-prepare-kernel (duration 00:00:01) [common]
2021-12-06T15:38:44.030761 end: 1.4.6 prepare-kernel (duration 00:00:01) [common]
2021-12-06T15:38:44.030883 start: 1.4.7 configure-preseed-file (timeout 00:08:58) [common]
2021-12-06T15:38:44.031000 end: 1.4.7 configure-preseed-file (duration 00:00:00) [common]
2021-12-06T15:38:44.031108 start: 1.4.8 compress-ramdisk (timeout 00:08:58) [common]
2021-12-06T15:38:44.031216 end: 1.4.8 compress-ramdisk (duration 00:00:00) [common]
2021-12-06T15:38:44.031338 end: 1.4 prepare-tftp-overlay (duration 00:00:08) [common]
2021-12-06T15:38:44.031448 start: 1.5 lxc-create-udev-rule-action (timeout 00:08:58) [common]
2021-12-06T15:38:44.031539 No LXC device requested
2021-12-06T15:38:44.031643 end: 1.5 lxc-create-udev-rule-action (duration 00:00:00) [common]
2021-12-06T15:38:44.031751 start: 1.6 deploy-device-env (timeout 00:08:58) [common]
2021-12-06T15:38:44.031853 end: 1.6 deploy-device-env (duration 00:00:00) [common]
2021-12-06T15:38:44.031949 Checking files for TFTP limit of 4294967296 bytes.
2021-12-06T15:38:44.032392 end: 1 tftp-deploy (duration 00:01:02) [common]
2021-12-06T15:38:44.032524 start: 2 uboot-action (timeout 00:25:00) [common]
2021-12-06T15:38:44.032646 start: 2.1 uboot-from-media (timeout 00:25:00) [common]
2021-12-06T15:38:44.032754 end: 2.1 uboot-from-media (duration 00:00:00) [common]
2021-12-06T15:38:44.032866 start: 2.2 bootloader-overlay (timeout 00:25:00) [common]
2021-12-06T15:38:44.033131 Using kernel file from prepare-kernel: 4331/tftp-deploy-91vfkeac/kernel/uImage
2021-12-06T15:38:44.033231 {'bootm': {'dtb': '0x08008000', 'kernel': '0x08080000', 'ramdisk': '0x13000000'}, 'pass': None, 'uimage': {'dtb': '0x08008000', 'kernel': '0x08080000', 'ramdisk': '0x13000000'}}
2021-12-06T15:38:44.033400 Parsed boot commands: setenv autoload no; setenv initrd_high 0xffffffff; setenv fdt_high 0xffffffff; dhcp; setenv serverip 10.0.70.20; tftpboot 0x08080000 4331/tftp-deploy-91vfkeac/kernel/uImage; tftpboot - {RAMDISK}; setenv initrd_size ${filesize}; tftpboot 0x08008000 4331/tftp-deploy-91vfkeac/dtb/meson-gxl-s805x-libretech-ac.dtb; setenv bootargs console=ttyAML0,115200n8; setenv bootargs ${bootargs} root=/dev/nfs rw; setenv bootargs ${bootargs} nfsroot=10.0.70.20:/var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt,tcp,hard,vers=3 init=/init rootwait minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399; setenv bootargs ${bootargs} ; setenv bootargs ${bootargs} ip=dhcp; bootm 0x08080000 - 0x08008000
2021-12-06T15:38:44.033524 end: 2.2 bootloader-overlay (duration 00:00:00) [common]
2021-12-06T15:38:44.033621 {'case': 'bootloader-overlay', 'definition': 'lava', 'duration': '0.00', 'extra': OrderedDict([('kernel_addr', '0x08080000'), ('dtb_addr', '0x08008000'), ('ramdisk_addr', '-')]), 'level': '2.2', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:38:44.033876 start: 2.3 connect-device (timeout 00:25:00) [common]
2021-12-06T15:38:44.033979 [common] connect-device Connecting to device using 'telnet 10.0.70.35 3021'
2021-12-06T15:38:44.037891 end: 2.3 connect-device (duration 00:00:00) [common]
2021-12-06T15:38:44.038226 start: 2.4 uboot-retry (timeout 00:25:00) [common]
2021-12-06T15:38:44.038360 start: 2.4.1 reset-device (timeout 00:25:00) [common]
2021-12-06T15:38:44.038486 start: 2.4.1.1 pdu-reboot (timeout 00:25:00) [common]
2021-12-06T15:38:44.038614 nice /usr/local/bin/meson-gxl-s805x-libretech-ac-power reboot 10.0.70.56
2021-12-06T15:38:49.279666 output: {"POWER":"OFF"}{"POWER":"ON"}
2021-12-06T15:38:49.280147 end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]
2021-12-06T15:38:49.280263 {'case': 'pdu-reboot', 'definition': 'lava', 'duration': '5.24', 'extra': OrderedDict([('status', 'success')]), 'level': '2.4.1.1', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:38:49.280505 end: 2.4.1 reset-device (duration 00:00:05) [common]
2021-12-06T15:38:49.280630 start: 2.4.2 bootloader-interrupt (timeout 00:24:55) [common]
2021-12-06T15:38:49.280726 Not interrupting bootloader, waiting for bootloader prompt
2021-12-06T15:38:49.280820 bootloader-interrupt: Wait for prompt => (timeout 00:25:00)
2021-12-06T15:38:49.281801 Trying 10.0.70.35...
2021-12-06T15:38:49.282079 Connected to 10.0.70.35.
2021-12-06T15:38:49.282183 Escape character is '^]'.
2021-12-06T15:38:49.282270 port 3021 device /dev/ttyUSBh1p2
2021-12-06T15:38:49.282392 �GXL:BL1:9ac50e:bb16dc;FEAT:ADFC318C:0;POC:1;RCY:0;SPI:0;0.0;CHK:0;
2021-12-06T15:38:49.282473 TE: 35051
2021-12-06T15:38:49.282549
2021-12-06T15:38:49.282665 BL2 Built : 15:21:18, Aug 28 2019. gxl g1bf2b53 - luan.yuan@droid15-sz
2021-12-06T15:38:49.282745
2021-12-06T15:38:49.282821 set vcck to 1120 mv
2021-12-06T15:38:49.282896 set vddee to 1000 mv
2021-12-06T15:38:49.282979 Board ID = 11
2021-12-06T15:38:49.283209 CPU clk: 1200MHz
2021-12-06T15:38:49.283300 DDR enable rdbi
2021-12-06T15:38:49.283376 DDR use ext vref
2021-12-06T15:38:49.283449 DQS-corr enabled
2021-12-06T15:38:49.283531 DDR scramble enabled
2021-12-06T15:38:49.283605 DDR4 chl: Rank0 16bit @ 1200MHz
2021-12-06T15:38:49.283678
2021-12-06T15:38:49.283753 bist_test rank: 0 14 00 28 29 17 3c 15 00 2a 2c 19 3f 1f 1f 1f 1f 1f 1f 1f 1f 1f 1f 1f 1f 661
2021-12-06T15:38:49.283833 Rank0: 1024MB(auto)-2T-18
2021-12-06T15:38:49.283916 AddrBus test pass!
2021-12-06T15:38:49.283994 Set ddr ssc: ppm1000-
2021-12-06T15:38:49.284066 Load fip header from SPI, src: 0x0000c000, des: 0x01400000, size: 0x00004000, part: 0
2021-12-06T15:38:49.284142 New fip structure!
2021-12-06T15:38:49.284215 Load bl30 from SPI, src: 0x00010000, des: 0x013c0000, size: 0x0000d600, part: 0
2021-12-06T15:38:49.284290 Load bl31 from SPI, src: 0x00020000, des: 0x05100000, size: 0x0002b400, part: 0
2021-12-06T15:38:49.284369 Load bl33 from SPI, src: 0x0004c000, des: 0x01000000, size: 0x0007be00, part: 0
2021-12-06T15:38:49.284442 NOTICE: BL3-1: v1.0(release):53f813e
2021-12-06T15:38:49.284520 NOTICE: BL3-1: Built : 15:51:23, May 22 2019
2021-12-06T15:38:49.284595 [BL31]: GXL CPU setup!
2021-12-06T15:38:49.284670 NOTICE: BL3-1: GXL normal boot!
2021-12-06T15:38:49.284744 NOTICE: BL3-1: BL33 decompress pass
2021-12-06T15:38:49.284819 mpu_config_enable:system pre init ok
2021-12-06T15:38:49.284896 dmc sec lock
2021-12-06T15:38:49.284979 [Image: gxl_v1.1.3390-6ac5299 2019-09-26 14:09:24 luan.yuan@droid15-sz]
2021-12-06T15:38:49.285054 OPS=0x34
2021-12-06T15:38:49.285126 21 0d 34 00 80 b5 99 52 5b 00 4a bf d5 e0 67 02
2021-12-06T15:38:49.285209 [0.727586 Inits done]
2021-12-06T15:38:49.285283 secure task start!
2021-12-06T15:38:49.285355 high task start!
2021-12-06T15:38:49.285429 low task start!
2021-12-06T15:38:49.285501 ERROR: Error initializing runtime service opteed_fast
2021-12-06T15:38:49.451173
2021-12-06T15:38:49.451500
2021-12-06T15:38:49.457949 U-Boot 2020.07+ (Aug 25 2020 - 04:00:30 +0000) Libre Computer AML-S805X-AC
2021-12-06T15:38:49.458108
2021-12-06T15:38:49.470109 DRAM: 1 GiB
2021-12-06T15:38:49.539361 MMC: mmc@74000: 0
2021-12-06T15:38:49.549794 Loading Environment from SPI Flash... SF: Detected gd25lq128 with page size 256 Bytes, erase size 64 KiB, total 16 MiB
2021-12-06T15:38:49.560187 OK
2021-12-06T15:38:49.613979 ** No partition table - mmc 0 **
2021-12-06T15:38:49.616942 Error: could not access storage.
2021-12-06T15:38:50.720030 In: serial
2021-12-06T15:38:50.721426 Out: serial
2021-12-06T15:38:50.722646 Err: serial
2021-12-06T15:38:50.724576 [BL31]: tee size: 0
2021-12-06T15:38:50.749262 Net: eth0: ethernet@c9410000
2021-12-06T15:38:50.750654 starting USB...
2021-12-06T15:38:50.974885 Bus dwc3@c9000000: Register 2000140 NbrPorts 2
2021-12-06T15:38:50.977239 Starting the controller
2021-12-06T15:38:50.978386 USB XHCI 1.00
2021-12-06T15:38:52.099823 scanning bus dwc3@c9000000 for devices... 1 USB Device(s) found
2021-12-06T15:38:52.105894 scanning usb for storage devices... 0 Storage Device(s) found
2021-12-06T15:38:52.106470 end: 2.4.2 bootloader-interrupt (duration 00:00:03) [common]
2021-12-06T15:38:52.106851 start: 2.4.3 bootloader-commands (timeout 00:24:52) [common]
2021-12-06T15:38:52.107151 setenv autoload no
2021-12-06T15:38:52.207573 => setenv autoload no
2021-12-06T15:38:52.208000 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:52)
2021-12-06T15:38:52.209433 setenv autoload no
2021-12-06T15:38:52.210060 setenv initrd_high 0xffffffff
2021-12-06T15:38:52.310416 => setenv initrd_high 0xffffffff
2021-12-06T15:38:52.310748 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:52)
2021-12-06T15:38:52.319775 setenv initrd_high 0xffffffff
2021-12-06T15:38:52.320271 setenv fdt_high 0xffffffff
2021-12-06T15:38:52.420534 => setenv fdt_high 0xffffffff
2021-12-06T15:38:52.420748 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:52)
2021-12-06T15:38:52.421696 setenv fdt_high 0xffffffff
2021-12-06T15:38:52.422214 dhcp
2021-12-06T15:38:52.522514 => dhcp
2021-12-06T15:38:52.522804 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:52)
2021-12-06T15:38:52.532796 dhcp
2021-12-06T15:38:52.635542 Speed: 100, full duplex
2021-12-06T15:38:52.637264 BOOTP broadcast 1
2021-12-06T15:38:52.646785 DHCP client bound to address 10.0.70.82 (9 ms)
2021-12-06T15:38:52.647292 setenv serverip 10.0.70.20
2021-12-06T15:38:52.747677 => setenv serverip 10.0.70.20
2021-12-06T15:38:52.747954 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:51)
2021-12-06T15:38:52.748740 setenv serverip 10.0.70.20
2021-12-06T15:38:52.749350 tftpboot 0x08080000 4331/tftp-deploy-91vfkeac/kernel/uImage
2021-12-06T15:38:52.849659 => tftpboot 0x08080000 4331/tftp-deploy-91vfkeac/kernel/uImage
2021-12-06T15:38:52.849873 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:51)
2021-12-06T15:38:52.850723 tftpboot 0x08080000 4331/tftp-deploy-91vfkeac/kernel/uImage
2021-12-06T15:38:52.953414 Speed: 100, full duplex
2021-12-06T15:38:52.956229 Using ethernet@c9410000 device
2021-12-06T15:38:52.962269 TFTP from server 10.0.70.20; our IP address is 10.0.70.82
2021-12-06T15:38:52.966210 Filename '4331/tftp-deploy-91vfkeac/kernel/uImage'.
2021-12-06T15:38:52.975927 Load address: 0x8080000
2021-12-06T15:38:53.415591 Loading: *#################################################################
2021-12-06T15:38:53.557888 #################################################################
2021-12-06T15:38:53.708861 #################################################################
2021-12-06T15:38:53.852295 #################################################################
2021-12-06T15:38:53.995342 #################################################################
2021-12-06T15:38:54.137668 #################################################################
2021-12-06T15:38:54.281737 #################################################################
2021-12-06T15:38:54.427981 #################################################################
2021-12-06T15:38:54.565076 #################################################################
2021-12-06T15:38:54.705361 #################################################################
2021-12-06T15:38:54.845949 #################################################################
2021-12-06T15:38:54.992986 #################################################################
2021-12-06T15:38:55.141268 #################################################################
2021-12-06T15:38:55.289332 #################################################################
2021-12-06T15:38:55.437231 #################################################################
2021-12-06T15:38:55.587010 #################################################################
2021-12-06T15:38:55.744654 #################################################################
2021-12-06T15:38:55.893994 #################################################################
2021-12-06T15:38:56.043083 #################################################################
2021-12-06T15:38:56.189049 #################################################################
2021-12-06T15:38:56.333962 #################################################################
2021-12-06T15:38:56.476825 #################################################################
2021-12-06T15:38:56.619070 #################################################################
2021-12-06T15:38:56.761931 #################################################################
2021-12-06T15:38:56.904335 #################################################################
2021-12-06T15:38:57.048204 #################################################################
2021-12-06T15:38:57.192422 #################################################################
2021-12-06T15:38:57.337550 #################################################################
2021-12-06T15:38:57.482926 #################################################################
2021-12-06T15:38:57.627786 #################################################################
2021-12-06T15:38:59.788988 ########################################T ##T #######################
2021-12-06T15:38:59.979260 #################################################################
2021-12-06T15:39:00.266658 #################################################################
2021-12-06T15:39:00.311374 ####################
2021-12-06T15:39:00.315045 4.1 MiB/s
2021-12-06T15:39:00.315555 done
2021-12-06T15:39:00.320321 Bytes transferred = 31773248 (1e4d240 hex)
2021-12-06T15:39:00.321028 tftpboot - {RAMDISK}
2021-12-06T15:39:00.421461 => tftpboot - {RAMDISK}
2021-12-06T15:39:00.421842 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:44)
2021-12-06T15:39:00.424554 tftpboot - {RAMDISK}
2021-12-06T15:39:00.528795 Speed: 100, full duplex
2021-12-06T15:39:00.532810 Using ethernet@c9410000 device
2021-12-06T15:39:00.536878 TFTP from server 10.0.70.20; our IP address is 10.0.70.82
2021-12-06T15:39:00.538697 Filename '{RAMDISK}'.
2021-12-06T15:39:00.539024
2021-12-06T15:39:00.543977 TFTP error: trying to overwrite reserved memory...
2021-12-06T15:39:00.544380 setenv initrd_size ${filesize}
2021-12-06T15:39:00.644822 => setenv initrd_size ${filesize}
2021-12-06T15:39:00.645342 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:00.647949 setenv initrd_size ${filesize}
2021-12-06T15:39:00.648576 tftpboot 0x08008000 4331/tftp-deploy-91vfkeac/dtb/meson-gxl-s805x-libretech-ac.dtb
2021-12-06T15:39:00.749150 => tftpboot 0x08008000 4331/tftp-deploy-91vfkeac/dtb/meson-gxl-s805x-libretech-ac.dtb
2021-12-06T15:39:00.749971 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:00.759193 tftpboot 0x08008000 4331/tftp-deploy-91vfkeac/dtb/meson-gxl-s805x-libretech-ac.dtb
2021-12-06T15:39:00.863484 Speed: 100, full duplex
2021-12-06T15:39:00.866241 Using ethernet@c9410000 device
2021-12-06T15:39:00.871427 TFTP from server 10.0.70.20; our IP address is 10.0.70.82
2021-12-06T15:39:00.878118 Filename '4331/tftp-deploy-91vfkeac/dtb/meson-gxl-s805x-libretech-ac.dtb'.
2021-12-06T15:39:00.880289 Load address: 0x8008000
2021-12-06T15:39:00.902866 Loading: *##
2021-12-06T15:39:00.906410 1.1 MiB/s
2021-12-06T15:39:00.906979 done
2021-12-06T15:39:00.911196 Bytes transferred = 28020 (6d74 hex)
2021-12-06T15:39:00.911851 setenv bootargs console=ttyAML0,115200n8
2021-12-06T15:39:01.012327 => setenv bootargs console=ttyAML0,115200n8
2021-12-06T15:39:01.012839 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:01.015488 setenv bootargs console=ttyAML0,115200n8
2021-12-06T15:39:01.016134 setenv bootargs ${bootargs} root=/dev/nfs rw
2021-12-06T15:39:01.116548 => setenv bootargs ${bootargs} root=/dev/nfs rw
2021-12-06T15:39:01.116889 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:01.119686 setenv bootargs ${bootargs} root=/dev/nfs rw
2021-12-06T15:39:01.120123 setenv bootargs ${bootargs} nfsroot=10.0.70.20:/var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt,tcp,hard,vers=3 init=/init rootwait minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399
2021-12-06T15:39:01.220564 =>setenv bootargs ${bootargs} nfsroot=10.0.70.20:/var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt,tcp,hard,vers=3 init=/init rootwait minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399
2021-12-06T15:39:01.220936 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:01.223703 setenv bootargs ${bootargs} nfsroot=10.0.70.20:/var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt,tcp,hard,vers=3 init=/init rootwait minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399
2021-12-06T15:39:01.224330 setenv bootargs ${bootargs}
2021-12-06T15:39:01.324724 => setenv bootargs ${bootargs}
2021-12-06T15:39:01.325024 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:01.327889 setenv bootargs ${bootargs}
2021-12-06T15:39:01.328504 setenv bootargs ${bootargs} ip=dhcp
2021-12-06T15:39:01.428913 => setenv bootargs ${bootargs} ip=dhcp
2021-12-06T15:39:01.429223 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:24:43)
2021-12-06T15:39:01.432020 setenv bootargs ${bootargs} ip=dhcp
2021-12-06T15:39:01.432632 bootm 0x08080000 - 0x08008000
2021-12-06T15:39:01.533042 => bootm 0x08080000 - 0x08008000
2021-12-06T15:39:01.533430 bootloader-commands: Wait for prompt Starting kernel (timeout 00:24:42)
2021-12-06T15:39:01.536230 bootm 0x08080000 - 0x08008000
2021-12-06T15:39:01.542335 ## Booting kernel from Legacy Image at 08080000 ...
2021-12-06T15:39:01.544067 Image Name:
2021-12-06T15:39:01.549421 Image Type: AArch64 Linux Kernel Image (uncompressed)
2021-12-06T15:39:01.553170 Data Size: 31773184 Bytes = 30.3 MiB
2021-12-06T15:39:01.555656 Load Address: 08080000
2021-12-06T15:39:01.557801 Entry Point: 08080000
2021-12-06T15:39:01.769894 Verifying Checksum ... OK
2021-12-06T15:39:01.774967 ## Flattened Device Tree blob at 08008000
2021-12-06T15:39:01.778819 Booting using the fdt blob at 0x8008000
2021-12-06T15:39:01.780999 Loading Kernel Image
2021-12-06T15:39:01.806702 Loading Device Tree to 000000003beee000, end 000000003bef7d73 ... OK
2021-12-06T15:39:01.809892
2021-12-06T15:39:01.811461 end: 2.4.3 bootloader-commands (duration 00:00:10) [common]
2021-12-06T15:39:01.811800 start: 2.4.4 auto-login-action (timeout 00:24:42) [common]
2021-12-06T15:39:01.812042 auto-login-action: Wait for prompt ['Linux version [0-9]', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] (timeout 00:25:00)
2021-12-06T15:39:01.812430 Starting kernel ...
2021-12-06T15:39:01.812555
2021-12-06T15:39:02.050649 [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
2021-12-06T15:39:02.053499 Using line separator: #'\n'#
2021-12-06T15:39:02.053830 No login prompt set.
2021-12-06T15:39:02.054151 Parsing kernel messages
2021-12-06T15:39:02.054440 ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', 'lava-shell:', 'Login timed out', 'Login incorrect']
2021-12-06T15:39:02.054860 [auto-login-action] Waiting for messages, (timeout 00:24:42)
2021-12-06T15:39:02.072561 [ 0.000000] Linux version 5.15.0linux-v5.15-for-mesa-ci-540a4af22d71.tar.bz2 (root@runner-qyensjbb-project-176-concurrent-0) (gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2) #1 SMP PREEMPT Thu Dec 2 18:16:45 UTC 2021
2021-12-06T15:39:02.077616 [ 0.000000] Machine model: Libre Computer AML-S805X-AC
2021-12-06T15:39:02.080876 [ 0.000000] efi: UEFI not found.
2021-12-06T15:39:02.088855 [ 0.000000] [Firmware Bug]: Kernel image misaligned at boot, please fix your bootloader!
2021-12-06T15:39:02.099595 [ 0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'hwrom@0': base 0x0000000000000000, size 16 MiB
2021-12-06T15:39:02.110754 [ 0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'secmon@5000000': base 0x0000000005000000, size 3 MiB
2021-12-06T15:39:02.118718 [ 0.000000] Reserved memory: created CMA memory pool at 0x000000002bc00000, size 256 MiB
2021-12-06T15:39:02.126661 [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
2021-12-06T15:39:02.130974 [ 0.000000] NUMA: No NUMA configuration found
2021-12-06T15:39:02.138164 [ 0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x000000003fe5afff]
2021-12-06T15:39:02.143383 [ 0.000000] NUMA: NODE_DATA [mem 0x3fc31c00-0x3fc33fff]
2021-12-06T15:39:02.145772 [ 0.000000] Zone ranges:
2021-12-06T15:39:02.151920 [ 0.000000] DMA [mem 0x0000000000000000-0x000000003fe5afff]
2021-12-06T15:39:02.154729 [ 0.000000] DMA32 empty
2021-12-06T15:39:02.157791 [ 0.000000] Normal empty
2021-12-06T15:39:02.162001 [ 0.000000] Movable zone start for each node
2021-12-06T15:39:02.165546 [ 0.000000] Early memory node ranges
2021-12-06T15:39:02.171737 [ 0.000000] node 0: [mem 0x0000000000000000-0x000000000fffffff]
2021-12-06T15:39:02.177837 [ 0.000000] node 0: [mem 0x0000000010000000-0x00000000101fffff]
2021-12-06T15:39:02.184027 [ 0.000000] node 0: [mem 0x0000000010200000-0x000000003fe5afff]
2021-12-06T15:39:02.190980 [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003fe5afff]
2021-12-06T15:39:02.196914 [ 0.000000] On node 0, zone DMA: 421 pages in unavailable ranges
2021-12-06T15:39:02.202022 [ 0.000000] psci: probing for conduit method from DT.
2021-12-06T15:39:02.206581 [ 0.000000] psci: PSCIv0.2 detected in firmware.
2021-12-06T15:39:02.211701 [ 0.000000] psci: Using standard PSCI v0.2 function IDs
2021-12-06T15:39:02.216517 [ 0.000000] psci: Trusted OS migration not required
2021-12-06T15:39:02.222883 [ 0.000000] percpu: Embedded 19 pages/cpu s40600 r8192 d29032 u77824
2021-12-06T15:39:02.226946 [ 0.000000] Detected VIPT I-cache on CPU0
2021-12-06T15:39:02.233760 [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR
2021-12-06T15:39:02.240040 [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)
2021-12-06T15:39:02.245079 [ 0.000000] CPU features: detected: ARM erratum 845719
2021-12-06T15:39:02.251718 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 257633
2021-12-06T15:39:02.254552 [ 0.000000] Policy zone: DMA
2021-12-06T15:39:02.279132 [ 0.000000] Kernel command line: console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=10.0.70.20:/var/lib/lava/dispatcher/tmp/4331/extract-nfsrootfs-4iykwhjt,tcp,hard,vers=3 init=/init rootwait minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399 ip=dhcp
2021-12-06T15:39:02.290243 [ 0.000000] Unknown command line parameters: minio_results=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399
2021-12-06T15:39:02.298013 [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
2021-12-06T15:39:02.305510 [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
2021-12-06T15:39:02.311770 [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
2021-12-06T15:39:02.325656 [ 0.000000] Memory: 711232K/1046892K available (15296K kernel code, 2868K rwdata, 7612K rodata, 5120K init, 459K bss, 73516K reserved, 262144K cma-reserved)
2021-12-06T15:39:02.332215 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
2021-12-06T15:39:02.337829 [ 0.000000] rcu: Preemptible hierarchical RCU implementation.
2021-12-06T15:39:02.342319 [ 0.000000] rcu: RCU event tracing is enabled.
2021-12-06T15:39:02.348949 [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
2021-12-06T15:39:02.354014 [ 0.000000] Trampoline variant of Tasks RCU enabled.
2021-12-06T15:39:02.361485 [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
2021-12-06T15:39:02.368046 [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
2021-12-06T15:39:02.373567 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
2021-12-06T15:39:02.377699 [ 0.000000] Root IRQ handler: gic_handle_irq
2021-12-06T15:39:02.382363 [ 0.000000] GIC: Using split EOI/Deactivate mode
2021-12-06T15:39:02.388650 [ 0.000000] irq_meson_gpio: 110 to 8 gpio interrupt mux initialized
2021-12-06T15:39:02.396670 [ 0.000000] random: get_random_bytes called from start_kernel+0x3fc/0x598 with crng_init=0
2021-12-06T15:39:02.402817 [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
2021-12-06T15:39:02.413410 [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
2021-12-06T15:39:02.421314 [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
2021-12-06T15:39:02.425956 [ 0.000581] Console: colour dummy device 80x25
2021-12-06T15:39:02.436145 [ 0.000662] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
2021-12-06T15:39:02.440625 [ 0.000676] pid_max: default: 32768 minimum: 301
2021-12-06T15:39:02.445199 [ 0.000736] LSM: Security Framework initializing
2021-12-06T15:39:02.452416 [ 0.000799] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
2021-12-06T15:39:02.460299 [ 0.000812] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
2021-12-06T15:39:02.464980 [ 0.002140] rcu: Hierarchical SRCU implementation.
2021-12-06T15:39:02.469420 [ 0.002544] EFI services will not be available.
2021-12-06T15:39:02.473903 [ 0.002891] smp: Bringing up secondary CPUs ...
2021-12-06T15:39:02.477838 [ 0.003370] Detected VIPT I-cache on CPU1
2021-12-06T15:39:02.484420 [ 0.003439] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
2021-12-06T15:39:02.488355 [ 0.003992] Detected VIPT I-cache on CPU2
2021-12-06T15:39:02.494994 [ 0.004048] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
2021-12-06T15:39:02.498699 [ 0.004612] Detected VIPT I-cache on CPU3
2021-12-06T15:39:02.505306 [ 0.004666] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
2021-12-06T15:39:02.509300 [ 0.004747] smp: Brought up 1 node, 4 CPUs
2021-12-06T15:39:02.513873 [ 0.004768] SMP: Total of 4 processors activated.
2021-12-06T15:39:02.519057 [ 0.004775] CPU features: detected: 32-bit EL0 Support
2021-12-06T15:39:02.524302 [ 0.004779] CPU features: detected: 32-bit EL1 Support
2021-12-06T15:39:02.529203 [ 0.004786] CPU features: detected: CRC32 instructions
2021-12-06T15:39:02.533235 [ 0.011140] CPU: All CPU(s) started at EL2
2021-12-06T15:39:02.537788 [ 0.011207] alternatives: patching kernel code
2021-12-06T15:39:02.540977 [ 0.012547] devtmpfs: initialized
2021-12-06T15:39:02.545411 [ 0.017157] KASLR disabled due to lack of seed
2021-12-06T15:39:02.555037 [ 0.017346] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
2021-12-06T15:39:02.561750 [ 0.017367] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
2021-12-06T15:39:02.567123 [ 0.027732] pinctrl core: initialized pinctrl subsystem
2021-12-06T15:39:02.570708 [ 0.028542] DMI not present or invalid.
2021-12-06T15:39:02.576728 [ 0.028984] NET: Registered PF_NETLINK/PF_ROUTE protocol family
2021-12-06T15:39:02.583712 [ 0.030264] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
2021-12-06T15:39:02.591314 [ 0.030395] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
2021-12-06T15:39:02.599076 [ 0.030494] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
2021-12-06T15:39:02.604600 [ 0.030559] audit: initializing netlink subsys (disabled)
2021-12-06T15:39:02.612313 [ 0.030747] audit: type=2000 audit(0.028:1): state=initialized audit_enabled=0 res=1
2021-12-06T15:39:02.618251 [ 0.031679] thermal_sys: Registered thermal governor 'step_wise'
2021-12-06T15:39:02.624517 [ 0.031690] thermal_sys: Registered thermal governor 'power_allocator'
2021-12-06T15:39:02.628512 [ 0.031920] cpuidle: using governor menu
2021-12-06T15:39:02.635219 [ 0.032127] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
2021-12-06T15:39:02.640723 [ 0.032267] ASID allocator initialised with 32768 entries
2021-12-06T15:39:02.644731 [ 0.033093] Serial: AMBA PL011 UART driver
2021-12-06T15:39:02.652450 [ 0.046498] platform c883a000.hdmi-tx: Fixing up cyclic dependency with d0100000.vpu
2021-12-06T15:39:02.660138 [ 0.048427] platform hdmi-connector: Fixing up cyclic dependency with c883a000.hdmi-tx
2021-12-06T15:39:02.666952 [ 0.057944] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
2021-12-06T15:39:02.673389 [ 0.057965] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
2021-12-06T15:39:02.680141 [ 0.057972] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
2021-12-06T15:39:02.686709 [ 0.057979] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
2021-12-06T15:39:02.691106 [ 0.059052] cryptd: max_cpu_qlen set to 1000
2021-12-06T15:39:02.694807 [ 0.061090] ACPI: Interpreter disabled.
2021-12-06T15:39:02.698206 [ 0.062444] VCCK: supplied by DC_5V
2021-12-06T15:39:02.702126 [ 0.062652] VCC_3V3: supplied by DC_5V
2021-12-06T15:39:02.706004 [ 0.062855] VDDIO_AO18: supplied by VCC_3V3
2021-12-06T15:39:02.710200 [ 0.063060] VDDIO_BOOT: supplied by VCC_3V3
2021-12-06T15:39:02.715235 [ 0.063746] iommu: Default domain type: Translated
2021-12-06T15:39:02.721213 [ 0.063764] iommu: DMA domain TLB invalidation policy: strict mode
2021-12-06T15:39:02.723800 [ 0.064094] vgaarb: loaded
2021-12-06T15:39:02.727490 [ 0.064406] SCSI subsystem initialized
2021-12-06T15:39:02.733178 [ 0.064811] usbcore: registered new interface driver usbfs
2021-12-06T15:39:02.738259 [ 0.064849] usbcore: registered new interface driver hub
2021-12-06T15:39:02.743386 [ 0.064886] usbcore: registered new device driver usb
2021-12-06T15:39:02.748175 [ 0.065367] pps_core: LinuxPPS API ver. 1 registered
2021-12-06T15:39:02.757314 [ 0.065375] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
2021-12-06T15:39:02.761324 [ 0.065397] PTP clock support registered
2021-12-06T15:39:02.764261 [ 0.065429] EDAC MC: Ver: 3.0.0
2021-12-06T15:39:02.767513 [ 0.066829] FPGA manager framework
2021-12-06T15:39:02.773565 [ 0.066936] Advanced Linux Sound Architecture Driver Initialized.
2021-12-06T15:39:02.779936 [ 0.067944] clocksource: Switched to clocksource arch_sys_counter
2021-12-06T15:39:02.783571 [ 0.068114] VFS: Disk quotas dquot_6.6.0
2021-12-06T15:39:02.790494 [ 0.068164] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
2021-12-06T15:39:02.793903 [ 0.068361] pnp: PnP ACPI: disabled
2021-12-06T15:39:02.798874 [ 0.073516] NET: Registered PF_INET protocol family
2021-12-06T15:39:02.806214 [ 0.073642] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
2021-12-06T15:39:02.814301 [ 0.074299] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
2021-12-06T15:39:02.821970 [ 0.074342] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
2021-12-06T15:39:02.829031 [ 0.074437] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
2021-12-06T15:39:02.835665 [ 0.074549] TCP: Hash tables configured (established 8192 bind 8192)
2021-12-06T15:39:02.841969 [ 0.074679] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
2021-12-06T15:39:02.849117 [ 0.074711] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
2021-12-06T15:39:02.854481 [ 0.074862] NET: Registered PF_UNIX/PF_LOCAL protocol family
2021-12-06T15:39:02.860498 [ 0.075308] RPC: Registered named UNIX socket transport module.
2021-12-06T15:39:02.865015 [ 0.075326] RPC: Registered udp transport module.
2021-12-06T15:39:02.869707 [ 0.075330] RPC: Registered tcp transport module.
2021-12-06T15:39:02.876194 [ 0.075335] RPC: Registered tcp NFSv4.1 backchannel transport module.
2021-12-06T15:39:02.880051 [ 0.075347] PCI: CLS 0 bytes, default 64
2021-12-06T15:39:02.888302 [ 0.076136] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
2021-12-06T15:39:02.892243 [ 0.076531] kvm [1]: IPA Size Limit: 40 bits
2021-12-06T15:39:02.896218 [ 0.077825] kvm [1]: vgic interrupt IRQ9
2021-12-06T15:39:02.901303 [ 0.077938] kvm [1]: Hyp mode initialized successfully
2021-12-06T15:39:02.907868 [ 0.081520] workingset: timestamp_bits=42 max_order=18 bucket_order=0
2021-12-06T15:39:02.913376 [ 0.086976] squashfs: version 4.0 (2009/01/31) Phillip Lougher
2021-12-06T15:39:02.918487 [ 0.087627] NFS: Registering the id_resolver key type
2021-12-06T15:39:02.922504 [ 0.087664] Key type id_resolver registered
2021-12-06T15:39:02.926489 [ 0.087670] Key type id_legacy registered
2021-12-06T15:39:02.933134 [ 0.087743] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
2021-12-06T15:39:02.940622 [ 0.087751] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
2021-12-06T15:39:02.945925 [ 0.087962] 9p: Installing v9fs 9p2000 file system support
2021-12-06T15:39:02.953455 [ 0.124927] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
2021-12-06T15:39:02.957886 [ 0.124951] io scheduler mq-deadline registered
2021-12-06T15:39:02.962024 [ 0.124958] io scheduler kyber registered
2021-12-06T15:39:02.964968 [ 0.134022] EINJ: ACPI disabled.
2021-12-06T15:39:02.972167 [ 0.143382] soc soc0: Amlogic Meson GXL (S805X) Revision 21:d (34:2) Detected
2021-12-06T15:39:02.978154 [ 0.148437] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
2021-12-06T15:39:02.987741 [ 0.150809] c81004c0.serial: ttyAML0 at MMIO 0xc81004c0 (irq = 22, base_baud = 1500000) is a meson_uart
2021-12-06T15:39:02.991930 [ 1.088334] printk: console [ttyAML0] enabled
2021-12-06T15:39:02.996009 [ 1.093386] msm_serial: driver initialized
2021-12-06T15:39:03.007048 [ 1.106983] lima d00c0000.gpu: gp - mali450 version major 0 minor 0
2021-12-06T15:39:03.013144 [ 1.107654] lima d00c0000.gpu: pp0 - mali450 version major 0 minor 0
2021-12-06T15:39:03.019435 [ 1.113973] lima d00c0000.gpu: pp1 - mali450 version major 0 minor 0
2021-12-06T15:39:03.025629 [ 1.120253] lima d00c0000.gpu: pp2 - mali450 version major 0 minor 0
2021-12-06T15:39:03.034097 [ 1.126552] lima d00c0000.gpu: l2 cache 8K, 4-way, 64byte cache line, 128bit external bus
2021-12-06T15:39:03.042390 [ 1.134641] lima d00c0000.gpu: l2 cache 64K, 4-way, 64byte cache line, 128bit external bus
2021-12-06T15:39:03.047114 [ 1.143204] lima d00c0000.gpu: bus rate = 166666667
2021-12-06T15:39:03.051755 [ 1.147664] lima d00c0000.gpu: mod rate = 24000000
2021-12-06T15:39:03.059799 [ 1.152472] lima d00c0000.gpu: dev_pm_opp_set_regulators: no regulator (mali) found: -19
2021-12-06T15:39:03.065738 [ 1.160950] lima d00c0000.gpu: Failed to register cooling device
2021-12-06T15:39:03.072768 [ 1.166672] [drm] Initialized lima 1.1.0 20191231 for d00c0000.gpu on minor 0
2021-12-06T15:39:03.076776 [ 1.179866] loop: module loaded
2021-12-06T15:39:03.080234 [ 1.180668] megasas: 07.717.02.00-rc1
2021-12-06T15:39:03.084542 [ 1.184412] libphy: Fixed MDIO Bus: probed
2021-12-06T15:39:03.089290 [ 1.186563] tun: Universal TUN/TAP device driver, 1.6
2021-12-06T15:39:03.092756 [ 1.190924] thunder_xcv, ver 1.0
2021-12-06T15:39:03.095785 [ 1.193321] thunder_bgx, ver 1.0
2021-12-06T15:39:03.098436 [ 1.196495] nicpf, ver 1.0
2021-12-06T15:39:03.101613 [ 1.199988] hclge is initializing
2021-12-06T15:39:03.108950 [ 1.202424] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version
2021-12-06T15:39:03.114161 [ 1.209570] hns3: Copyright (c) 2017 Huawei Corporation.
2021-12-06T15:39:03.118952 [ 1.214912] e1000: Intel(R) PRO/1000 Network Driver
2021-12-06T15:39:03.124579 [ 1.219673] e1000: Copyright (c) 1999-2006 Intel Corporation.
2021-12-06T15:39:03.129763 [ 1.225407] e1000e: Intel(R) PRO/1000 Network Driver
2021-12-06T15:39:03.135524 [ 1.230268] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
2021-12-06T15:39:03.141124 [ 1.236183] igb: Intel(R) Gigabit Ethernet Network Driver
2021-12-06T15:39:03.146356 [ 1.241481] igb: Copyright (c) 2007-2014 Intel Corporation.
2021-12-06T15:39:03.152502 [ 1.247033] igbvf: Intel(R) Gigabit Virtual Function Network Driver
2021-12-06T15:39:03.158554 [ 1.253210] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
2021-12-06T15:39:03.162170 [ 1.259108] sky2: driver version 1.30
2021-12-06T15:39:03.167479 [ 1.264369] usbcore: registered new interface driver r8152
2021-12-06T15:39:03.172750 [ 1.268178] usbcore: registered new interface driver asix
2021-12-06T15:39:03.178849 [ 1.273523] usbcore: registered new interface driver ax88179_178a
2021-12-06T15:39:03.184538 [ 1.279543] usbcore: registered new interface driver cdc_ether
2021-12-06T15:39:03.190227 [ 1.285326] usbcore: registered new interface driver smsc95xx
2021-12-06T15:39:03.196049 [ 1.291017] usbcore: registered new interface driver net1080
2021-12-06T15:39:03.201754 [ 1.296619] usbcore: registered new interface driver cdc_subset
2021-12-06T15:39:03.207205 [ 1.302493] usbcore: registered new interface driver zaurus
2021-12-06T15:39:03.212985 [ 1.308020] usbcore: registered new interface driver cdc_ncm
2021-12-06T15:39:03.217976 [ 1.313780] VFIO - User Level meta-driver version: 0.3
2021-12-06T15:39:03.224473 [ 1.320631] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
2021-12-06T15:39:03.228793 [ 1.325157] ehci-pci: EHCI PCI platform driver
2021-12-06T15:39:03.233852 [ 1.329610] ehci-platform: EHCI generic platform driver
2021-12-06T15:39:03.240223 [ 1.334909] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
2021-12-06T15:39:03.244604 [ 1.340875] ohci-pci: OHCI PCI platform driver
2021-12-06T15:39:03.250938 [ 1.345290] ohci-platform: OHCI generic platform driver
2021-12-06T15:39:03.255736 [ 1.350956] usbcore: registered new interface driver usb-storage
2021-12-06T15:39:03.259720 [ 1.358457] i2c_dev: i2c /dev entries driver
2021-12-06T15:39:03.266001 [ 1.364845] sdhci: Secure Digital Host Controller Interface driver
2021-12-06T15:39:03.270381 [ 1.366711] sdhci: Copyright(c) Pierre Ossman
2021-12-06T15:39:03.276342 [ 1.371583] Synopsys Designware Multimedia Card Interface Driver
2021-12-06T15:39:03.281869 [ 1.377989] sdhci-pltfm: SDHCI platform and OF driver helper
2021-12-06T15:39:03.287433 [ 1.378442] meson-gx-mmc d0074000.mmc: allocated mmc-pwrseq
2021-12-06T15:39:03.293439 [ 1.388152] ledtrig-cpu: registered to indicate activity on CPUs
2021-12-06T15:39:03.297514 [ 1.395506] meson-sm: secure-monitor enabled
2021-12-06T15:39:03.303144 [ 1.399305] usbcore: registered new interface driver usbhid
2021-12-06T15:39:03.306809 [ 1.403834] usbhid: USB HID core driver
2021-12-06T15:39:03.313681 [ 1.408767] platform-mhu c883c404.mailbox: Platform MHU Mailbox registered
2021-12-06T15:39:03.318958 [ 1.418232] gnss: GNSS driver registered with major 234
2021-12-06T15:39:03.323855 [ 1.422563] NET: Registered PF_PACKET protocol family
2021-12-06T15:39:03.327965 [ 1.424767] 9pnet: Installing 9P2000 support
2021-12-06T15:39:03.332460 [ 1.428877] Key type dns_resolver registered
2021-12-06T15:39:03.348484 [ 1.449211] meson-drm d0100000.vpu: Queued 2 outputs on vpu
2021-12-06T15:39:03.355049 [ 1.449806] meson-drm d0100000.vpu: CVBS Output connector not available
2021-12-06T15:39:03.387491 [ 1.483997] meson-dw-hdmi c883a000.hdmi-tx: Detected HDMI TX controller v2.01a with HDCP (meson_dw_hdmi_phy)
2021-12-06T15:39:03.395260 [ 1.488687] meson-dw-hdmi c883a000.hdmi-tx: registered DesignWare HDMI I2C bus driver
2021-12-06T15:39:03.402683 [ 1.497197] meson-drm d0100000.vpu: bound c883a000.hdmi-tx (ops meson_dw_hdmi_ops)
2021-12-06T15:39:03.410098 [ 1.503877] [drm] Initialized meson 1.0.0 20161109 for d0100000.vpu on minor 1
2021-12-06T15:39:03.416321 [ 1.510794] meson-drm d0100000.vpu: [drm] Cannot find any crtc or sizes
2021-12-06T15:39:03.422963 [ 1.521158] meson8b-dwmac c9410000.ethernet: IRQ eth_wake_irq not found
2021-12-06T15:39:03.429248 [ 1.523704] meson8b-dwmac c9410000.ethernet: IRQ eth_lpi not found
2021-12-06T15:39:03.435147 [ 1.529972] meson8b-dwmac c9410000.ethernet: PTP uses main clock
2021-12-06T15:39:03.442324 [ 1.536890] meson8b-dwmac c9410000.ethernet: User ID: 0x11, Synopsys ID: 0x37
2021-12-06T15:39:03.447526 [ 1.542934] meson8b-dwmac c9410000.ethernet: DWMAC1000
2021-12-06T15:39:03.454649 [ 1.548035] meson8b-dwmac c9410000.ethernet: DMA HW capability register supported
2021-12-06T15:39:03.459772 [ 1.549381] mmc1: new HS200 MMC card at address 0001
2021-12-06T15:39:03.467002 [ 1.555449] meson8b-dwmac c9410000.ethernet: RX Checksum Offload Engine supported
2021-12-06T15:39:03.471800 [ 1.561224] mmcblk1: mmc1:0001 H8G4a� 7.28 GiB
2021-12-06T15:39:03.476756 [ 1.567788] meson8b-dwmac c9410000.ethernet: COE Type 2
2021-12-06T15:39:03.483941 [ 1.567794] meson8b-dwmac c9410000.ethernet: TX Checksum insertion supported
2021-12-06T15:39:03.490281 [ 1.567798] meson8b-dwmac c9410000.ethernet: Wake-Up On Lan supported
2021-12-06T15:39:03.494863 [ 1.575552] mmcblk1boot0: mmc1:0001 H8G4a� 4.00 MiB
2021-12-06T15:39:03.500731 [ 1.577536] meson8b-dwmac c9410000.ethernet: Normal descriptors
2021-12-06T15:39:03.505691 [ 1.586221] mmcblk1boot1: mmc1:0001 H8G4a� 4.00 MiB
2021-12-06T15:39:03.511530 [ 1.590826] meson8b-dwmac c9410000.ethernet: Ring mode enabled
2021-12-06T15:39:03.519580 [ 1.590832] meson8b-dwmac c9410000.ethernet: Enable RX Mitigation via HW Watchdog Timer
2021-12-06T15:39:03.522806 [ 1.591291] libphy: stmmac: probed
2021-12-06T15:39:03.529160 [ 1.597265] mmcblk1rpmb: mmc1:0001 H8G4a� 4.00 MiB, chardev (235:0)
2021-12-06T15:39:03.534184 [ 1.604371] dwc3-meson-g12a d0078080.usb: USB2 ports: 2
2021-12-06T15:39:03.539669 [ 1.634971] dwc3-meson-g12a d0078080.usb: USB3 ports: 0
2021-12-06T15:39:04.078394 [ 2.177603] dwc2 c9100000.usb: supply vusb_d not found, using dummy regulator
2021-12-06T15:39:04.085744 [ 2.179269] dwc2 c9100000.usb: supply vusb_a not found, using dummy regulator
2021-12-06T15:39:04.092615 [ 2.186490] dwc2 c9100000.usb: EPs: 7, dedicated fifos, 712 entries in SPRAM
2021-12-06T15:39:04.097692 [ 2.195696] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
2021-12-06T15:39:04.105581 [ 2.198657] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
2021-12-06T15:39:04.115029 [ 2.206322] xhci-hcd xhci-hcd.2.auto: hcc params 0x0228f664 hci version 0x100 quirks 0x0000000002010010
2021-12-06T15:39:04.120629 [ 2.215547] xhci-hcd xhci-hcd.2.auto: irq 47, io mem 0xc9000000
2021-12-06T15:39:04.126133 [ 2.221491] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
2021-12-06T15:39:04.133666 [ 2.226810] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
2021-12-06T15:39:04.140394 [ 2.234399] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
2021-12-06T15:39:04.144014 [ 2.241437] hub 1-0:1.0: USB hub found
2021-12-06T15:39:04.148053 [ 2.244602] hub 1-0:1.0: 2 ports detected
2021-12-06T15:39:04.155981 [ 2.248860] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
2021-12-06T15:39:04.159629 [ 2.257063] hub 2-0:1.0: USB hub found
2021-12-06T15:39:04.166670 [ 2.260301] hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19)
2021-12-06T15:39:04.173290 [ 2.269389] scpi_protocol scpi: SCP Protocol legacy pre-1.0 firmware
2021-12-06T15:39:04.174955 domain-0 init dvfs: 4
2021-12-06T15:39:04.189573 [ 2.292121] libphy: mdio_mux: probed
2021-12-06T15:39:04.193073 [ 2.293636] libphy: mdio_mux: probed
2021-12-06T15:39:04.262715 [ 2.359074] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)
2021-12-06T15:39:04.270383 [ 2.364944] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
2021-12-06T15:39:04.283856 [ 2.382415] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found
2021-12-06T15:39:04.290602 [ 2.384391] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW
2021-12-06T15:39:04.298088 [ 2.391781] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode
2021-12-06T15:39:05.850169 [ 3.947925] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx
2021-12-06T15:40:27.168101 [ 3.976051] Sending DHCP requests ...... timed out!
2021-12-06T15:40:27.174157 [ 85.273090] meson8b-dwmac c9410000.ethernet eth0: Link is Down
2021-12-06T15:40:27.179284 [ 85.278393] IP-Config: Retrying forever (NFS root)...
2021-12-06T15:40:27.250821 [ 85.349289] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)
2021-12-06T15:40:27.258136 [ 85.356103] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
2021-12-06T15:40:27.270768 [ 85.372094] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found
2021-12-06T15:40:27.277621 [ 85.374043] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW
2021-12-06T15:40:27.285226 [ 85.380840] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode
2021-12-06T15:40:28.816430 [ 86.916597] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx
2021-12-06T15:40:31.209767 [ 86.944022] Sending DHCP requests ..
2021-12-06T15:40:31.212890 [ 89.314694] random: fast init done
2021-12-06T15:41:45.899993 [ 93.248210] .... timed out!
2021-12-06T15:41:45.905730 [ 164.009078] meson8b-dwmac c9410000.ethernet eth0: Link is Down
2021-12-06T15:41:45.910646 [ 164.012514] IP-Config: Retrying forever (NFS root)...
2021-12-06T15:41:45.979619 [ 164.080601] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)
2021-12-06T15:41:45.988281 [ 164.091363] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
2021-12-06T15:41:46.005809 [ 164.109301] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found
2021-12-06T15:41:46.012452 [ 164.111359] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW
2021-12-06T15:41:46.020104 [ 164.118253] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode
2021-12-06T15:41:47.587814 [ 165.690535] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx
2021-12-06T15:43:04.991552 [ 165.716021] Sending DHCP requests ...... timed out!
2021-12-06T15:43:04.997670 [ 243.101116] meson8b-dwmac c9410000.ethernet eth0: Link is Down
2021-12-06T15:43:05.002835 [ 243.106342] IP-Config: Retrying forever (NFS root)...
2021-12-06T15:43:05.074100 [ 243.177326] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)
2021-12-06T15:43:05.081575 [ 243.184180] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
2021-12-06T15:43:05.094704 [ 243.200137] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found
2021-12-06T15:43:05.100963 [ 243.202088] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW
2021-12-06T15:43:05.108682 [ 243.208886] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode
2021-12-06T15:43:06.694943 [ 244.799967] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx
2021-12-06T15:44:01.124633 [ 244.832054] Sending DHCP requests ......
2021-12-06T15:44:01.127418 [ 299.234995] random: crng init done
2021-12-06T15:44:28.911600 [ 327.024142] timed out!
2021-12-06T15:44:28.916558 [ 327.025140] meson8b-dwmac c9410000.ethernet eth0: Link is Down
2021-12-06T15:44:28.922008 [ 327.028580] IP-Config: Retrying forever (NFS root)...
2021-12-06T15:44:28.989706 [ 327.095135] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)
2021-12-06T15:44:28.997121 [ 327.100759] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
2021-12-06T15:44:29.010023 [ 327.117859] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found
2021-12-06T15:44:29.016733 [ 327.119802] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW
2021-12-06T15:44:29.024336 [ 327.126562] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode
2021-12-06T15:44:30.602549 [ 328.709693] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx
2021-12-06T15:44:30.640302 [ 328.735954] Sending DHCP requests ., OK
2021-12-06T15:44:30.647354 [ 328.752036] IP-Config: Got DHCP answer from 10.0.70.100, my address is 10.0.70.82
2021-12-06T15:44:30.650464 [ 328.757611] IP-Config: Complete:
2021-12-06T15:44:30.660484 [ 328.760798] device=eth0, hwaddr=12:f9:c0:d1:5f:21, ipaddr=10.0.70.82, mask=255.255.255.0, gw=10.0.70.100
2021-12-06T15:44:30.668873 [ 328.770640] host=meson-gxl-s805x-libretech-ac-2, domain=enunes.eu, nis-domain=(none)
2021-12-06T15:44:30.675247 [ 328.778735] bootserver=10.0.70.100, rootserver=10.0.70.20, rootpath=
2021-12-06T15:44:30.679075 [ 328.778749] nameserver0=10.0.70.100
2021-12-06T15:44:30.682108 [ 328.790057] ALSA device list:
2021-12-06T15:44:30.685690 [ 328.792313] No soundcards found.
2021-12-06T15:44:30.704839 [ 328.814561] VFS: Mounted root (nfs filesystem) on device 0:21.
2021-12-06T15:44:30.707655 [ 328.815365] devtmpfs: mounted
2021-12-06T15:44:30.712115 [ 328.819563] Freeing unused kernel memory: 5120K
2021-12-06T15:44:30.731937 [ 328.844060] Run /init as init process
2021-12-06T15:44:30.895215 sh: 0: can't access tty; job control turned off
2021-12-06T15:44:30.896816 Matched prompt #4: lava-shell:
2021-12-06T15:44:30.896978 {'case': 'kernel-messages', 'definition': 'lava', 'duration': '328.84', 'extra': {'extra': [{'success': 'lava-shell:'}]}, 'level': '2.4.4', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:44:30.897348 end: 2.4.4 auto-login-action (duration 00:05:29) [common]
2021-12-06T15:44:30.897479 {'case': 'auto-login-action', 'definition': 'lava', 'duration': '329.09', 'extra': OrderedDict([('success', 'lava-shell:')]), 'level': '2.4.4', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:44:30.897738 start: 2.4.5 expect-shell-connection (timeout 00:19:13) [common]
2021-12-06T15:44:30.897866 Forcing a shell prompt, looking for ['lava-shell:']
2021-12-06T15:44:30.898002
2021-12-06T15:44:30.948214 lava-shell:
2021-12-06T15:44:30.948459 expect-shell-connection: Wait for prompt ['lava-shell:'] (timeout 00:25:00)
2021-12-06T15:44:30.949726
2021-12-06T15:44:30.950828 end: 2.4.5 expect-shell-connection (duration 00:00:00) [common]
2021-12-06T15:44:30.950997 start: 2.4.6 export-device-env (timeout 00:19:13) [common]
2021-12-06T15:44:30.951171 end: 2.4.6 export-device-env (duration 00:00:00) [common]
2021-12-06T15:44:30.951338 end: 2.4 uboot-retry (duration 00:05:47) [common]
2021-12-06T15:44:30.951528 end: 2 uboot-action (duration 00:05:47) [common]
2021-12-06T15:44:30.951684 start: 3 lava-test-retry (timeout 00:23:11) [common]
2021-12-06T15:44:30.951838 start: 3.1 lava-test-shell (timeout 00:23:11) [common]
2021-12-06T15:44:30.951974 Using namespace: common
2021-12-06T15:44:30.952118 #
2021-12-06T15:44:31.052396 lava-shell:#
2021-12-06T15:44:31.052571 lava-test-shell: Wait for prompt ['lava-shell:'] (timeout 00:30:00)
2021-12-06T15:44:31.054139 #
2021-12-06T15:44:31.055125 Using /lava-4331
2021-12-06T15:44:31.055291 export SHELL=/bin/sh
2021-12-06T15:44:31.155600 lava-shell:export SHELL=/bin/sh
2021-12-06T15:44:31.155940 /lava-4331/bin/lava-test-runner /lava-4331/0
2021-12-06T15:44:31.256552 /lava-4331/bin/lava-test-runner /lava-4331/0
2021-12-06T15:44:31.257931 Test shell timeout: 10s (minimum of the action and connection timeout)
2021-12-06T15:44:31.258819 export SHELL=/bin/sh
2021-12-06T15:44:31.260520 lava-shell:/lava-4331/bin/lava-test-runner /lava-4331/0
2021-12-06T15:44:31.553846 + export TESTRUN_ID=0_mesa
2021-12-06T15:44:31.556671 + cd /lava-4331/0/tests/0_mesa
2021-12-06T15:44:31.557680 + cat uuid
2021-12-06T15:44:31.561495 + UUID=4331_1.4.2.4.1
2021-12-06T15:44:31.562279 + set +x
2021-12-06T15:44:31.566292 Received signal: <STARTRUN> 0_mesa 4331_1.4.2.4.1
2021-12-06T15:44:31.566486 Starting test lava.0_mesa (4331_1.4.2.4.1)
2021-12-06T15:44:31.566973 Enabling test definition pattern 'hwci: (?P<test_case_id>\\S*):\\s+(?P<result>(pass|fail))'
2021-12-06T15:44:31.567173 Enabling test definition fixup ''
2021-12-06T15:44:31.567473 <LAVA_SIGNAL_STARTRUN 0_mesa 4331_1.4.2.4.1>
2021-12-06T15:44:31.567681 + set -ex
2021-12-06T15:44:31.568071 + cd /
2021-12-06T15:44:31.570442 + mount -t proc none /proc
2021-12-06T15:44:31.657776 + mount -t sysfs none /sys
2021-12-06T15:44:31.673381 + mount -t devtmpfs none /dev
2021-12-06T15:44:31.694041 mount: /dev: none already mounted on /proc.
2021-12-06T15:44:31.696827 + echo possibly already mounted
2021-12-06T15:44:31.699124 possibly already mounted
2021-12-06T15:44:31.700998 + mkdir -p /dev/pts
2021-12-06T15:44:31.705119 + mount -t devpts devpts /dev/pts
2021-12-06T15:44:31.724503 + mount -t tmpfs tmpfs /tmp
2021-12-06T15:44:31.742541 + echo nameserver 8.8.8.8
2021-12-06T15:44:31.743524 + [ -z ]
2021-12-06T15:44:31.745636 + sntp -sS pool.ntp.org
2021-12-06T15:44:32.065519 sntp 4.2.8p15@1.3728-o Wed Sep 23 11:46:38 UTC 2020 (1)
2021-12-06T15:44:32.073116 kod_init_kod_db(): Cannot open KoD db file /var/lib/sntp/kod: No such file or directory
2021-12-06T15:44:32.161903 1970-01-01 00:05:30.253094 (+0000) +1638805141.893341 +/- 1092536761.291870 pool.ntp.org 89.221.210.188 s2 no-leap
2021-12-06T15:44:32.165764 + break
2021-12-06T15:44:32.166497 + set +x
2021-12-06T15:44:32.172391 + mkdir -p /builds/enunes/mesa
2021-12-06T15:44:32.199580 + wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/mesa-arm64.tar.gz
2021-12-06T15:44:32.204081 + tar -xz -C /builds/enunes/mesa
2021-12-06T15:44:32.684910 --2021-12-06 15:44:32-- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/mesa-arm64.tar.gz
2021-12-06T15:44:32.914094 Resolving minio-packet.freedesktop.org (minio-packet.freedesktop.org)... 147.75.198.117
2021-12-06T15:44:33.014337 Connecting to minio-packet.freedesktop.org (minio-packet.freedesktop.org)|147.75.198.117|:443... connected.
2021-12-06T15:44:33.413353 HTTP request sent, awaiting response...
2021-12-06T15:44:33.414977 HTTP/1.1 200 OK
2021-12-06T15:44:33.418335 Date: Mon, 06 Dec 2021 15:44:33 GMT
2021-12-06T15:44:33.421480 Content-Type: binary/octet-stream
2021-12-06T15:44:33.423996 Content-Length: 12236072
2021-12-06T15:44:33.426222 Connection: keep-alive
2021-12-06T15:44:33.428195 Accept-Ranges: bytes
2021-12-06T15:44:33.432725 Content-Security-Policy: block-all-mixed-content
2021-12-06T15:44:33.436752 ETag: \"c61d569e1454eae030bdf3e5007a4d88-2\"
2021-12-06T15:44:33.440916 Last-Modified: Mon, 06 Dec 2021 15:36:18 GMT
2021-12-06T15:44:33.442180 Vary: Origin
2021-12-06T15:44:33.445616 X-Amz-Request-Id: 16BE34E52FD4060F
2021-12-06T15:44:33.448632 X-Xss-Protection: 1; mode=block
2021-12-06T15:44:33.456905 x-amz-expiration: expiry-date=\"Thu, 06 Jan 2022 00:00:00 GMT\", rule-id=\"Artifacts - 4 weeks\"
2021-12-06T15:44:33.462452 Strict-Transport-Security: max-age=15724800; includeSubDomains
2021-12-06T15:44:33.465461 Access-Control-Allow-Origin: *
2021-12-06T15:44:33.469363 Length: 12236072 (12M) [binary/octet-stream]
2021-12-06T15:44:33.471287 Saving to: 'STDOUT'
2021-12-06T15:44:37.234252
2021-12-06T15:44:47.115840 0K ........ ... 100% 872K=14s
2021-12-06T15:44:47.116314
2021-12-06T15:44:47.122254 2021-12-06 15:44:47 (872 KB/s) - written to stdout [12236072/12236072]
2021-12-06T15:44:47.122404
2021-12-06T15:44:49.200482 + wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399/job-rootfs-overlay.tar.gz
2021-12-06T15:44:49.202015 + tar -xz -C /
2021-12-06T15:44:49.239155 --2021-12-06 15:44:49-- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399/job-rootfs-overlay.tar.gz
2021-12-06T15:44:49.319508 Resolving minio-packet.freedesktop.org (minio-packet.freedesktop.org)... 147.75.198.117
2021-12-06T15:44:49.419012 Connecting to minio-packet.freedesktop.org (minio-packet.freedesktop.org)|147.75.198.117|:443... connected.
2021-12-06T15:44:49.772410 HTTP request sent, awaiting response...
2021-12-06T15:44:49.773975 HTTP/1.1 200 OK
2021-12-06T15:44:49.777246 Date: Mon, 06 Dec 2021 15:44:49 GMT
2021-12-06T15:44:49.780453 Content-Type: binary/octet-stream
2021-12-06T15:44:49.782504 Content-Length: 2569
2021-12-06T15:44:49.784732 Connection: keep-alive
2021-12-06T15:44:49.786820 Accept-Ranges: bytes
2021-12-06T15:44:49.791391 Content-Security-Policy: block-all-mixed-content
2021-12-06T15:44:49.795200 ETag: \"d0225c9aeac47fda874431edebdfe4f4\"
2021-12-06T15:44:49.799336 Last-Modified: Mon, 06 Dec 2021 15:37:02 GMT
2021-12-06T15:44:49.800660 Vary: Origin
2021-12-06T15:44:49.804069 X-Amz-Request-Id: 16BE34E8FEF6B7F6
2021-12-06T15:44:49.807029 X-Xss-Protection: 1; mode=block
2021-12-06T15:44:49.815296 x-amz-expiration: expiry-date=\"Thu, 06 Jan 2022 00:00:00 GMT\", rule-id=\"Artifacts - 4 weeks\"
2021-12-06T15:44:49.820989 Strict-Transport-Security: max-age=15724800; includeSubDomains
2021-12-06T15:44:49.823929 Access-Control-Allow-Origin: *
2021-12-06T15:44:49.827665 Length: 2569 (2.5K) [binary/octet-stream]
2021-12-06T15:44:49.829480 Saving to: 'STDOUT'
2021-12-06T15:44:49.829844
2021-12-06T15:44:49.834553 0K 100% 35.9M=0s
2021-12-06T15:44:49.834919
2021-12-06T15:44:49.840291 2021-12-06 15:44:49 (35.9 MB/s) - written to stdout [2569/2569]
2021-12-06T15:44:49.840645
2021-12-06T15:44:49.844113 + echo export CI_JOB_JWT_FILE=/minio_jwt
2021-12-06T15:44:49.846297 + exec /init-stage2.sh
2021-12-06T15:44:49.847493 + [ -z ]
2021-12-06T15:44:49.851465 + ln -sf /builds/enunes/mesa/install /install
2021-12-06T15:44:49.868969 + export LD_LIBRARY_PATH=/install/lib
2021-12-06T15:44:49.873073 + export LIBGL_DRIVERS_PATH=/install/lib/dri
2021-12-06T15:44:49.875713 + export XDG_CACHE_HOME=/tmp
2021-12-06T15:44:49.879993 + python3 -c import sys;print(\":\".join(sys.path))
2021-12-06T15:44:50.459358 + export PYTHONPATH=:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/local/lib/python3.9/dist-packages:/usr/lib/python3/dist-packages:/usr/lib/python3.9/dist-packages
2021-12-06T15:44:50.460512 + [ = true ]
2021-12-06T15:44:50.461530 + [ -n ]
2021-12-06T15:44:50.462912 + + RESULT=fail
2021-12-06T15:44:50.465261 ./capture-devcoredump.sh
2021-12-06T15:44:50.468584 + sh /install/piglit/piglit-runner.sh
2021-12-06T15:44:50.469815 + [ -z lima ]
2021-12-06T15:44:50.470457 + pwd
2021-12-06T15:44:50.472383 + INSTALL=//install
2021-12-06T15:44:50.473326 + pwd
2021-12-06T15:44:50.476518 + export LD_LIBRARY_PATH=//install/lib/
2021-12-06T15:44:50.479513 + export EGL_PLATFORM=surfaceless
2021-12-06T15:44:50.480157 + pwd
2021-12-06T15:44:50.481164 + uname -m
2021-12-06T15:44:50.494006 + export VK_ICD_FILENAMES=//install/share/vulkan/icd.d/_icd.aarch64.json
2021-12-06T15:44:50.494116 + pwd
2021-12-06T15:44:50.494201 + RESULTS=//results
2021-12-06T15:44:50.494281 + mkdir -p //results
2021-12-06T15:44:50.511793 + [ -n -o -n 2 ]
2021-12-06T15:44:50.512980 + expr 1 * 2
2021-12-06T15:44:50.533275 + FRACTION=2
2021-12-06T15:44:50.536775 + PIGLIT_RUNNER_OPTIONS= --fraction 2
2021-12-06T15:44:50.537713 + [ -n 2 ]
2021-12-06T15:44:50.542780 + PIGLIT_RUNNER_OPTIONS= --fraction 2 --fraction-start 2
2021-12-06T15:44:50.545854 + [ -e //install/lima-fails.txt ]
2021-12-06T15:44:50.553857 + PIGLIT_RUNNER_OPTIONS= --fraction 2 --fraction-start 2 --baseline //install/lima-fails.txt
2021-12-06T15:44:50.556877 + touch //install/lima-flakes.txt
2021-12-06T15:44:50.557888 + [ -n ]
2021-12-06T15:44:50.558857 + [ -n ]
2021-12-06T15:44:50.559682 + [ -n ]
2021-12-06T15:44:50.562848 + [ -e //install/lima-skips.txt ]
2021-12-06T15:44:50.568696 + PIGLIT_SKIPS= //install/lima-skips.txt
2021-12-06T15:44:50.568797 + set +e
2021-12-06T15:44:50.590759 + piglit-runner run --piglit-folder /piglit --output //results --jobs 4 --skips //install/all-skips.txt //install/lima-skips.txt --flakes //install/lima-flakes.txt --profile gpu --process-isolation --fraction 2 --fraction-start 2 --baseline //install/lima-fails.txt -v -v
2021-12-06T15:44:50.807536 [0m[33mINFO - ... using \"/piglit/tests/gpu.meta.xml\"
2021-12-06T15:44:50.811676 [0m[0m[33mINFO - Found subprofile: \"quick_gl\"
2021-12-06T15:44:50.817235 [0m[0m[33mINFO - ... using \"/piglit/tests/quick_gl.xml.gz\"
2021-12-06T15:44:51.011730 [0m[0m[33mINFO - Found subprofile: \"quick_shader\"
2021-12-06T15:44:51.020207 [0m[0m[33mINFO - ... using \"/piglit/tests/quick_shader.xml.gz\"
2021-12-06T15:44:51.624329 [0mRunning 12105 piglit tests on 4 threads
2021-12-06T15:44:51.660856 Pass: 0, Duration: 0
2021-12-06T15:44:53.748172 Pass: 1, Skip: 12, Duration: 2, Remaining: 32:19
2021-12-06T15:44:55.753334 Pass: 9, Skip: 59, Duration: 4, Remaining: 12:04
2021-12-06T15:44:57.778698 Pass: 24, ExpectedFail: 3, Skip: 96, Duration: 6, Remaining: 10:43
2021-12-06T15:44:59.874607 Pass: 37, ExpectedFail: 4, Skip: 156, Duration: 8, Remaining: 8:51
2021-12-06T15:45:01.890184 Pass: 50, ExpectedFail: 4, Skip: 206, Duration: 10, Remaining: 8:10
2021-12-06T15:45:03.930056 Pass: 59, ExpectedFail: 5, Skip: 253, Duration: 12, Remaining: 7:56
2021-12-06T15:45:05.972571 Pass: 68, ExpectedFail: 7, Skip: 296, Duration: 14, Remaining: 7:49
2021-12-06T15:45:08.023449 Pass: 76, ExpectedFail: 8, Skip: 339, Duration: 16, Remaining: 7:46
2021-12-06T15:45:10.060858 Pass: 85, ExpectedFail: 9, Skip: 391, Duration: 18, Remaining: 7:33
2021-12-06T15:45:12.120294 Pass: 102, ExpectedFail: 9, Skip: 421, Duration: 20, Remaining: 7:36
2021-12-06T15:45:14.121209 Pass: 111, ExpectedFail: 10, Skip: 475, Duration: 22, Remaining: 7:23
2021-12-06T15:45:16.141467 Pass: 121, ExpectedFail: 11, Skip: 524, Duration: 24, Remaining: 7:16
2021-12-06T15:45:18.243000 Pass: 134, ExpectedFail: 12, Skip: 572, Duration: 26, Remaining: 7:13
2021-12-06T15:45:20.297220 Pass: 144, ExpectedFail: 12, Skip: 623, Duration: 28, Remaining: 7:07
2021-12-06T15:45:22.375408 Pass: 154, ExpectedFail: 13, Skip: 679, Duration: 30, Remaining: 7:02
2021-12-06T15:45:24.404261 Pass: 186, ExpectedFail: 16, Skip: 741, Duration: 32, Remaining: 6:50
2021-12-06T15:45:26.428107 Pass: 205, ExpectedFail: 16, Skip: 786, Duration: 34, Remaining: 6:50
2021-12-06T15:45:28.480934 Pass: 210, ExpectedFail: 17, Skip: 817, Duration: 36, Remaining: 6:56
2021-12-06T15:45:30.548304 Pass: 220, ExpectedFail: 18, Skip: 853, Duration: 38, Remaining: 6:58
2021-12-06T15:45:32.685072 Pass: 229, ExpectedFail: 18, Skip: 916, Duration: 41, Remaining: 6:49
2021-12-06T15:45:34.686820 Pass: 239, ExpectedFail: 19, Skip: 971, Duration: 43, Remaining: 6:42
2021-12-06T15:45:36.789149 Pass: 248, ExpectedFail: 19, Skip: 1013, Duration: 45, Remaining: 6:42
2021-12-06T15:45:38.817710 Pass: 259, ExpectedFail: 20, Skip: 1067, Duration: 47, Remaining: 6:37
2021-12-06T15:45:40.850176 Pass: 272, ExpectedFail: 23, Skip: 1117, Duration: 49, Remaining: 6:33
2021-12-06T15:45:42.896566 Pass: 281, ExpectedFail: 26, Skip: 1164, Duration: 51, Remaining: 6:30
2021-12-06T15:45:44.902548 Pass: 291, ExpectedFail: 38, Skip: 1221, Duration: 53, Remaining: 6:25
2021-12-06T15:45:46.927313 Pass: 300, ExpectedFail: 40, Skip: 1266, Duration: 55, Remaining: 6:23
2021-12-06T15:45:48.994640 Pass: 309, ExpectedFail: 40, Skip: 1317, Duration: 57, Remaining: 6:20
2021-12-06T15:45:51.079656 Pass: 315, ExpectedFail: 42, Skip: 1353, Duration: 59, Remaining: 6:21
2021-12-06T15:45:53.102267 Pass: 322, ExpectedFail: 44, Skip: 1396, Duration: 1:01, Remaining: 6:20
2021-12-06T15:45:55.167719 Pass: 333, ExpectedFail: 48, Skip: 1430, Duration: 1:03, Remaining: 6:21
2021-12-06T15:45:57.244718 Pass: 337, ExpectedFail: 50, Skip: 1488, Duration: 1:05, Remaining: 6:17
2021-12-06T15:45:59.329612 Pass: 365, ExpectedFail: 51, Skip: 1554, Duration: 1:07, Remaining: 6:14
2021-12-06T15:46:01.330394 Pass: 373, ExpectedFail: 54, Skip: 1602, Duration: 1:09, Remaining: 6:11
2021-12-06T15:46:03.382413 Pass: 381, ExpectedFail: 57, Skip: 1641, Duration: 1:11, Remaining: 6:10
2021-12-06T15:46:05.394826 Pass: 389, ExpectedFail: 58, Skip: 1692, Duration: 1:13, Remaining: 6:07
2021-12-06T15:46:07.401204 Pass: 407, ExpectedFail: 63, Skip: 1736, Duration: 1:15, Remaining: 6:05
2021-12-06T15:46:09.516071 Pass: 415, ExpectedFail: 65, Skip: 1791, Duration: 1:17, Remaining: 6:02
2021-12-06T15:46:11.535459 Pass: 425, ExpectedFail: 65, Skip: 1841, Duration: 1:19, Remaining: 5:59
2021-12-06T15:46:13.550830 Pass: 433, ExpectedFail: 66, Skip: 1902, Duration: 1:21, Remaining: 5:54
2021-12-06T15:46:15.559659 Pass: 444, ExpectedFail: 67, Skip: 1952, Duration: 1:23, Remaining: 5:51
2021-12-06T15:46:17.598988 Pass: 456, ExpectedFail: 68, Skip: 1996, Duration: 1:25, Remaining: 5:49
2021-12-06T15:46:19.639047 Pass: 463, ExpectedFail: 69, Skip: 2059, Duration: 1:27, Remaining: 5:44
2021-12-06T15:46:21.829602 Pass: 472, ExpectedFail: 71, Skip: 2093, Duration: 1:30, Remaining: 5:46
2021-12-06T15:46:23.881311 Pass: 478, ExpectedFail: 73, Skip: 2117, Duration: 1:32, Remaining: 5:49
2021-12-06T15:46:25.913573 Pass: 484, ExpectedFail: 73, Skip: 2145, Duration: 1:34, Remaining: 5:52
2021-12-06T15:46:27.961211 Pass: 485, ExpectedFail: 73, Skip: 2174, Duration: 1:36, Remaining: 5:54
2021-12-06T15:46:30.006628 Pass: 497, ExpectedFail: 73, Skip: 2215, Duration: 1:38, Remaining: 5:53
2021-12-06T15:46:32.038915 Pass: 502, ExpectedFail: 75, Skip: 2271, Duration: 1:40, Remaining: 5:49
2021-12-06T15:46:34.065973 Pass: 505, ExpectedFail: 75, Skip: 2329, Duration: 1:42, Remaining: 5:46
2021-12-06T15:46:36.113658 Pass: 516, ExpectedFail: 76, Skip: 2376, Duration: 1:44, Remaining: 5:44
2021-12-06T15:46:38.146533 Pass: 526, ExpectedFail: 76, Skip: 2431, Duration: 1:46, Remaining: 5:40
2021-12-06T15:46:40.151364 Pass: 535, ExpectedFail: 77, Skip: 2477, Duration: 1:48, Remaining: 5:38
2021-12-06T15:46:42.262777 Pass: 545, ExpectedFail: 78, Skip: 2530, Duration: 1:50, Remaining: 5:35
2021-12-06T15:46:44.334858 Pass: 556, ExpectedFail: 81, Skip: 2581, Duration: 1:52, Remaining: 5:32
2021-12-06T15:46:46.343051 Pass: 564, ExpectedFail: 82, Skip: 2629, Duration: 1:54, Remaining: 5:30
2021-12-06T15:46:48.356298 Pass: 572, ExpectedFail: 83, Skip: 2682, Duration: 1:56, Remaining: 5:27
2021-12-06T15:46:50.361954 Pass: 585, ExpectedFail: 83, Skip: 2740, Duration: 1:58, Remaining: 5:23
2021-12-06T15:46:52.362952 Pass: 596, ExpectedFail: 85, Skip: 2789, Duration: 2:00, Remaining: 5:20
2021-12-06T15:46:54.372646 Pass: 603, ExpectedFail: 91, Skip: 2851, Duration: 2:02, Remaining: 5:17
2021-12-06T15:46:56.393335 Pass: 614, ExpectedFail: 93, Skip: 2900, Duration: 2:04, Remaining: 5:14
2021-12-06T15:46:58.491650 Pass: 624, ExpectedFail: 95, Skip: 2956, Duration: 2:06, Remaining: 5:11
2021-12-06T15:47:00.548193 Pass: 634, ExpectedFail: 97, Skip: 3020, Duration: 2:08, Remaining: 5:06
2021-12-06T15:47:02.604502 Pass: 643, ExpectedFail: 97, Skip: 3084, Duration: 2:10, Remaining: 5:02
2021-12-06T15:47:04.687160 Pass: 654, ExpectedFail: 98, Skip: 3145, Duration: 2:13, Remaining: 4:59
2021-12-06T15:47:06.689418 Pass: 664, ExpectedFail: 103, Skip: 3189, Duration: 2:15, Remaining: 4:56
2021-12-06T15:47:08.752344 Pass: 675, ExpectedFail: 103, Skip: 3239, Duration: 2:17, Remaining: 4:54
2021-12-06T15:47:10.831088 Pass: 747, ExpectedFail: 105, Skip: 3289, Duration: 2:19, Remaining: 4:52
2021-12-06T15:47:12.836623 Pass: 813, ExpectedFail: 108, Skip: 3340, Duration: 2:21, Remaining: 4:49
2021-12-06T15:47:14.862136 Pass: 826, ExpectedFail: 108, Skip: 3393, Duration: 2:23, Remaining: 4:46
2021-12-06T15:47:16.868107 Pass: 832, ExpectedFail: 109, Skip: 3448, Duration: 2:25, Remaining: 4:44
2021-12-06T15:47:18.952202 Pass: 848, ExpectedFail: 117, Skip: 3489, Duration: 2:27, Remaining: 4:42
2021-12-06T15:47:21.004590 Pass: 860, ExpectedFail: 118, Skip: 3537, Duration: 2:29, Remaining: 4:40
2021-12-06T15:47:23.033272 Pass: 871, ExpectedFail: 118, Skip: 3574, Duration: 2:31, Remaining: 4:39
2021-12-06T15:47:25.054382 Pass: 914, ExpectedFail: 121, Skip: 3624, Duration: 2:33, Remaining: 4:37
2021-12-06T15:47:27.181321 Pass: 923, ExpectedFail: 121, Skip: 3680, Duration: 2:35, Remaining: 4:34
2021-12-06T15:47:29.205692 Pass: 932, ExpectedFail: 122, Skip: 3740, Duration: 2:37, Remaining: 4:31
2021-12-06T15:47:31.212719 Pass: 943, ExpectedFail: 124, Skip: 3783, Duration: 2:39, Remaining: 4:29
2021-12-06T15:47:33.245985 Pass: 957, ExpectedFail: 125, Skip: 3834, Duration: 2:41, Remaining: 4:27
2021-12-06T15:47:35.443757 Pass: 963, ExpectedFail: 125, Skip: 3869, Duration: 2:43, Remaining: 4:27
2021-12-06T15:47:37.570429 Pass: 969, ExpectedFail: 125, Skip: 3908, Duration: 2:45, Remaining: 4:26
2021-12-06T15:47:39.613038 Pass: 987, ExpectedFail: 127, Skip: 3965, Duration: 2:47, Remaining: 4:23
2021-12-06T15:47:41.653660 Pass: 995, ExpectedFail: 127, Skip: 4030, Duration: 2:49, Remaining: 4:19
2021-12-06T15:47:43.677683 Pass: 1005, ExpectedFail: 129, Skip: 4086, Duration: 2:52, Remaining: 4:17
2021-12-06T15:47:45.727744 Pass: 1014, ExpectedFail: 130, Skip: 4138, Duration: 2:54, Remaining: 4:14
2021-12-06T15:47:47.732944 Pass: 1020, ExpectedFail: 132, Skip: 4200, Duration: 2:56, Remaining: 4:12
2021-12-06T15:47:49.768560 Pass: 1033, ExpectedFail: 132, Skip: 4254, Duration: 2:58, Remaining: 4:09
2021-12-06T15:47:51.884308 Pass: 1048, ExpectedFail: 133, Skip: 4309, Duration: 3:00, Remaining: 4:06
2021-12-06T15:47:53.964730 Pass: 1058, ExpectedFail: 135, Skip: 4367, Duration: 3:02, Remaining: 4:03
2021-12-06T15:47:56.072390 Pass: 1068, ExpectedFail: 135, Skip: 4417, Duration: 3:04, Remaining: 4:01
2021-12-06T15:47:58.112575 Pass: 1080, ExpectedFail: 139, Skip: 4466, Duration: 3:06, Remaining: 3:59
2021-12-06T15:48:00.166978 Pass: 1123, ExpectedFail: 141, Skip: 4511, Duration: 3:08, Remaining: 3:57
2021-12-06T15:48:02.172817 Pass: 1136, ExpectedFail: 141, Skip: 4541, Duration: 3:10, Remaining: 3:56
2021-12-06T15:48:04.205510 Pass: 1148, ExpectedFail: 141, Skip: 4597, Duration: 3:12, Remaining: 3:53
2021-12-06T15:48:06.214205 Pass: 1156, ExpectedFail: 143, Skip: 4649, Duration: 3:14, Remaining: 3:51
2021-12-06T15:48:08.261048 Pass: 1163, ExpectedFail: 144, Skip: 4709, Duration: 3:16, Remaining: 3:48
2021-12-06T15:48:10.264953 Pass: 1174, ExpectedFail: 144, Skip: 4748, Duration: 3:18, Remaining: 3:47
2021-12-06T15:48:12.302935 Pass: 1190, ExpectedFail: 144, Skip: 4800, Duration: 3:20, Remaining: 3:44
2021-12-06T15:48:14.353930 Pass: 1196, ExpectedFail: 148, Skip: 4847, Duration: 3:22, Remaining: 3:42
2021-12-06T15:48:16.514678 Pass: 1210, ExpectedFail: 148, Skip: 4891, Duration: 3:24, Remaining: 3:41
2021-12-06T15:48:18.576827 Pass: 1219, ExpectedFail: 149, Skip: 4936, Duration: 3:26, Remaining: 3:39
2021-12-06T15:48:20.668800 Pass: 1227, ExpectedFail: 152, Skip: 4985, Duration: 3:29, Remaining: 3:37
2021-12-06T15:48:22.691279 Pass: 1236, ExpectedFail: 152, Skip: 5036, Duration: 3:31, Remaining: 3:35
2021-12-06T15:48:24.697297 Pass: 1250, ExpectedFail: 153, Skip: 5083, Duration: 3:33, Remaining: 3:32
2021-12-06T15:48:26.740400 Pass: 1255, ExpectedFail: 153, Skip: 5159, Duration: 3:35, Remaining: 3:29
2021-12-06T15:48:28.787494 Pass: 1263, ExpectedFail: 153, Skip: 5208, Duration: 3:37, Remaining: 3:27
2021-12-06T15:48:30.799442 Pass: 1274, ExpectedFail: 153, Skip: 5249, Duration: 3:39, Remaining: 3:25
2021-12-06T15:48:32.807006 Pass: 1280, ExpectedFail: 154, Skip: 5308, Duration: 3:41, Remaining: 3:23
2021-12-06T15:48:34.875207 Pass: 1286, ExpectedFail: 156, Skip: 5386, Duration: 3:43, Remaining: 3:19
2021-12-06T15:48:36.885870 Pass: 1292, ExpectedFail: 156, Skip: 5450, Duration: 3:45, Remaining: 3:16
2021-12-06T15:48:38.978621 Pass: 1300, ExpectedFail: 157, Skip: 5512, Duration: 3:47, Remaining: 3:15
2021-12-06T15:48:41.009605 Pass: 1308, ExpectedFail: 158, Skip: 5561, Duration: 3:49, Remaining: 3:13
2021-12-06T15:48:43.119596 Pass: 1315, ExpectedFail: 160, Skip: 5614, Duration: 3:51, Remaining: 3:10
2021-12-06T15:48:45.130757 Pass: 1320, ExpectedFail: 160, Skip: 5666, Duration: 3:53, Remaining: 3:08
2021-12-06T15:48:47.164221 Pass: 1329, ExpectedFail: 161, Skip: 5711, Duration: 3:55, Remaining: 3:07
2021-12-06T15:48:49.212877 Pass: 1343, ExpectedFail: 161, Skip: 5752, Duration: 3:57, Remaining: 3:05
2021-12-06T15:48:51.354973 Pass: 1352, ExpectedFail: 161, Skip: 5805, Duration: 3:59, Remaining: 3:03
2021-12-06T15:48:53.358704 Pass: 1365, ExpectedFail: 165, Skip: 5856, Duration: 4:01, Remaining: 3:00
2021-12-06T15:48:55.363011 Pass: 1374, ExpectedFail: 167, Skip: 5913, Duration: 4:03, Remaining: 2:58
2021-12-06T15:48:57.375746 Pass: 1385, ExpectedFail: 169, Skip: 5954, Duration: 4:05, Remaining: 2:56
2021-12-06T15:48:59.426447 Pass: 1391, ExpectedFail: 171, Skip: 6013, Duration: 4:07, Remaining: 2:54
2021-12-06T15:49:01.428821 Pass: 1404, ExpectedFail: 172, Skip: 6057, Duration: 4:09, Remaining: 2:51
2021-12-06T15:49:03.456560 Pass: 1412, ExpectedFail: 173, Skip: 6107, Duration: 4:11, Remaining: 2:49
2021-12-06T15:49:05.557814 Pass: 1420, ExpectedFail: 174, Skip: 6156, Duration: 4:13, Remaining: 2:47
2021-12-06T15:49:07.623908 Pass: 1431, ExpectedFail: 174, Skip: 6214, Duration: 4:15, Remaining: 2:45
2021-12-06T15:49:09.671343 Pass: 1441, ExpectedFail: 176, Skip: 6264, Duration: 4:18, Remaining: 2:43
2021-12-06T15:49:11.695872 Pass: 1451, ExpectedFail: 176, Skip: 6313, Duration: 4:20, Remaining: 2:41
2021-12-06T15:49:13.743898 Pass: 1459, ExpectedFail: 179, Skip: 6354, Duration: 4:22, Remaining: 2:39
2021-12-06T15:49:15.770655 Pass: 1465, ExpectedFail: 180, Skip: 6390, Duration: 4:24, Remaining: 2:38
2021-12-06T15:49:17.808681 Pass: 1471, ExpectedFail: 181, Skip: 6434, Duration: 4:26, Remaining: 2:36
2021-12-06T15:49:19.838971 Pass: 1480, ExpectedFail: 181, Skip: 6492, Duration: 4:28, Remaining: 2:34
2021-12-06T15:49:21.857350 Pass: 1488, ExpectedFail: 181, Skip: 6537, Duration: 4:30, Remaining: 2:32
2021-12-06T15:49:23.869443 Pass: 1499, ExpectedFail: 182, Skip: 6582, Duration: 4:32, Remaining: 2:30
2021-12-06T15:49:25.955800 Pass: 1511, ExpectedFail: 183, Skip: 6633, Duration: 4:34, Remaining: 2:28
2021-12-06T15:49:27.986700 Pass: 1536, ExpectedFail: 183, Skip: 6686, Duration: 4:36, Remaining: 2:26
2021-12-06T15:49:30.064934 Pass: 1544, ExpectedFail: 184, Skip: 6735, Duration: 4:38, Remaining: 2:24
2021-12-06T15:49:32.088295 Pass: 1554, ExpectedFail: 185, Skip: 6789, Duration: 4:40, Remaining: 2:21
2021-12-06T15:49:34.128780 Pass: 1566, ExpectedFail: 187, Skip: 6830, Duration: 4:42, Remaining: 2:19
2021-12-06T15:49:36.242336 Pass: 1574, ExpectedFail: 188, Skip: 6893, Duration: 4:44, Remaining: 2:17
2021-12-06T15:49:38.260884 Pass: 1582, ExpectedFail: 188, Skip: 6947, Duration: 4:46, Remaining: 2:15
2021-12-06T15:49:40.301426 Pass: 1590, ExpectedFail: 189, Skip: 7007, Duration: 4:48, Remaining: 2:12
2021-12-06T15:49:42.302252 Pass: 1600, ExpectedFail: 190, Skip: 7053, Duration: 4:50, Remaining: 2:10
2021-12-06T15:49:44.388070 Pass: 1610, ExpectedFail: 192, Skip: 7104, Duration: 4:52, Remaining: 2:08
2021-12-06T15:49:46.390890 Pass: 1620, ExpectedFail: 194, Skip: 7164, Duration: 4:54, Remaining: 2:05
2021-12-06T15:49:48.457014 Pass: 1626, ExpectedFail: 195, Skip: 7226, Duration: 4:56, Remaining: 2:03
2021-12-06T15:49:50.565985 Pass: 1637, ExpectedFail: 198, Skip: 7282, Duration: 4:58, Remaining: 2:00
2021-12-06T15:49:52.632058 Pass: 1644, ExpectedFail: 200, Skip: 7333, Duration: 5:00, Remaining: 1:58
2021-12-06T15:49:54.688954 Pass: 1653, ExpectedFail: 200, Skip: 7384, Duration: 5:03, Remaining: 1:56
2021-12-06T15:49:56.705109 Pass: 1663, ExpectedFail: 200, Skip: 7443, Duration: 5:05, Remaining: 1:53
2021-12-06T15:49:58.750709 Pass: 1671, ExpectedFail: 201, Skip: 7485, Duration: 5:07, Remaining: 1:52
2021-12-06T15:50:00.790552 Pass: 1678, ExpectedFail: 203, Skip: 7537, Duration: 5:09, Remaining: 1:50
2021-12-06T15:50:02.809793 Pass: 1686, ExpectedFail: 205, Skip: 7588, Duration: 5:11, Remaining: 1:47
2021-12-06T15:50:04.813535 Pass: 1691, ExpectedFail: 206, Skip: 7645, Duration: 5:13, Remaining: 1:45
2021-12-06T15:50:06.871104 Pass: 1701, ExpectedFail: 207, Skip: 7701, Duration: 5:15, Remaining: 1:43
2021-12-06T15:50:08.888343 Pass: 1710, ExpectedFail: 208, Skip: 7756, Duration: 5:17, Remaining: 1:40
2021-12-06T15:50:10.920629 Pass: 1718, ExpectedFail: 208, Skip: 7814, Duration: 5:19, Remaining: 1:38
2021-12-06T15:50:12.922618 Pass: 1728, ExpectedFail: 209, Skip: 7880, Duration: 5:21, Remaining: 1:35
2021-12-06T15:50:14.925271 Pass: 1737, ExpectedFail: 210, Skip: 7938, Duration: 5:23, Remaining: 1:33
2021-12-06T15:50:16.931339 Pass: 1744, ExpectedFail: 211, Skip: 7999, Duration: 5:25, Remaining: 1:31
2021-12-06T15:50:18.970321 Pass: 1753, ExpectedFail: 211, Skip: 8052, Duration: 5:27, Remaining: 1:29
2021-12-06T15:50:20.971480 Pass: 1760, ExpectedFail: 212, Skip: 8117, Duration: 5:29, Remaining: 1:26
2021-12-06T15:50:22.976860 Pass: 1765, ExpectedFail: 212, Skip: 8189, Duration: 5:31, Remaining: 1:23
2021-12-06T15:50:25.071858 Pass: 1777, ExpectedFail: 213, Skip: 8246, Duration: 5:33, Remaining: 1:21
2021-12-06T15:50:27.161795 Pass: 1787, ExpectedFail: 214, Skip: 8293, Duration: 5:35, Remaining: 1:19
2021-12-06T15:50:29.197250 Pass: 1797, ExpectedFail: 214, Skip: 8344, Duration: 5:37, Remaining: 1:17
2021-12-06T15:50:31.258322 Pass: 1810, ExpectedFail: 215, Skip: 8380, Duration: 5:39, Remaining: 1:15
2021-12-06T15:50:33.318407 Pass: 1817, ExpectedFail: 216, Skip: 8448, Duration: 5:41, Remaining: 1:12
2021-12-06T15:50:35.346863 Pass: 1825, ExpectedFail: 218, Skip: 8509, Duration: 5:43, Remaining: 1:10
2021-12-06T15:50:37.357688 Pass: 1837, ExpectedFail: 218, Skip: 8558, Duration: 5:45, Remaining: 1:08
2021-12-06T15:50:39.373990 Pass: 1850, ExpectedFail: 219, Skip: 8609, Duration: 5:47, Remaining: 1:06
2021-12-06T15:50:41.485534 Pass: 1858, ExpectedFail: 219, Skip: 8671, Duration: 5:49, Remaining: 1:03
2021-12-06T15:50:43.575510 Pass: 1869, ExpectedFail: 221, Skip: 8725, Duration: 5:51, Remaining: 1:01
2021-12-06T15:50:45.576025 Pass: 1879, ExpectedFail: 224, Skip: 8777, Duration: 5:53, Remaining: 59
2021-12-06T15:50:47.637801 Pass: 1889, ExpectedFail: 224, Skip: 8823, Duration: 5:55, Remaining: 57
2021-12-06T15:50:49.658552 Pass: 1901, ExpectedFail: 225, Skip: 8866, Duration: 5:58, Remaining: 55
2021-12-06T15:50:51.783282 Pass: 1910, ExpectedFail: 225, Skip: 8917, Duration: 6:00, Remaining: 53
2021-12-06T15:50:53.794245 Pass: 1923, ExpectedFail: 226, Skip: 8954, Duration: 6:02, Remaining: 51
2021-12-06T15:50:55.817080 Pass: 1928, ExpectedFail: 226, Skip: 9012, Duration: 6:04, Remaining: 49
2021-12-06T15:50:57.819320 Pass: 1936, ExpectedFail: 227, Skip: 9062, Duration: 6:06, Remaining: 47
2021-12-06T15:50:59.902967 Pass: 1950, ExpectedFail: 227, Skip: 9103, Duration: 6:08, Remaining: 45
2021-12-06T15:51:01.034657 [0m[31mERROR - Duplicate subtest found, marking test failed: cube array texture
2021-12-06T15:51:01.970357 [0mPass: 1981, ExpectedFail: 230, Skip: 9135, Duration: 6:10, Remaining: 44
2021-12-06T15:51:03.985100 Pass: 1992, ExpectedFail: 230, Skip: 9191, Duration: 6:12, Remaining: 42
2021-12-06T15:51:06.069381 Pass: 2001, ExpectedFail: 230, Skip: 9243, Duration: 6:14, Remaining: 39
2021-12-06T15:51:08.125623 Pass: 2010, ExpectedFail: 232, Skip: 9294, Duration: 6:16, Remaining: 37
2021-12-06T15:51:10.162125 Pass: 2019, ExpectedFail: 233, Skip: 9342, Duration: 6:18, Remaining: 35
2021-12-06T15:51:12.175709 Pass: 2029, ExpectedFail: 233, Skip: 9389, Duration: 6:20, Remaining: 33
2021-12-06T15:51:14.191088 Pass: 2039, ExpectedFail: 234, Skip: 9431, Duration: 6:22, Remaining: 32
2021-12-06T15:51:16.283434 Pass: 2049, ExpectedFail: 235, Skip: 9480, Duration: 6:24, Remaining: 30
2021-12-06T15:51:18.290798 Pass: 2059, ExpectedFail: 237, Skip: 9535, Duration: 6:26, Remaining: 28
2021-12-06T15:51:20.405934 Pass: 2068, ExpectedFail: 239, Skip: 9589, Duration: 6:28, Remaining: 25
2021-12-06T15:51:22.474930 Pass: 2075, ExpectedFail: 240, Skip: 9632, Duration: 6:30, Remaining: 24
2021-12-06T15:51:24.526539 Pass: 2100, ExpectedFail: 241, Skip: 9686, Duration: 6:32, Remaining: 22
2021-12-06T15:51:26.667862 Pass: 2112, ExpectedFail: 242, Skip: 9740, Duration: 6:35, Remaining: 20
2021-12-06T15:51:28.693863 Pass: 2120, ExpectedFail: 243, Skip: 9794, Duration: 6:37, Remaining: 18
2021-12-06T15:51:30.767736 Pass: 2132, ExpectedFail: 243, Skip: 9835, Duration: 6:39, Remaining: 16
2021-12-06T15:51:32.777647 Pass: 2144, ExpectedFail: 244, Skip: 9873, Duration: 6:41, Remaining: 14
2021-12-06T15:51:34.869097 Pass: 2166, ExpectedFail: 246, Skip: 9912, Duration: 6:43, Remaining: 12
2021-12-06T15:51:36.911096 Pass: 2175, ExpectedFail: 246, Skip: 9962, Duration: 6:45, Remaining: 10
2021-12-06T15:51:38.930198 Pass: 2198, ExpectedFail: 246, Skip: 10009, Duration: 6:47, Remaining: 8
2021-12-06T15:51:40.947622 Pass: 2206, ExpectedFail: 247, Skip: 10059, Duration: 6:49, Remaining: 6
2021-12-06T15:51:42.957100 Pass: 2213, ExpectedFail: 247, Skip: 10112, Duration: 6:51, Remaining: 4
2021-12-06T15:51:45.014171 Pass: 2227, ExpectedFail: 249, Skip: 10156, Duration: 6:53, Remaining: 2
2021-12-06T15:51:47.014662 Pass: 2238, ExpectedFail: 250, Skip: 10206, Duration: 6:55, Remaining: 0
2021-12-06T15:51:47.560755 Pass: 2243, ExpectedFail: 250, Skip: 10217, Duration: 6:55, Remaining: 0
2021-12-06T15:51:47.801658
2021-12-06T15:51:47.802818 Slowest tests:
2021-12-06T15:51:47.808971 spec@arb_vertex_buffer_object@vbo-subdata-many drawelements (8.69s)
2021-12-06T15:51:47.815973 spec@glsl-1.10@execution@temp-array-indexing@glsl-vs-giant-temp-array (8.14s)
2021-12-06T15:51:47.821207 spec@ext_texture_env_combine@texture-env-combine (4.93s)
2021-12-06T15:51:47.825370 spec@arb_fragment_program@fp-long-alu (4.55s)
2021-12-06T15:51:47.831205 spec@glsl-1.10@execution@glsl-1.10-built-in-uniform-state (3.86s)
2021-12-06T15:51:47.838396 + PIGLIT_EXITCODE=0
2021-12-06T15:51:47.858656 + deqp-runner junit --testsuite gpu --results //results/failures.csv --output //results/junit.xml --limit 50 --template See https://enunes.pages.freedesktop.org/-/mesa/-/jobs/16452399/artifacts/results/{{testcase}}.xml
2021-12-06T15:51:48.071189 + [ -n ]
2021-12-06T15:51:48.071985 + exit 0
2021-12-06T15:51:48.073258 + RESULT=pass
2021-12-06T15:51:48.075442 + rm -rf results/trace/
2021-12-06T15:51:48.093273 + cat /proc/cmdline
2021-12-06T15:51:48.096079 + tr \n
2021-12-06T15:51:48.098254 + grep minio_results
2021-12-06T15:51:48.104907 + cut -d = -f 2
2021-12-06T15:51:48.120737 + MINIO=minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399
2021-12-06T15:51:48.127450 + [ -n minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399 ]
2021-12-06T15:51:48.130395 + tar -czf results.tar.gz results/
2021-12-06T15:51:48.260558 + ci-fairy minio login --token-file /minio_jwt
2021-12-06T15:51:57.744351 + ci-fairy minio cp results.tar.gz minio://minio-packet.freedesktop.org/artifacts/enunes/mesa/460128/16452399/results.tar.gz
2021-12-06T15:52:03.624548 {'case': 'mesa', 'definition': '0_mesa', 'result': 'pass'}
2021-12-06T15:52:03.625552 + echo hwci: mesa: pass
2021-12-06T15:52:03.625680 hwci: mesa: pass
2021-12-06T15:52:03.627044 {'case': 'mesa', 'definition': '0_mesa', 'result': 'pass'}
2021-12-06T15:52:03.637507 ok: lava_test_shell seems to have completed
2021-12-06T15:52:03.637852 Marking unfinished test run as failed
2021-12-06T15:52:03.638041 {'case': '0_mesa', 'definition': 'lava', 'duration': '452.07', 'result': 'fail', 'uuid': '4331_1.4.2.4.1'}
2021-12-06T15:52:03.638517 mesa: pass
2021-12-06T15:52:03.638702 end: 3.1 lava-test-shell (duration 00:07:33) [common]
2021-12-06T15:52:03.638844 end: 3 lava-test-retry (duration 00:07:33) [common]
2021-12-06T15:52:03.638988 start: 4 finalize (timeout 00:00:30) [common]
2021-12-06T15:52:03.639131 start: 4.1 power-off (timeout 00:00:30) [common]
2021-12-06T15:52:03.639252 nice /usr/local/bin/meson-gxl-s805x-libretech-ac-power off 10.0.70.56
2021-12-06T15:52:04.730334 output: {"POWER":"OFF"}
2021-12-06T15:52:04.730811 end: 4.1 power-off (duration 00:00:01) [common]
2021-12-06T15:52:04.730923 {'case': 'power-off', 'definition': 'lava', 'duration': '1.09', 'extra': OrderedDict([('status', 'success')]), 'level': '4.1', 'namespace': 'common', 'result': 'pass'}
2021-12-06T15:52:04.731339 start: 4.2 read-feedback (timeout 00:00:29) [common]
2021-12-06T15:52:04.731529 <LAVA_TEST_RUNNER EXIT>
2021-12-06T15:52:05.732651 Listened to connection for namespace 'common' for 1s
2021-12-06T15:52:05.732924 Finalising connection for namespace 'common'
2021-12-06T15:52:05.833353 end: 4.2 read-feedback (duration 00:00:01) [common]
2021-12-06T15:52:05.833584 end: 4 finalize (duration 00:00:02) [common]
2021-12-06T15:52:05.833747 Cleaning after the job
2021-12-06T15:52:05.833919 Cleaning up download directory: /srv/tftp/4331/tftp-deploy-91vfkeac/kernel
2021-12-06T15:52:05.838505 Cleaning up download directory: /srv/tftp/4331/tftp-deploy-91vfkeac/dtb
2021-12-06T15:52:05.838792 Cleaning up download directory: /srv/tftp/4331/tftp-deploy-91vfkeac/nfsrootfs
2021-12-06T15:52:05.898357 Override tmp directory removed at /srv/tftp/4331
2021-12-06T15:52:05.898795 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/4331
2021-12-06T15:52:06.763214 Job finished correctly
2021-12-06T15:52:06.763511 {'case': 'job', 'definition': 'lava', 'result': 'pass'}
id : 4331
description : mesa: lima-mali450-piglit-gpu:arm64 2/2: https://gitlab.freedesktop.org/enunes/mesa/-/pipelines/460128 on lima-ci-enable-piglit 2/2
device : meson-gxl-s805x-libretech-ac-2
device_type : meson-gxl-s805x-libretech-ac
health_check : False
pipeline : True
health : Complete
state : Finished
submitter : mesa-ci
submit_time : 20211206T15:37:03
start_time : 20211206T15:37:40
end_time : 20211206T15:52:06
tags : []
visibility : Group only
failure_comment : None
2021-12-06 15:52:14.474361: LAVA: result for test_suite '0_mesa', test_case 'mesa': pass
section_end:1638805934:step_script
[0Ksection_start:1638805934:after_script
[0K[0K[36;1mRunning after_script[0;m
[0;m[32;1mRunning after script...[0;m
[32;1m$ wget -q "https://${JOB_RESULTS_PATH}" -O- | tar -xz[0;m
section_end:1638805936:after_script
[0Ksection_start:1638805936:upload_artifacts_on_success
[0K[0K[36;1mUploading artifacts for successful job[0;m
[0;m[32;1mUploading artifacts...[0;m
results/: found 10 matching files and directories [0;m
Uploading artifacts as "archive" to coordinator... ok[0;m id[0;m=16452399 responseStatus[0;m=201 Created token[0;m=czayyuhd
section_end:1638805937:upload_artifacts_on_success
[0Ksection_start:1638805937:cleanup_file_variables
[0K[0K[36;1mCleaning up file based variables[0;m
[0;msection_end:1638805938:cleanup_file_variables
[0K[32;1mJob succeeded
[0;m