Running with gitlab-runner 17.6.0 (374d34fd)
  on mesa-lava-collabora-meson-g12b-a311d-khadas-vim3 kd2Z3fPJ, system ID: s_ff4301510d00
section_start:1733993364:prepare_executor
Preparing the "docker" executor
Using Docker executor with image registry.freedesktop.org/valentine/mesa/debian/x86_64_pyutils:20241002-pyutils--e195d80f35b45cc73668be3767b923fd76c70ed5 ...
Authenticating with credentials from job payload (GitLab Registry)
Pulling docker image registry.freedesktop.org/valentine/mesa/debian/x86_64_pyutils:20241002-pyutils--e195d80f35b45cc73668be3767b923fd76c70ed5 ...
Using docker image sha256:ebc7b3fe89be4d390775303adddb33539c235a2663165d78d6887b799bd64788 for registry.freedesktop.org/valentine/mesa/debian/x86_64_pyutils:20241002-pyutils--e195d80f35b45cc73668be3767b923fd76c70ed5 with digest registry.freedesktop.org/bbrezillon/mesa/debian/x86_64_pyutils@sha256:bd9ee9f72b4e805e3a91aa9b18e146a0cd222b0ac39dd92614b312415681e5b8 ...
section_end:1733993367:prepare_executor
section_start:1733993367:prepare_script
Preparing environment
Running on runner-kd2z3fpj-project-22365-concurrent-0 via freedom...
section_end:1733993367:prepare_script
section_start:1733993367:get_sources
Getting source from Git repository
Skipping Git repository setup
Skipping Git checkout
Skipping Git submodules setup
section_end:1733993368:get_sources
section_start:1733993368:step_script
Executing "step_script" stage of the job script
Using docker image sha256:ebc7b3fe89be4d390775303adddb33539c235a2663165d78d6887b799bd64788 for registry.freedesktop.org/valentine/mesa/debian/x86_64_pyutils:20241002-pyutils--e195d80f35b45cc73668be3767b923fd76c70ed5 with digest registry.freedesktop.org/bbrezillon/mesa/debian/x86_64_pyutils@sha256:bd9ee9f72b4e805e3a91aa9b18e146a0cd222b0ac39dd92614b312415681e5b8 ...
$ if [ -z "${KERNEL_IMAGE_BASE:-}" ]; then # collapsed multi-line command
$ export SCRIPTS_DIR=$(mktemp -d) && curl -L -s --retry 4 -f --retry-all-errors --retry-delay 60 -O --output-dir "${SCRIPTS_DIR}" "${CI_PROJECT_URL}/-/raw/${CI_COMMIT_SHA}/.gitlab-ci/setup-test-env.sh" && . ${SCRIPTS_DIR}/setup-test-env.sh && echo -n "${S3_JWT}" > "${S3_JWT_FILE}" && unset CI_JOB_JWT S3_JWT  # Unsetting vulnerable env variables
$ section_start artifacts_download "Downloading artifacts from s3"

section_start:1733993369:artifacts_download[collapsed=true]
[00:07] Downloading artifacts from s3

$ rm -rf install
$ (set -x; curl -L --retry 4 -f --retry-all-errors --retry-delay 60 ${FDO_HTTP_CACHE_URI:-}https://${PIPELINE_ARTIFACTS_BASE}/${S3_ARTIFACT_NAME}.tar.zst | tar --zstd -x)
++ curl -L --retry 4 -f --retry-all-errors --retry-delay 60 https://s3.freedesktop.org/artifacts/Valentine/mesa/1328385/mesa-python-test.tar.zst
++ tar --zstd -x
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100  472k  100  472k    0     0   587k      0 --:--:-- --:--:-- --:--:--  587k
$ section_end artifacts_download
section_end:1733993370:artifacts_download

$ . artifacts/setup-test-env.sh
$ ./artifacts/lava/lava-submit.sh

section_start:1733993370:prepare_rootfs[collapsed=true]
[00:08] Preparing root filesystem

section_end:1733993370:prepare_rootfs


section_start:1733993370:rootfs[collapsed=true]
[00:08] Assembling root filesystem

section_end:1733993371:rootfs


section_start:1733993371:variables[collapsed=true]
[00:09] Environment variables passed through to device:

export ARTIFACTS_BASE_URL='https://Valentine.pages.freedesktop.org/-/mesa/-/jobs/68088111/artifacts'
export CI_COMMIT_BRANCH='timeout-lava'
export CI_COMMIT_REF_NAME='timeout-lava'
export CI_COMMIT_TITLE='lava timeout'
export CI_JOB_ID='68088111'
export S3_JWT_FILE='/s3_jwt'
export CI_JOB_STARTED_AT='2024-12-12T08:49:22Z'
export CI_JOB_NAME='panfrost-g52-vk-full:arm64'
export CI_JOB_URL='https://gitlab.freedesktop.org/Valentine/mesa/-/jobs/68088111'
export CI_NODE_TOTAL='1'
export CI_PAGES_DOMAIN='pages.freedesktop.org'
export CI_PIPELINE_ID='1328385'
export CI_PIPELINE_URL='https://gitlab.freedesktop.org/Valentine/mesa/-/pipelines/1328385'
export CI_PROJECT_DIR='/builds/Valentine/mesa'
export CI_PROJECT_NAME='mesa'
export CI_PROJECT_PATH='Valentine/mesa'
export CI_PROJECT_ROOT_NAMESPACE='Valentine'
export CI_RUNNER_DESCRIPTION='mesa-lava-collabora-meson-g12b-a311d-khadas-vim3'
export CI_SERVER_URL='https://gitlab.freedesktop.org'
export DEQP_SUITE='panfrost-g52-vk-full'
export FDO_CI_CONCURRENT='4'
export FDO_UPSTREAM_REPO='mesa/mesa'
export FLAKES_CHANNEL='#panfrost-ci'
export GPU_VERSION='panfrost-g52'
export HWCI_FREQ_MAX='true'
export HWCI_START_WESTON='1'
export HWCI_TEST_SCRIPT='/install/deqp-runner.sh'
export JOB_ARTIFACTS_BASE='s3.freedesktop.org/artifacts/Valentine/mesa/1328385/68088111'
export JOB_RESULTS_PATH='s3.freedesktop.org/artifacts/Valentine/mesa/1328385/68088111/results.tar.zst'
export JOB_ROOTFS_OVERLAY_PATH='s3.freedesktop.org/artifacts/Valentine/mesa/1328385/68088111/job-rootfs-overlay.tar.gz'
export KERNEL_IMAGE_BASE='https://s3.freedesktop.org/mesa-rootfs/gfx-ci/linux/v6.6.21-mesa-f8ea'
export KERNEL_IMAGE_NAME='Image'
export MESA_IMAGE='registry.freedesktop.org/valentine/mesa/debian/x86_64_pyutils:20241002-pyutils--e195d80f35b45cc73668be3767b923fd76c70ed5'
export MESA_IMAGE_PATH='debian/x86_64_pyutils'
export MESA_IMAGE_TAG='20241002-pyutils'
export MESA_SPIRV_LOG_LEVEL='error'
export MESA_TEMPLATES_COMMIT='e195d80f35b45cc73668be3767b923fd76c70ed5'
export MESA_VK_ABORT_ON_DEVICE_LOSS='1'
export MESA_VK_IGNORE_CONFORMANCE_WARNING='1'
export S3_HOST='s3.freedesktop.org'
export S3_RESULTS_UPLOAD='s3.freedesktop.org/artifacts/Valentine/mesa/1328385/68088111'
export PAN_I_WANT_A_BROKEN_VULKAN_DRIVER='1'
export PAN_MESA_DEBUG='sync'
export PANVK_DEBUG='no_known_warn,sync'
export PIGLIT_NO_WINDOW='1'
export PIGLIT_REPLAY_REFERENCE_IMAGES_BASE='s3.freedesktop.org/mesa-tracie-results/mesa/mesa'
export PIPELINE_ARTIFACTS_BASE='s3.freedesktop.org/artifacts/Valentine/mesa/1328385'
export VK_DRIVER='panfrost'
section_end:1733993371:variables


section_start:1733993371:lava_submit[collapsed=true]
[00:09] Submitting job for scheduling

CI_JOB_TIMEOUT: 18000
LAVA_TEST_DUT_SUITE_TIMEOUT: 300
LAVA_TEST_CASE_TIMEOUT: 300
section_start:1733993372:yaml_dump[collapsed=true]
LAVA job definition (YAML)
job_name: 'mesa: panfrost-g52-vk-full:arm64: https://gitlab.freedesktop.org/Valentine/mesa/-/pipelines/1328385 on timeout-lava /1'
device_type: meson-g12b-a311d-khadas-vim3
visibility:
  group:
  - Collabora+fdo
priority: 50
context:
  extra_nfsroot_args: ' init=/init rootwait usbcore.quirks=0bda:8153:k'
timeouts:
  job:
    minutes: 295
  actions:
    depthcharge-retry:
      minutes: 4
    depthcharge-start:
      minutes: 1
    depthcharge-action:
      minutes: 15
actions:
- deploy:
    timeout:
      minutes: 10
    to: tftp
    os: oe
    kernel:
      url: https://s3.freedesktop.org/mesa-rootfs/gfx-ci/linux/v6.6.21-mesa-f8ea/arm64/Image
      type: image
    nfsrootfs:
      url: https://s3.freedesktop.org/mesa-rootfs/mesa/mesa/20241204-vkcts-main--v6.6.21-mesa-f8ea--20241125-crosvm--bca9635d--e195d80f35b45cc73668be3767b923fd76c70ed5/arm64/lava-rootfs.tar.zst
      compression: zstd
      format: tar
      overlays:
        mesa-build:
          compression: zstd
          format: tar
          path: /builds/Valentine/mesa
          url: https://s3.freedesktop.org/artifacts/Valentine/mesa/1328385/mesa-arm64-default-debugoptimized.tar.zst
        job-overlay:
          compression: gz
          format: tar
          path: /
          url: https://s3.freedesktop.org/artifacts/Valentine/mesa/1328385/68088111/job-rootfs-overlay.tar.gz
    dtb:
      url: https://s3.freedesktop.org/mesa-rootfs/gfx-ci/linux/v6.6.21-mesa-f8ea/arm64/meson-g12b-a311d-khadas-vim3.dtb
    namespace: dut
    failure_retry: 3
- boot:
    failure_retry: 3
    method: u-boot
    prompts:
    - 'lava-shell:'
    commands: nfs
    namespace: dut
    auto_login:
      login_commands:
      - dropbear -R -B
      - touch /dut_ready
      login_prompt: 'ogin:'
      username: ''
- test:
    namespace: dut
    definitions:
    - from: inline
      name: setup-ssh-server
      path: inline-setup-ssh-server
      repository:
        metadata:
          format: Lava-Test Test Definition 1.0
          name: dut-env-export
        run:
          steps:
          - |-
            set -ex
            cd /
            findmnt --mountpoint /proc || mount -t proc none /proc
            findmnt --mountpoint /sys || mount -t sysfs none /sys
            mount -t debugfs none /sys/kernel/debug
            findmnt --mountpoint /dev || mount -t devtmpfs none /dev
            mkdir -p /dev/pts
            mount -t devpts devpts /dev/pts
            mkdir /dev/shm
            mount -t tmpfs -o noexec,nodev,nosuid tmpfs /dev/shm
            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 _ in 1 2 3; do sntp -sS pool.ntp.org && break || sleep 2; done || true
            export CURRENT_SECTION=dut_boot
          - export -p > /dut-env-vars.sh
- test:
    namespace: container
    timeout:
      minutes: 295
    failure_retry: 3
    definitions:
    - name: docker_ssh_client
      from: inline
      path: inline/docker_ssh_client.yaml
      repository:
        metadata:
          name: mesa
          description: Mesa test plan
          format: Lava-Test Test Definition 1.0
        run:
          steps:
          - |-
            set -ex
            timeout 1m bash << EOF
            while [ -z "$(lava-target-ip)" ]; do
                echo Waiting for DUT to join LAN;
                sleep 1;
            done
            EOF

            ping -c 5 -w 60 $(lava-target-ip)

            lava_ssh_test_case() {
                set -x
                local test_case="${1}"
                shift
                lava-test-case "${test_case}" --shell \
                    ssh ${SSH_PTY_ARGS:--T} \
                    -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null \
                    root@$(lava-target-ip) "${@}"
            }
          - lava_ssh_test_case 'wait_for_dut_login' << EOF
          - while [ ! -e /dut_ready ]; do sleep 1; done;
          - EOF
          - |-
            lava_ssh_test_case 'artifact_download' 'bash --' << EOF
            source /dut-env-vars.sh
            set -e
            set -x  # HIDE_END
            echo "export S3_JWT_FILE=/s3_jwt" >> /set-job-env-vars.sh
            EOF
          - export SSH_PTY_ARGS=-tt
          - lava_ssh_test_case 'mesa_panfrost-g52-vk-full:arm64' '"cd / && /init-stage2.sh"'
    docker:
      image: registry.freedesktop.org/valentine/mesa/alpine/x86_64_lava_ssh_client:20241122-sections--e195d80f35b45cc73668be3767b923fd76c70ed5

section_end:1733993372:yaml_dump

section_end:1733993372:lava_submit

section_start:1733993372:dut_queue
[00:10] Waiting for hardware device to become available
08:49:32.525: Waiting for job 16905650 to start.
08:49:47.511: Job 16905650 started.
section_end:1733993387:dut_queue

section_start:1733993387:dut_boot[collapsed=true]
[00:25] Booting hardware device
08:49:57.627: lava-dispatcher, installed at version: 2024.09
08:49:57.627: start: 0 validate
08:49:57.627: Start time: 2024-12-12 08:50:30.790479+00:00 (UTC)
08:49:57.627: Using caching service: 'http://localhost/cache/?uri=%s'
08:49:57.627: Using caching service: 'http://localhost/cache/?uri=%s'
08:49:57.627: Using caching service: 'http://localhost/cache/?uri=%s'
08:49:57.627: Using caching service: 'http://localhost/cache/?uri=%s'
08:49:57.627: Using caching service: 'http://localhost/cache/?uri=%s'
08:50:12.842: validate duration: 24.28
08:50:22.982: start: 1 tftp-deploy (timeout 00:10:00) [dut]
08:50:22.982: downloading https://s3.freedesktop.org/mesa-rootfs/gfx-ci/linux/v6.6.21-mesa-f8ea/arm64/Image
08:50:22.982: 39 MB downloaded in 0.14 s (281.15 MB/s)
08:50:22.982: downloading https://s3.freedesktop.org/mesa-rootfs/gfx-ci/linux/v6.6.21-mesa-f8ea/arm64/meson-g12b-a311d-khadas-vim3.dtb
08:50:22.982: 0 MB downloaded in 0.00 s (42.50 MB/s)
08:50:22.982: downloading https://s3.freedesktop.org/mesa-rootfs/mesa/mesa/20241204-vkcts-main--v6.6.21-mesa-f8ea--20241125-crosvm--bca9635d--e195d80f35b45cc73668be3767b923fd76c70ed5/arm64/lava-rootfs.tar.zst
08:50:22.982: Using unzstd to decompress zstd
08:50:22.982: 597 MB downloaded in 2.24 s (266.51 MB/s)
08:50:22.982: downloading https://s3.freedesktop.org/artifacts/Valentine/mesa/1328385/mesa-arm64-default-debugoptimized.tar.zst
08:50:22.982: Using unzstd to decompress zstd
08:50:22.982: 58 MB downloaded in 0.21 s (282.20 MB/s)
08:50:22.982: downloading https://s3.freedesktop.org/artifacts/Valentine/mesa/1328385/68088111/job-rootfs-overlay.tar.gz
08:50:22.982: Using gunzip to decompress gz
08:50:22.982: 0 MB downloaded in 0.00 s (6.55 MB/s)
08:50:22.982: Modifying '/var/lib/lava/dispatcher/tmp/16905650/tftp-deploy-kfyrelo5/nfsrootfs/lava-rootfs.tar'
08:50:43.407: Loading test definitions
08:50:43.407: Creating lava-test-runner.conf files
08:50:43.407: [dut] Applying overlay to NFS
08:50:43.407: end: 1 tftp-deploy (duration 00:00:22) [dut]
08:50:43.407: start: 2 uboot-action (timeout 00:03:00) [dut]
08:50:43.407: Parsed boot commands:
08:50:43.407: - setenv autoload no
08:50:43.407: - setenv initrd_high 0xffffffff
08:50:43.407: - setenv fdt_high 0xffffffff
08:50:43.407: - dhcp
08:50:43.407: - setenv serverip 192.168.201.1
08:50:43.407: - tftp 0x1080000 16905650/tftp-deploy-kfyrelo5/kernel/Image
08:50:43.407: - setenv initrd_size ${filesize}
08:50:43.407: - tftp 0x1000000 16905650/tftp-deploy-kfyrelo5/dtb/meson-g12b-a311d-khadas-vim3.dtb
08:50:43.407: - setenv bootargs 'console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=192.168.201.1:/var/lib/lava/dispatcher/tmp/16905650/extract-nfsrootfs-p0olt4ic,tcp,hard,v3 init=/init rootwait usbcore.quirks=0bda:8153:k  ip=dhcp'
08:50:43.407: - booti 0x1080000 - 0x1000000
08:50:43.407: [dut] connect-device Connecting to device using '/usr/bin/console -k -f -M localhost meson-g12b-a311d-khadas-vim3-cbg-0'
08:50:48.504: [Enter `^Ec?' for help]
08:50:48.504: �G12B:BL:6e7c85:2a3b91;FEAT:E0F83180:402000;POC:B;RCY:0;SPINOR:0;0.

08:50:48.504: bl2_stage_init 0x0b
08:50:48.504: bl2_stage_init 0x01
08:50:48.504: bl2_stage_init 0x81
08:50:48.504: hw id: 0x0000 - pwm id 0x01
08:50:48.504: bl2_stage_init 0xc1
08:50:48.504: bl2_stage_init 0x02
08:50:48.504: L0:00000000
08:50:48.504: L1:20000703
08:50:48.504: L2:00008067
08:50:48.504: L3:14000000
08:50:48.504: B2:00402000
08:50:48.504: B1:e0f83180
08:50:48.504: TE: 59988
08:50:48.504: BL2 Built : 13:12:17, Sep 21 2023. g12b amlogic-dev g34b3d81e-dirty - bichao.zheng@rd02-sz
08:50:48.504: Board ID = 8
08:50:48.504: Set A53 clk to 24M
08:50:48.504: Set A73 clk to 24M
08:50:48.504: Set clk81 to 24M
08:50:48.504: A53 clk: 1200 MHz
08:50:48.504: A73 clk: 1200 MHz
08:50:48.504: CLK81: 166.6M
08:50:48.504: smccc: 0001385c
08:50:48.504: SPI NOR init
08:50:48.504: storage init finish
08:50:48.504: board id: 8
08:50:48.504: Load FIP HDR DDR from SPI, src: 0x00010000, des: 0xfffd0000, size: 0x00004000, part: 0
08:50:48.504: Get wrong ddr fw magic! Error!!
08:50:48.504: fw parse done
08:50:48.504: PIEI prepare done
08:50:48.504: fastboot data verify
08:50:48.504: result: 255
08:50:48.504: Cfg max: 12, cur: 1. Board id: 255. Force loop cfg
08:50:48.504: LPDDR4 probe
08:50:48.504: LPDDR4_PHY_V_0_1_22-Built : 14:57:26, Sep  1 2020. g12b g3c95fed - gongwei.chen@droid11-sz
08:50:48.504: ddr clk to 1608MHz
08:50:48.504: dmc_version 0001
08:50:48.504: Check phy result
08:50:48.504: INFO : End of CA training
08:50:48.504: INFO : End of initialization
08:50:48.504: INFO : Training has run successfully!
08:50:48.504: Check phy result
08:50:48.504: INFO : End of initialization
08:50:48.504: INFO : End of read enable training
08:50:48.504: INFO : End of fine write leveling
08:50:48.504: INFO : End of read dq deskew training
08:50:48.504: INFO : End of MPR read delay center optimization
08:50:48.504: INFO : End of Write leveling coarse delay
08:50:48.504: INFO : End of read delay center optimization
08:50:48.504: INFO : Training has run successfully!
08:50:48.504: Check phy result
08:50:48.504: INFO : End of initialization
08:50:48.505: INFO : End of MPR read delay center optimization
08:50:48.505: INFO : End of write delay center optimization
08:50:48.505: INFO : End of read delay center optimization
08:50:48.505: INFO : End of max read latency training
08:50:48.505: INFO : Training has run successfully!
08:50:48.505: 1D training succeed
08:50:48.505: Check phy result
08:50:48.505: INFO : End of initialization
08:50:48.505: INFO : End of 2D read delay Voltage center optimization
08:50:48.505: INFO : End of 2D read delay Voltage center optimization
08:50:48.505: INFO : End of 2D write delay Voltage center optimization
08:50:48.505: INFO : End of 2D write delay Voltage center optimization
08:50:48.505: INFO : Training has run successfully!
08:50:48.505: soc_vref_reg_value 0x 00000027 00000027 00000029 00000026 00000027 00000026 00000026 00000027 00000028 00000027 00000028 00000029 00000029 00000027 00000025 00000027 00000028 00000027 00000028 00000028 00000028 00000027 00000027 00000026 00000027 00000027 00000027 00000029 00000027 00000028 00000029 00000027 dwc_ddrphy_apb_wr((0<<20)|(2<<16)|(0<<12)|(0xb0):0004 
08:50:48.505:  dram_vref_reg_value 0x 00000013
08:50:48.505: 2D training succeed
08:50:48.505: auto size-- 65535DDR cs0 size: 2048MB
08:50:48.505: DDR cs1 size: 2048MB
08:50:48.505: DMC_DDR_CTRL: 00e00024DDR size: 3928MB
08:50:48.505: cs0 DataBus test pass
08:50:48.505: cs1 DataBus test pass
08:50:48.505: cs0 AddrBus test pass
08:50:48.505: cs1 AddrBus test pass
08:50:48.505: non-sec scramble use zero key
08:50:48.505: ddr scramble enabled
08:50:48.505: 100bdlr_step_size ps== 414
08:50:48.505: result report
08:50:48.505: boot times 0Enable ddr reg access
08:50:48.505: Load FIP HDR from SPI, src: 0x00010000, des: 0x01700000, size: 0x00004000, part: 0
08:50:48.505: Load BL3X from SPI, src: 0x00078000, des: 0x01768000, size: 0x00120e00, part: 0
08:50:48.505: 0.0;M3 CHK:0;cm4_sp_mode 0
MVN_1=0x00000000
MVN_2=0x00000000
[Image: g12b_v1.1.3390-6ac5299 2019-09-26 14:10:05 luan.yuan@droid15-sz]
OPS=0x10
ring efuse init
chipver efuse init
29 0b 10 00 01 0f 06 00 00 06 32 38 59 47 4e 50 
[0.018960 Inits done]
secure task start!
08:50:48.505: high task start!
08:50:48.505: low task start!
08:50:48.505: run into bl31
08:50:48.505: NOTICE:  BL31: v1.3(release):4fc40b1
08:50:48.505: NOTICE:  BL31: Built : 15:58:17, May 22 2019
08:50:48.505: NOTICE:  BL31: G12A normal boot!
08:50:48.505: ERROR:   Error initializing runtime service opteed_fast
08:50:48.505: <debug_uart>
08:50:48.505: U-Boot 2024.04-rc2-g7bb761c4 (Feb 23 2024 - 08:05:24 +0000) khadas-vim3
08:50:48.505: Model: Khadas VIM3
08:50:48.505: SoC:   Amlogic Meson G12B (A311D) Revision 29:b (10:2)
08:50:53.588: DRAM:  2 GiB (effective 3.8 GiB)
08:50:53.588: Core:  410 devices, 36 uclasses, devicetree: separate
08:50:53.588: MMC:   sd@ffe03000: 0, sd@ffe05000: 1, mmc@ffe07000: 2
08:50:53.588: Loading Environment from nowhere... OK
08:50:53.588: In:    usbkbd,serial
08:50:53.588: Out:   vidconsole,serial
08:50:53.588: Err:   vidconsole,serial
08:50:53.588: Net:   eth0: ethernet@ff3f0000
08:50:53.588: $ 

08:50:53.588: Hit any key to stop autoboot:  2  0 
08:50:53.588: $ setenv autoload no

08:50:53.588: => setenv autoload no
08:50:53.588: $ setenv initrd_high 0xffffffff

08:50:53.588: => setenv initrd_high 0xffffffff
08:50:53.588: $ setenv fdt_high 0xffffffff

08:50:58.675: => setenv fdt_high 0xffffffff
08:50:58.675: $ dhcp

08:50:58.675: => dhcp
08:50:58.675: Speed: 1000, full duplex
08:50:58.675: BOOTP broadcast 1
08:50:58.675: *** WARNING: Host Name is too long (34 - max: 32) - truncated
08:50:58.675: *** WARNING: Host Name is too long (34 - max: 32) - truncated
08:50:58.675: DHCP client bound to address 192.168.201.16 (10 ms)
08:50:58.675: $ setenv serverip 192.168.201.1

08:50:58.675: => setenv serverip 192.168.201.1
08:50:58.675: $ tftp 0x1080000 16905650/tftp-deploy-kfyrelo5/kernel/Image

08:51:03.756: => tftp 0x1080000 16905650/tftp-deploy-kfyrelo5/kernel/Image
08:51:03.756: Speed: 1000, full duplex
08:51:03.756: Using ethernet@ff3f0000 device
08:51:03.756: TFTP from server 192.168.201.1; our IP address is 192.168.201.16
08:51:03.756: Filename '16905650/tftp-deploy-kfyrelo5/kernel/Image'.
08:51:03.756: Load address: 0x1080000
08:51:03.756: Loading: *#################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:03.756: 	 #################################################################
08:51:13.883: 	 ###T ##############################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.883: 	 #################################################################
08:51:13.884: 	 #################################################################
08:51:13.884: 	 ##############################
08:51:13.884: 	 5.1 MiB/s
08:51:13.884: done
08:51:13.884: Bytes transferred = 41462272 (278aa00 hex)
08:51:13.884: $ setenv initrd_size ${filesize}

08:51:13.884: => setenv initrd_size ${filesize}
08:51:13.884: $ tftp 0x1000000 16905650/tftp-deploy-kfyrelo5/dtb/meson-g12b-a311d-khadas-vim3.dtb

08:51:18.955: => tftp 0x1000000 16905650/tftp-deploy-kfyrelo5/dtb/meson-g12b-a311d-khadas-vim3.dtb
08:51:18.955: Speed: 1000, full duplex
08:51:18.955: Using ethernet@ff3f0000 device
08:51:18.955: TFTP from server 192.168.201.1; our IP address is 192.168.201.16
08:51:18.955: Filename '16905650/tftp-deploy-kfyrelo5/dtb/meson-g12b-a311d-khadas-vim3.dtb'.
08:51:18.955: Load address: 0x1000000
08:51:18.955: Loading: *####
08:51:18.955: 	 4.6 MiB/s
08:51:18.955: done
08:51:18.955: Bytes transferred = 52735 (cdff hex)
08:51:24.017: $ setenv bootargs 'console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=192.168.201.1:/var/lib/lava/dispatcher/tmp/16905650/extract-nfsrootfs-p0olt4ic,tcp,hard,v3 init=/init rootwait usbcore.quirks=0bda:8153:k  ip=dhcp'

08:51:34.174: [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
08:51:34.174: [    0.000000] Linux version 6.6.21-gf8ea60f0f8b9 (root@runner-efluxtks-project-9705-concurrent-1) (aarch64-linux-gnu-gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT Wed May  8 03:40:15 UTC 2024
08:51:34.174: [    0.000000] KASLR disabled due to lack of seed
08:51:34.174: [    0.000000] Machine model: Khadas VIM3
08:51:34.174: [    0.000000] efi: UEFI not found.
08:51:34.174: [    0.000000] Reserved memory: created CMA memory pool at 0x00000000e4c00000, size 256 MiB
08:51:34.174: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
08:51:34.174: [    0.000000] OF: reserved mem: 0x00000000e4c00000..0x00000000f4bfffff (262144 KiB) map reusable linux,cma
08:51:34.174: [    0.000000] OF: reserved mem: 0x0000000005000000..0x00000000052fffff (3072 KiB) nomap non-reusable secmon@5000000
08:51:34.174: [    0.000000] OF: reserved mem: 0x0000000005300000..0x00000000072fffff (32768 KiB) nomap non-reusable secmon@5300000
08:51:34.174: [    0.000000] NUMA: No NUMA configuration found
08:51:34.175: [    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000000f4e5afff]
08:51:34.175: [    0.000000] NUMA: NODE_DATA [mem 0xe46648c0-0xe4666fff]
08:51:34.175: [    0.000000] Zone ranges:
08:51:34.175: [    0.000000]   DMA      [mem 0x0000000000000000-0x00000000f4e5afff]
08:51:34.175: [    0.000000]   DMA32    empty
08:51:34.175: [    0.000000]   Normal   empty
08:51:34.175: [    0.000000] Movable zone start for each node
08:51:34.175: [    0.000000] Early memory node ranges
08:51:34.175: [    0.000000]   node   0: [mem 0x0000000000000000-0x0000000004ffffff]
08:51:34.175: [    0.000000]   node   0: [mem 0x0000000005000000-0x00000000072fffff]
08:51:34.175: [    0.000000]   node   0: [mem 0x0000000007300000-0x00000000f4e5afff]
08:51:34.175: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000f4e5afff]
08:51:34.175: [    0.000000] On node 0, zone DMA: 12709 pages in unavailable ranges
08:51:34.175: [    0.000000] psci: probing for conduit method from DT.
08:51:34.175: [    0.000000] psci: PSCIv1.0 detected in firmware.
08:51:34.175: [    0.000000] psci: Using standard PSCI v0.2 function IDs
08:51:34.175: [    0.000000] psci: MIGRATE_INFO_TYPE not supported.
08:51:34.175: [    0.000000] psci: SMC Calling Convention v1.1
08:51:34.175: [    0.000000] percpu: Embedded 22 pages/cpu s50408 r8192 d31512 u90112
08:51:34.175: [    0.000000] Detected VIPT I-cache on CPU0
08:51:34.175: [    0.000000] CPU features: detected: ARM erratum 845719
08:51:34.175: [    0.000000] alternatives: applying boot alternatives
08:51:34.175: [    0.000000] Kernel command line: console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=192.168.201.1:/var/lib/lava/dispatcher/tmp/16905650/extract-nfsrootfs-p0olt4ic,tcp,hard,v3 init=/init rootwait usbcore.quirks=0bda:8153:k  ip=dhcp
08:51:34.175: [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
08:51:34.175: [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
08:51:34.175: [    0.000000] Fallback order for Node 0: 0 
08:51:34.175: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 987425
08:51:34.175: [    0.000000] Policy zone: DMA
08:51:34.175: [    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
08:51:34.175: [    0.000000] software IO TLB: area num 8.
08:51:34.175: [    0.000000] software IO TLB: mapped [mem 0x00000000dc17c000-0x00000000e017c000] (64MB)
08:51:34.175: [    0.000000] Memory: 3528652K/4012396K available (17600K kernel code, 4202K rwdata, 10396K rodata, 8128K init, 575K bss, 221600K reserved, 262144K cma-reserved)
08:51:34.175: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1
08:51:34.175: [    0.000000] rcu: Preemptible hierarchical RCU implementation.
08:51:34.175: [    0.000000] rcu: 	RCU event tracing is enabled.
08:51:34.175: [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=6.
08:51:34.175: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
08:51:34.175: [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6
08:51:34.175: [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
08:51:34.175: [    0.000000] Root IRQ handler: gic_handle_irq
08:51:34.175: [    0.000000] GIC: Using split EOI/Deactivate mode
08:51:34.175: [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
08:51:34.175: [    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
08:51:34.175: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
08:51:34.175: [    0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
08:51:34.175: [    0.000450] Console: colour dummy device 80x25
08:51:34.175: [    0.000521] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
08:51:34.175: [    0.000532] pid_max: default: 32768 minimum: 301
08:51:34.175: [    0.000606] LSM: initializing lsm=capability,integrity
08:51:34.175: [    0.000709] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
08:51:34.175: [    0.000729] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
08:51:34.175: [    0.002767] RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1.
08:51:34.175: [    0.002839] RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1.
08:51:34.175: [    0.003028] rcu: Hierarchical SRCU implementation.
08:51:34.175: [    0.003032] rcu: 	Max phase no-delay instances is 1000.
08:51:34.175: [    0.005493] EFI services will not be available.
08:51:34.175: [    0.005941] smp: Bringing up secondary CPUs ...
08:51:34.175: [    0.006631] Detected VIPT I-cache on CPU1
08:51:34.175: [    0.006741] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
08:51:34.175: [    0.008022] CPU features: detected: Spectre-v2
08:51:34.175: [    0.008034] CPU features: detected: Spectre-v4
08:51:34.175: [    0.008038] CPU features: detected: Spectre-BHB
08:51:34.175: [    0.008043] CPU features: detected: ARM erratum 858921
08:51:34.175: [    0.008050] Detected VIPT I-cache on CPU2
08:51:34.175: [    0.008118] arch_timer: Enabling local workaround for ARM erratum 858921
08:51:34.175: [    0.008134] arch_timer: CPU2: Trapping CNTVCT access
08:51:34.175: [    0.008145] CPU2: Booted secondary processor 0x0000000100 [0x410fd092]
08:51:34.175: [    0.008844] Detected VIPT I-cache on CPU3
08:51:34.175: [    0.008885] arch_timer: Enabling local workaround for ARM erratum 858921
08:51:34.175: [    0.008894] arch_timer: CPU3: Trapping CNTVCT access
08:51:34.175: [    0.008901] CPU3: Booted secondary processor 0x0000000101 [0x410fd092]
08:51:34.175: [    0.009551] Detected VIPT I-cache on CPU4
08:51:34.175: [    0.009593] arch_timer: Enabling local workaround for ARM erratum 858921
08:51:34.175: [    0.009602] arch_timer: CPU4: Trapping CNTVCT access
08:51:34.175: [    0.009608] CPU4: Booted secondary processor 0x0000000102 [0x410fd092]
08:51:34.175: [    0.010278] Detected VIPT I-cache on CPU5
08:51:34.175: [    0.010321] arch_timer: Enabling local workaround for ARM erratum 858921
08:51:34.175: [    0.010330] arch_timer: CPU5: Trapping CNTVCT access
08:51:34.175: [    0.010337] CPU5: Booted secondary processor 0x0000000103 [0x410fd092]
08:51:34.175: [    0.010417] smp: Brought up 1 node, 6 CPUs
08:51:34.175: [    0.010429] SMP: Total of 6 processors activated.
08:51:34.175: [    0.010434] CPU features: detected: 32-bit EL0 Support
08:51:34.175: [    0.010437] CPU features: detected: 32-bit EL1 Support
08:51:34.175: [    0.010442] CPU features: detected: CRC32 instructions
08:51:34.175: [    0.010528] CPU: All CPU(s) started at EL2
08:51:34.175: [    0.010550] alternatives: applying system-wide alternatives
08:51:34.175: [    0.013199] devtmpfs: initialized
08:51:34.175: [    0.019802] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
08:51:34.175: [    0.019819] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
08:51:34.175: [    0.023761] pinctrl core: initialized pinctrl subsystem
08:51:34.175: [    0.025621] DMI not present or invalid.
08:51:34.175: [    0.026190] NET: Registered PF_NETLINK/PF_ROUTE protocol family
08:51:34.175: [    0.027141] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
08:51:34.175: [    0.027482] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
08:51:34.175: [    0.027764] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
08:51:34.175: [    0.027821] audit: initializing netlink subsys (disabled)
08:51:34.175: [    0.027948] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1
08:51:34.175: [    0.029016] thermal_sys: Registered thermal governor 'step_wise'
08:51:34.175: [    0.029020] thermal_sys: Registered thermal governor 'power_allocator'
08:51:34.175: [    0.029061] cpuidle: using governor menu
08:51:34.175: [    0.029135] NET: Registered PF_QIPCRTR protocol family
08:51:34.175: [    0.029267] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
08:51:34.175: [    0.029368] ASID allocator initialised with 65536 entries
08:51:34.175: [    0.030410] Serial: AMBA PL011 UART driver
08:51:34.175: [    0.037111] platform ff600000.hdmi-tx: Fixed dependency cycle(s) with /soc/vpu@ff900000
08:51:34.175: [    0.045919] platform ff600000.hdmi-tx: Fixed dependency cycle(s) with /soc/vpu@ff900000
08:51:34.175: [    0.045964] platform ff900000.vpu: Fixed dependency cycle(s) with /soc/bus@ff600000/hdmi-tx@0
08:51:34.175: [    0.051736] platform ff600000.hdmi-tx: Fixed dependency cycle(s) with /hdmi-connector
08:51:34.175: [    0.051783] platform hdmi-connector: Fixed dependency cycle(s) with /soc/bus@ff600000/hdmi-tx@0
08:51:34.175: [    0.053173] Modules: 22496 pages in range for non-PLT usage
08:51:34.175: [    0.053177] Modules: 514016 pages in range for PLT usage
08:51:34.175: [    0.053787] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
08:51:34.175: [    0.053792] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
08:51:34.175: [    0.053796] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
08:51:34.175: [    0.053799] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
08:51:34.175: [    0.053803] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
08:51:34.175: [    0.053806] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
08:51:34.175: [    0.053809] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
08:51:34.175: [    0.053812] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
08:51:34.176: [    0.055067] ACPI: Interpreter disabled.
08:51:34.176: [    0.057442] iommu: Default domain type: Translated
08:51:34.176: [    0.057446] iommu: DMA domain TLB invalidation policy: strict mode
08:51:34.176: [    0.057637] SCSI subsystem initialized
08:51:34.176: [    0.057862] usbcore: registered new interface driver usbfs
08:51:34.176: [    0.057881] usbcore: registered new interface driver hub
08:51:34.176: [    0.057904] usbcore: registered new device driver usb
08:51:34.176: [    0.058367] pps_core: LinuxPPS API ver. 1 registered
08:51:34.176: [    0.058371] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
08:51:34.176: [    0.058379] PTP clock support registered
08:51:34.176: [    0.058400] EDAC MC: Ver: 3.0.0
08:51:34.176: [    0.059026] scmi_core: SCMI protocol bus registered
08:51:34.176: [    0.059495] FPGA manager framework
08:51:34.176: [    0.059553] Advanced Linux Sound Architecture Driver Initialized.
08:51:34.176: [    0.060222] vgaarb: loaded
08:51:34.176: [    0.060537] clocksource: Switched to clocksource arch_sys_counter
08:51:34.176: [    0.060684] VFS: Disk quotas dquot_6.6.0
08:51:34.176: [    0.060706] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
08:51:34.176: [    0.060820] pnp: PnP ACPI: disabled
08:51:34.176: [    0.064983] NET: Registered PF_INET protocol family
08:51:34.176: [    0.065168] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
08:51:34.176: [    0.067904] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
08:51:34.176: [    0.067962] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
08:51:34.176: [    0.067972] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
08:51:34.176: [    0.068118] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
08:51:34.176: [    0.068474] TCP: Hash tables configured (established 32768 bind 32768)
08:51:34.176: [    0.068594] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
08:51:34.176: [    0.068637] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
08:51:34.176: [    0.068782] NET: Registered PF_UNIX/PF_LOCAL protocol family
08:51:34.176: [    0.069090] RPC: Registered named UNIX socket transport module.
08:51:34.176: [    0.069094] RPC: Registered udp transport module.
08:51:34.176: [    0.069097] RPC: Registered tcp transport module.
08:51:34.176: [    0.069099] RPC: Registered tcp-with-tls transport module.
08:51:34.176: [    0.069102] RPC: Registered tcp NFSv4.1 backchannel transport module.
08:51:34.176: [    0.069111] PCI: CLS 0 bytes, default 64
08:51:34.176: [    0.069492] kvm [1]: IPA Size Limit: 40 bits
08:51:34.176: [    0.070687] kvm [1]: vgic interrupt IRQ9
08:51:34.176: [    0.070713] kvm [1]: Hyp mode initialized successfully
08:51:34.176: [    0.071750] workingset: timestamp_bits=42 max_order=20 bucket_order=0
08:51:34.176: [    0.071990] squashfs: version 4.0 (2009/01/31) Phillip Lougher
08:51:34.176: [    0.072165] NFS: Registering the id_resolver key type
08:51:34.176: [    0.072207] Key type id_resolver registered
08:51:34.176: [    0.072210] Key type id_legacy registered
08:51:34.176: [    0.072225] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
08:51:34.176: [    0.072229] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
08:51:34.176: [    0.072314] 9p: Installing v9fs 9p2000 file system support
08:51:34.176: [    0.099946] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
08:51:34.176: [    0.099952] io scheduler mq-deadline registered
08:51:34.176: [    0.099956] io scheduler kyber registered
08:51:34.176: [    0.099980] io scheduler bfq registered
08:51:34.176: [    0.100596] irq_meson_gpio: 100 to 8 gpio interrupt mux initialized
08:51:34.176: [    0.112480] EINJ: ACPI disabled.
08:51:34.176: [    0.132055] soc soc0: Amlogic Meson G12B (A311D) Revision 29:b (10:2) Detected
08:51:34.176: [    0.140176] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
08:51:34.176: [    0.142780] ff803000.serial: ttyAML0 at MMIO 0xff803000 (irq = 14, base_baud = 1500000) is a meson_uart
08:51:34.176: [    0.142800] printk: console [ttyAML0] enabled
08:51:34.176: [    1.255982] ffd24000.serial: ttyAML6 at MMIO 0xffd24000 (irq = 15, base_baud = 1500000) is a meson_uart
08:51:34.176: [    1.264936] serial serial0: tty port ttyAML6 registered
08:51:34.176: [    1.270229] msm_serial: driver initialized
08:51:34.176: [    1.274976] STM32 USART driver initialized
08:51:34.176: [    1.278923] random: crng init done
08:51:34.176: [    1.287928] panfrost ffe40000.gpu: clock rate = 24000000
08:51:34.176: [    1.287994] panfrost ffe40000.gpu: error -ENODEV: _opp_set_regulators: no regulator (mali) found
08:51:34.176: [    1.297276] panfrost ffe40000.gpu: mali-g52 id 0x7212 major 0x0 minor 0x0 status 0x0
08:51:34.176: [    1.304074] panfrost ffe40000.gpu: features: 00000000,00000cf7, issues: 00000000,00000400
08:51:34.176: [    1.312216] panfrost ffe40000.gpu: Features: L2:0x07110206 Shader:0x00000000 Tiler:0x00000809 Mem:0x1 MMU:0x00002830 AS:0xff JS:0x7
08:51:34.176: [    1.324001] panfrost ffe40000.gpu: shader_present=0x3 l2_present=0x1
08:51:34.176: [    1.334663] panfrost ffe40000.gpu: shader power transition timeout
08:51:34.176: [    1.337244] [drm] Initialized panfrost 1.2.0 20180908 for ffe40000.gpu on minor 0
08:51:34.176: [    1.347745] loop: module loaded
08:51:34.176: [    1.348051] zram: Added device: zram0
08:51:34.176: [    1.351499] megasas: 07.725.01.00-rc1
08:51:34.176: [    1.359275] tun: Universal TUN/TAP device driver, 1.6
08:51:34.176: [    1.360011] thunder_xcv, ver 1.0
08:51:34.176: [    1.362596] thunder_bgx, ver 1.0
08:51:34.176: [    1.365793] nicpf, ver 1.0
08:51:34.176: [    1.369246] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version
08:51:34.176: [    1.375659] hns3: Copyright (c) 2017 Huawei Corporation.
08:51:34.176: [    1.380971] hclge is initializing
08:51:34.176: [    1.384257] e1000: Intel(R) PRO/1000 Network Driver
08:51:34.176: [    1.389098] e1000: Copyright (c) 1999-2006 Intel Corporation.
08:51:34.176: [    1.394826] e1000e: Intel(R) PRO/1000 Network Driver
08:51:34.176: [    1.399751] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
08:51:34.176: [    1.405659] igb: Intel(R) Gigabit Ethernet Network Driver
08:51:34.176: [    1.411014] igb: Copyright (c) 2007-2014 Intel Corporation.
08:51:34.176: [    1.416578] igbvf: Intel(R) Gigabit Virtual Function Network Driver
08:51:34.176: [    1.422804] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
08:51:34.176: [    1.428712] sky2: driver version 1.30
08:51:34.176: [    1.434093] usbcore: registered new device driver r8152-cfgselector
08:51:34.176: [    1.438595] usbcore: registered new interface driver r8152
08:51:34.176: [    1.444049] usbcore: registered new interface driver lan78xx
08:51:34.176: [    1.449684] usbcore: registered new interface driver asix
08:51:34.176: [    1.455058] usbcore: registered new interface driver ax88179_178a
08:51:34.176: [    1.461126] usbcore: registered new interface driver cdc_ether
08:51:34.176: [    1.466932] usbcore: registered new interface driver smsc95xx
08:51:34.176: [    1.472658] usbcore: registered new interface driver net1080
08:51:34.176: [    1.478289] usbcore: registered new interface driver cdc_subset
08:51:34.176: [    1.484177] usbcore: registered new interface driver zaurus
08:51:34.176: [    1.489726] usbcore: registered new interface driver cdc_ncm
08:51:34.176: [    1.495358] usbcore: registered new interface driver r8153_ecm
08:51:34.176: [    1.501389] VFIO - User Level meta-driver version: 0.3
08:51:34.176: [    1.508592] usbcore: registered new interface driver usb-storage
08:51:34.176: [    1.512384] usbcore: registered new device driver onboard-usb-hub
08:51:34.176: [    1.520756] i2c_dev: i2c /dev entries driver
08:51:34.176: [    1.530288] sdhci: Secure Digital Host Controller Interface driver
08:51:34.176: [    1.530828] sdhci: Copyright(c) Pierre Ossman
08:51:34.176: [    1.535565] Synopsys Designware Multimedia Card Interface Driver
08:51:34.176: [    1.542301] sdhci-pltfm: SDHCI platform and OF driver helper
08:51:34.176: [    1.548787] ledtrig-cpu: registered to indicate activity on CPUs
08:51:34.176: [    1.553750] meson-sm: secure-monitor enabled
08:51:34.176: [    1.557725] usbcore: registered new interface driver usbhid
08:51:34.176: [    1.562555] usbhid: USB HID core driver
08:51:34.176: [    1.571718] gnss: GNSS driver registered with major 510
08:51:34.176: [    1.572549] watchdog: Delayed init of the lockup detector failed: -19
08:51:34.176: [    1.577996] watchdog: Hard watchdog permanently disabled
08:51:34.176: [    1.578842] NET: Registered PF_PACKET protocol family
08:51:34.176: [    1.588352] 9pnet: Installing 9P2000 support
08:51:34.176: [    1.592577] Key type dns_resolver registered
08:51:34.176: [    1.603592] registered taskstats version 1
08:51:34.176: [    1.640819] meson-drm ff900000.vpu: Queued 1 outputs on vpu
08:51:34.176: [    1.642841] meson-drm ff900000.vpu: CVBS Output connector not available
08:51:34.176: [    1.672587] meson-dw-hdmi ff600000.hdmi-tx: Detected HDMI TX controller v2.01a with HDCP (meson_dw_hdmi_phy)
08:51:34.176: [    1.677201] meson-dw-hdmi ff600000.hdmi-tx: registered DesignWare HDMI I2C bus driver
08:51:34.176: [    1.685799] meson-drm ff900000.vpu: bound ff600000.hdmi-tx (ops meson_dw_hdmi_ops)
08:51:34.176: [    1.692214] meson-drm ff900000.vpu: DSI transceiver device is disabled
08:51:34.176: [    1.699030] [drm] Initialized meson 1.0.0 20161109 for ff900000.vpu on minor 1
08:51:34.176: [    1.895709] Console: switching to colour frame buffer device 128x48
08:51:34.176: [    1.913019] meson-drm ff900000.vpu: [drm] fb0: mesondrmfb frame buffer device
08:51:34.176: [    1.923251] meson8b-dwmac ff3f0000.ethernet: IRQ eth_wake_irq not found
08:51:34.176: [    1.926725] meson8b-dwmac ff3f0000.ethernet: IRQ eth_lpi not found
08:51:34.176: [    1.932910] meson8b-dwmac ff3f0000.ethernet: PTP uses main clock
08:51:34.176: [    1.939341] meson8b-dwmac ff3f0000.ethernet: User ID: 0x11, Synopsys ID: 0x37
08:51:34.176: [    1.945965] meson8b-dwmac ff3f0000.ethernet: 	DWMAC1000
08:51:34.176: [    1.951133] meson8b-dwmac ff3f0000.ethernet: DMA HW capability register supported
08:51:34.176: [    1.958584] meson8b-dwmac ff3f0000.ethernet: RX Checksum Offload Engine supported
08:51:34.176: [    1.966038] meson8b-dwmac ff3f0000.ethernet: COE Type 2
08:51:34.176: [    1.971237] meson8b-dwmac ff3f0000.ethernet: TX Checksum insertion supported
08:51:34.177: [    1.978256] meson8b-dwmac ff3f0000.ethernet: Wake-Up On Lan supported
08:51:34.177: [    1.984712] meson8b-dwmac ff3f0000.ethernet: Normal descriptors
08:51:34.177: [    1.990565] meson8b-dwmac ff3f0000.ethernet: Ring mode enabled
08:51:34.177: [    1.996370] meson8b-dwmac ff3f0000.ethernet: Enable RX Mitigation via HW Watchdog Timer
08:51:34.177: [    2.006271] dwc3-meson-g12a ffe09000.usb: USB2 ports: 2
08:51:34.177: [    2.009556] dwc3-meson-g12a ffe09000.usb: USB3 ports: 1
08:51:34.177: [    2.018263] dwc2 ff400000.usb: supply vusb_d not found, using dummy regulator
08:51:34.177: [    2.021929] dwc2 ff400000.usb: supply vusb_a not found, using dummy regulator
08:51:34.177: [    2.029157] dwc2 ff400000.usb: EPs: 7, dedicated fifos, 712 entries in SPRAM
08:51:34.177: [    2.037708] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
08:51:34.177: [    2.041457] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
08:51:34.177: [    2.049162] xhci-hcd xhci-hcd.2.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x0000008000000010
08:51:34.177: [    2.058449] xhci-hcd xhci-hcd.2.auto: irq 24, io mem 0xff500000
08:51:34.177: [    2.064417] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
08:51:34.177: [    2.069789] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
08:51:34.177: [    2.077411] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
08:51:34.177: [    2.084438] hub 1-0:1.0: USB hub found
08:51:34.177: [    2.087685] hub 1-0:1.0: 2 ports detected
08:51:34.177: [    2.091948] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
08:51:34.177: [    2.100044] hub 2-0:1.0: USB hub found
08:51:34.177: [    2.103433] hub 2-0:1.0: 1 port detected
08:51:34.177: [    2.113666] meson-gx-mmc ffe05000.mmc: Got CD GPIO
08:51:34.177: [    2.140028] pca953x 0-0020: using no AI
08:51:34.177: [    2.146081] meson-gx-mmc ffe07000.mmc: allocated mmc-pwrseq
08:51:34.177: [    2.173667] meson8b-dwmac ff3f0000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
08:51:34.177: [    2.268613] meson8b-dwmac ff3f0000.ethernet eth0: PHY [mdio_mux-0.0:00] driver [RTL8211F Gigabit Ethernet] (irq=26)
08:51:34.177: [    2.284325] meson8b-dwmac ff3f0000.ethernet eth0: No Safety Features support found
08:51:34.177: [    2.286262] meson8b-dwmac ff3f0000.ethernet eth0: PTP not supported by HW
08:51:34.177: [    2.293255] meson8b-dwmac ff3f0000.ethernet eth0: configuring for phy/rgmii link mode
08:51:34.177: [    2.344542] usb 1-1: new high-speed USB device number 2 using xhci-hcd
08:51:34.177: [    2.387848] mmc1: new HS200 MMC card at address 0001
08:51:34.177: [    2.388309] mmcblk1: mmc1:0001 BJTD4R 29.1 GiB
08:51:34.177: [    2.393244] mmcblk1boot0: mmc1:0001 BJTD4R 4.00 MiB
08:51:34.177: [    2.397453] mmcblk1boot1: mmc1:0001 BJTD4R 4.00 MiB
08:51:34.177: [    2.402202] mmcblk1rpmb: mmc1:0001 BJTD4R 4.00 MiB, chardev (511:0)
08:51:34.177: [    2.532056] hub 1-1:1.0: USB hub found
08:51:34.177: [    2.532123] hub 1-1:1.0: 4 ports detected
08:51:34.177: [    5.561869] meson8b-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
08:51:34.178: => setenv bootargs 'console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=192.168.201.1:/var/lib/lava/dispatcher/tmp/16905650/extract-nfsrootfs-p0olt4ic,tcp,hard,v3 init=/init rootwait usbcore.quirks=0bda:8153:k  ip=dhcp'
08:51:34.178: $ booti 0x1080000 - 0x1000000

08:51:34.178: => booti 0x1080000 - 0x1000000
08:51:34.178: Moving Image from 0x1080000 to 0x1200000, end=3a20000
08:51:34.178: ## Flattened Device Tree blob at 01000000
08:51:34.178:    Booting using the fdt blob at 0x1000000
08:51:34.178: Working FDT set to 1000000
08:51:34.178:    Loading Device Tree to 000000007fff0000, end 000000007ffffdfe ... OK
08:51:34.178: Working FDT set to 7fff0000
08:51:34.178: Starting kernel ...
08:51:34.178: Waiting for the login prompt
08:51:34.178: Parsing kernel messages
08:51:34.178: [    0.000000] 	Trampoline variant of Tasks RCU enabled.
08:51:34.178: [    0.000000] 	Tracing variant of Tasks RCU enabled.
08:51:39.255: [    5.592541] Sending DHCP requests .., OK
08:51:39.255: [    8.496578] IP-Config: Got DHCP answer from 192.168.201.1, my address is 192.168.201.16
08:51:39.255: [    8.502807] IP-Config: Complete:
08:51:39.255: [    8.506008]      device=eth0, hwaddr=c8:63:14:70:5d:a6, ipaddr=192.168.201.16, mask=255.255.255.0, gw=192.168.201.1
08:51:39.255: [    8.516407]      host=meson-g12b-a311d-khadas-vim3-cbg-0, domain=lava-rack, nis-domain=(none)
08:51:39.255: [    8.524899]      bootserver=192.168.201.1, rootserver=192.168.201.1, rootpath=
08:51:39.255: [    8.524904]      nameserver0=192.168.201.1
08:51:39.255: [    8.536306] clk: Disabling unused clocks
08:51:39.255: [    8.540215] ALSA device list:
08:51:39.255: [    8.543023]   No soundcards found.
08:51:39.255: [    8.572067] VFS: Mounted root (nfs filesystem) on device 0:22.
08:51:39.255: [    8.572829] devtmpfs: mounted
08:51:39.255: [    8.577557] Freeing unused kernel memory: 8128K
08:51:39.255: [    8.604603] Run /init as init process
08:51:39.256: [    9.818538] NET: Registered PF_INET6 protocol family
08:51:39.256: [    9.819299] Segment Routing with IPv6
08:51:39.256: [    9.821564] In-situ OAM (IOAM) with IPv6
08:51:39.256: sh: 0: can't access tty; job control turned off
08:51:39.257: $ 

08:51:39.257: lava-shell:
08:51:39.257: $ dropbear -R -B

08:51:39.257: lava-shell:dropbear -R -B
08:51:39.257: $ touch /dut_ready

08:51:44.402: lava-shell:touch /dut_ready
08:51:44.402: $ 

08:51:44.402: lava-shell:
08:51:49.487: [   18.659212] platform cpufreq-dt: deferred probe pending
08:51:49.487: [   18.659250] platform regulator-vddcpu-a: deferred probe pending
08:51:49.487: [   18.664712] platform regulator-vddcpu-b: deferred probe pending
08:51:49.487: [   18.670597] platform sdio-pwrseq: deferred probe pending
08:51:49.487: [   18.675882] platform wifi32k: deferred probe pending
08:51:49.490: $ export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/16905650/extract-nfsrootfs-p0olt4ic'

08:51:49.490: lava-shell:export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/16905650/extract-nfsrootfs-p0olt4ic'
08:51:49.490: $ export NFS_SERVER_IP='192.168.201.1'

08:51:49.490: lava-shell:export NFS_SERVER_IP='192.168.201.1'
08:51:49.490: end: 2 uboot-action (duration 00:01:09) [dut]
08:51:49.490: start: 3 lava-test-retry (timeout 00:00:30) [dut]
08:51:49.490: $ #

08:51:49.490: lava-shell:#
08:51:49.491: $ export SHELL=/bin/sh

08:51:49.491: lava-shell:export SHELL=/bin/sh
08:51:49.491: $ . /lava-16905650/environment

08:51:49.491: lava-shell:. /lava-16905650/environment
08:51:49.491: $ /lava-16905650/bin/lava-test-runner /lava-16905650/0

08:51:49.491: Test shell timeout: 10s (minimum of the action and connection timeout)
08:51:49.491: lava-shell:/lava-16905650/bin/lava-test-runner /lava-16905650/0
08:51:49.491: + export TESTRUN_ID=0_setup-ssh-server
08:51:49.491: + cd /lava-16905650/0/tests/0_setup-ssh-server
08:51:49.491: + cat uuid
08:51:49.491: + UUID=16905650_1.4.2.4.1
08:51:49.491: + set +x
08:51:49.491: <LAVA_SIGNAL_STARTRUN 0_setup-ssh-server 16905650_1.4.2.4.1>
08:51:49.491: + set -ex
08:51:49.491: + cd /
08:51:49.491: + findmnt --mountpoint /proc
08:51:49.491: 
08:51:49.491: section_start:1733993509:0_setup-ssh-server-16905650_1-4-2-4-1
[02:27] [dut] test_suite 0_setup-ssh-server 16905650_1.4.2.4.1 - Timeout: 5:00:00
08:51:49.491: Starting test lava.0_setup-ssh-server (16905650_1.4.2.4.1)
08:51:49.491: Skipping test definition patterns.
08:51:49.491: findmnt: can't read /proc/mounts: No such file or directory
08:51:49.491: + mount -t proc none /proc
08:51:49.491: + findmnt --mountpoint /sys
08:51:49.491: + mount -t sysfs none /sys
08:51:49.491: + mount -t debugfs none /sys/kernel/debug
08:51:49.491: + findmnt --mountpoint /dev
08:51:49.491: TARGET SOURCE   FSTYPE   OPTIONS
08:51:49.491: /dev   devtmpfs devtmpfs rw,relatime,size=1764324k,nr_inodes=441081,mode=755
08:51:49.491: + mkdir -p /dev/pts
08:51:49.491: + mount -t devpts devpts /dev/pts
08:51:49.491: + mkdir /dev/shm
08:51:49.491: + mount -t tmpfs -o noexec,nodev,nosuid tmpfs /dev/shm
08:51:49.491: + mount -t tmpfs tmpfs /tmp
08:51:49.491: + echo nameserver 8.8.8.8
08:51:49.491: + [ -z 192.168.201.1 ]
08:51:49.491: + echo 192.168.201.1 caching-proxy
08:51:49.491: + sntp -sS pool.ntp.org
08:51:49.491: 2024-12-12 08:51:43.709041 (+0000) +1733993484.050543 +/- 0.006909 pool.ntp.org 195.171.43.10 s1 no-leap
08:51:49.491: CLOCK: time stepped by 1733993484.050543
08:51:49.491: CLOCK: time changed from 1970-01-01 to 2024-12-12
08:51:49.491: + break
08:51:49.491: + export CURRENT_SECTION=dut_boot
08:51:49.491: + export -p
08:51:49.491: + set +x
08:51:49.491: <LAVA_SIGNAL_ENDRUN 0_setup-ssh-server 16905650_1.4.2.4.1>
08:51:49.491: <LAVA_TEST_RUNNER EXIT>
08:51:49.491: Ending use of test pattern.
08:51:49.491: Ending test lava.0_setup-ssh-server (16905650_1.4.2.4.1), duration 0.80
08:51:49.491: ok: lava_test_shell seems to have completed
08:51:49.491: end: 3 lava-test-retry (duration 00:00:01) [dut]
08:51:49.491: start: 4 lava-docker-test (timeout 04:53:28) [container]
08:51:49.491: Loading test definitions
08:51:49.491: Creating lava-test-runner.conf files
08:51:54.560: $ #

08:51:54.560: [?2004hdocker-test-shell:$ #
08:51:54.560: $ export SHELL=/bin/sh

08:51:54.560: $ . /lava-16905650/environment

08:51:54.560: [?2004hdocker-test-shell:$ export SHELL=/bin/sh
08:51:54.560: [?2004l
[?2004hdocker-test-shell:$ . /lava-16905650/environment
08:51:54.560: $ /lava-16905650/bin/lava-test-runner /lava-16905650/0

08:51:54.560: Test shell timeout: 10s (minimum of the action and connection timeout)
08:51:54.560: [?2004l
[?2004hdocker-test-shell:$ /lava-16905650/bin/lava-test-runner /lava-16905650/0
08:51:54.560: [?2004l
+ export 'TESTRUN_ID=0_docker_ssh_client'
08:51:54.560: + cd /lava-16905650/0/tests/0_docker_ssh_client
08:51:54.560: + cat uuid
08:51:54.560: + UUID=16905650_4.2.4.1
08:51:54.560: + set +x
08:51:54.560: <LAVA_SIGNAL_STARTRUN 0_docker_ssh_client 16905650_4.2.4.1>
08:51:54.561: + set -ex
08:51:54.561: + lava-target-ip
08:51:54.561: + timeout 1m bash
08:51:54.561: section_end:1733993514:0_setup-ssh-server-16905650_1-4-2-4-1

08:51:54.561: section_start:1733993514:0_docker_ssh_client-16905650_4-2-4-1
[02:32] [docker] test_suite 0_docker_ssh_client 16905650_4.2.4.1 - Timeout: 0:05:00
08:51:54.561: Starting test lava.0_docker_ssh_client (16905650_4.2.4.1)
08:51:54.561: Skipping test definition patterns.
08:51:54.561: + lava-target-ip
08:51:54.561: + ping -c 5 -w 60 192.168.201.16
08:51:54.561: PING 192.168.201.16 (192.168.201.16) 56(84) bytes of data.
08:51:54.561: 64 bytes from 192.168.201.16: icmp_seq=1 ttl=63 time=0.428 ms
08:51:54.561: 64 bytes from 192.168.201.16: icmp_seq=2 ttl=63 time=0.609 ms
08:51:54.561: 64 bytes from 192.168.201.16: icmp_seq=3 ttl=63 time=0.606 ms
08:51:54.561: 64 bytes from 192.168.201.16: icmp_seq=4 ttl=63 time=0.619 ms
08:51:54.561: 64 bytes from 192.168.201.16: icmp_seq=5 ttl=63 time=0.595 ms
08:51:54.561: --- 192.168.201.16 ping statistics ---
08:51:54.561: 5 packets transmitted, 5 received, 0% packet loss, time 4084ms
08:51:54.561: rtt min/avg/max/mdev = 0.428/0.571/0.619/0.072 ms
08:51:54.561: + lava_ssh_test_case wait_for_dut_login
08:51:54.561: + set -x
08:51:54.561: + local 'test_case=wait_for_dut_login'
08:51:54.561: + shift
08:51:54.561: + lava-target-ip
08:51:54.561: + lava-test-case wait_for_dut_login --shell ssh -T -o 'StrictHostKeyChecking=no' -o 'UserKnownHostsFile=/dev/null' root@192.168.201.16
08:51:54.561: section_end:1733993514:0_docker_ssh_client-16905650_4-2-4-1

08:51:54.561: section_start:1733993514:wait_for_dut_login
[02:32] test_case wait_for_dut_login - Timeout: 4:55:00
08:51:54.561: <LAVA_SIGNAL_STARTTC wait_for_dut_login>
08:51:54.561: section_end:1733993514:wait_for_dut_login

08:51:54.561: section_start:1733993514:post-wait_for_dut_login[collapsed=true]
[02:32] Post test_case wait_for_dut_login - Timeout: 0:05:00
08:51:54.561: Warning: Permanently added '192.168.201.16' (ED25519) to the list of known hosts.
<LAVA_SIGNAL_ENDTC wait_for_dut_login>
08:51:54.561: <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=wait_for_dut_login RESULT=pass>
08:51:54.561: + lava_ssh_test_case artifact_download 'bash --'
08:51:54.561: + set -x
08:51:54.561: + local 'test_case=artifact_download'
08:51:54.561: + shift
08:51:54.561: + lava-target-ip
08:51:54.561: + lava-test-case artifact_download --shell ssh -T -o 'StrictHostKeyChecking=no' -o 'UserKnownHostsFile=/dev/null' root@192.168.201.16 'bash --'
08:51:54.561: section_end:1733993514:post-wait_for_dut_login

08:51:54.561: section_start:1733993514:artifact_download
[02:32] test_case artifact_download - Timeout: 4:55:00
08:51:54.561: <LAVA_SIGNAL_STARTTC artifact_download>
08:51:54.561: Warning: Permanently added '192.168.201.16' (ED25519) to the list of known hosts.
+ echo 'export S3_JWT_FILE=/s3_jwt'
08:51:54.561: section_end:1733993514:artifact_download

08:51:54.561: section_start:1733993514:post-artifact_download[collapsed=true]
[02:32] Post test_case artifact_download - Timeout: 0:05:00
08:51:54.561: <LAVA_SIGNAL_ENDTC artifact_download>
08:51:54.561: <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=artifact_download RESULT=pass>
08:51:54.561: + export 'SSH_PTY_ARGS=-tt'
08:51:54.561: + lava_ssh_test_case mesa_panfrost-g52-vk-full:arm64 '"cd / && /init-stage2.sh"'
08:51:54.561: + set -x
08:51:54.561: + local 'test_case=mesa_panfrost-g52-vk-full:arm64'
08:51:54.561: + shift
08:51:54.561: + lava-target-ip
08:51:54.561: + lava-test-case mesa_panfrost-g52-vk-full:arm64 --shell ssh -tt -o 'StrictHostKeyChecking=no' -o 'UserKnownHostsFile=/dev/null' root@192.168.201.16 '"cd / && /init-stage2.sh"'
08:51:54.561: section_end:1733993514:post-artifact_download

08:51:54.561: 
08:51:54.561: <LAVA_SIGNAL_STARTTC mesa_panfrost-g52-vk-full:arm64>
08:51:54.561: Warning: Permanently added '192.168.201.16' (ED25519) to the list of known hosts.
08:51:54.561: section_end:1733993510:dut_boot

08:51:54.561: section_start:1733993510:init_stage2[collapsed=true]
[02:28] Pre-testing hardware setup
08:51:54.561: + '[' -z '' ']'
08:51:54.561: + HWCI_ZRAM_SIZE=2G
08:51:54.561: + /sbin/zramctl --find --size 2G -a zstd
08:51:54.561: /dev/zram0
08:51:59.665: + mkswap /dev/zram0
08:51:59.665: Setting up swapspace version 1, size = 2 GiB (2147479552 bytes)
08:51:59.665: no label, UUID=27bf8122-4cf2-4566-8aee-2673df87e4ca
08:51:59.665: + swapon /dev/zram0
08:51:59.665: + echo 'zram: 2G activated'
08:51:59.665: zram: 2G activated
08:51:59.665: + '[' '' = true ']'
08:51:59.665: + ln -sf /builds/Valentine/mesa/install /install
08:51:59.665: + export LD_LIBRARY_PATH=/install/lib
08:51:59.665: + LD_LIBRARY_PATH=/install/lib
08:51:59.665: + export LIBGL_DRIVERS_PATH=/install/lib/dri
08:51:59.665: + LIBGL_DRIVERS_PATH=/install/lib/dri
08:51:59.665: + export LD_LIBRARY_PATH=/install/lib:/usr/local/lib
08:51:59.665: + LD_LIBRARY_PATH=/install/lib:/usr/local/lib
08:51:59.665: + export XDG_CACHE_HOME=/tmp
08:51:59.665: + XDG_CACHE_HOME=/tmp
08:51:59.665: ++ python3 -c 'import sys;print(":".join(sys.path))'
08:51:59.665: + export PYTHONPATH=:/usr/lib/python311.zip:/usr/lib/python3.11:/usr/lib/python3.11/lib-dynload:/usr/local/lib/python3.11/dist-packages:/usr/lib/python3/dist-packages:/usr/lib/python3.11/dist-packages
08:51:59.665: + PYTHONPATH=:/usr/lib/python311.zip:/usr/lib/python3.11:/usr/lib/python3.11/lib-dynload:/usr/local/lib/python3.11/dist-packages:/usr/lib/python3/dist-packages:/usr/lib/python3.11/dist-packages
08:51:59.665: + '[' -n '' ']'
08:51:59.665: + ls -1 /install/lib/dri/armada-drm_dri.so /install/lib/dri/etnaviv_dri.so /install/lib/dri/exynos_dri.so /install/lib/dri/gm12u320_dri.so /install/lib/dri/hdlcd_dri.so /install/lib/dri/hx8357d_dri.so /install/lib/dri/ili9163_dri.so /install/lib/dri/ili9225_dri.so /install/lib/dri/ili9341_dri.so /install/lib/dri/ili9486_dri.so /install/lib/dri/imx-dcss_dri.so /install/lib/dri/imx-drm_dri.so /install/lib/dri/imx-lcdif_dri.so /install/lib/dri/ingenic-drm_dri.so /install/lib/dri/kgsl_dri.so /install/lib/dri/kirin_dri.so /install/lib/dri/kms_swrast_dri.so /install/lib/dri/komeda_dri.so /install/lib/dri/libdril_dri.so /install/lib/dri/lima_dri.so /install/lib/dri/mali-dp_dri.so /install/lib/dri/mcde_dri.so /install/lib/dri/mediatek_dri.so /install/lib/dri/meson_dri.so /install/lib/dri/mi0283qt_dri.so /install/lib/dri/msm_dri.so /install/lib/dri/mxsfb-drm_dri.so /install/lib/dri/nouveau_dri.so /install/lib/dri/panel-mipi-dbi_dri.so /install/lib/dri/panfrost_dri.so /install/lib/dri/panthor_dri.so /install/lib/dri/pl111_dri.so /install/lib/dri/rcar-du_dri.so /install/lib/dri/repaper_dri.so /install/lib/dri/rockchip_dri.so /install/lib/dri/rzg2l-du_dri.so /install/lib/dri/ssd130x_dri.so /install/lib/dri/st7586_dri.so /install/lib/dri/st7735r_dri.so /install/lib/dri/sti_dri.so /install/lib/dri/stm_dri.so /install/lib/dri/sun4i-drm_dri.so /install/lib/dri/swrast_dri.so /install/lib/dri/tegra_dri.so /install/lib/dri/udl_dri.so /install/lib/dri/v3d_dri.so /install/lib/dri/vc4_dri.so /install/lib/dri/virtio_gpu_dri.so /install/lib/dri/vkms_dri.so /install/lib/dri/zink_dri.so /install/lib/dri/zynqmp-dpsub_dri.so
08:51:59.665: /install/lib/dri/armada-drm_dri.so
08:51:59.665: /install/lib/dri/etnaviv_dri.so
08:51:59.665: /install/lib/dri/exynos_dri.so
08:51:59.665: /install/lib/dri/gm12u320_dri.so
08:51:59.665: /install/lib/dri/hdlcd_dri.so
08:51:59.665: /install/lib/dri/hx8357d_dri.so
08:51:59.665: /install/lib/dri/ili9163_dri.so
08:51:59.665: /install/lib/dri/ili9225_dri.so
08:51:59.665: /install/lib/dri/ili9341_dri.so
08:51:59.665: /install/lib/dri/ili9486_dri.so
08:51:59.666: /install/lib/dri/imx-dcss_dri.so
08:51:59.666: /install/lib/dri/imx-drm_dri.so
08:51:59.666: /install/lib/dri/imx-lcdif_dri.so
08:51:59.666: /install/lib/dri/ingenic-drm_dri.so
08:51:59.666: /install/lib/dri/kgsl_dri.so
08:51:59.666: /install/lib/dri/kirin_dri.so
08:51:59.666: /install/lib/dri/kms_swrast_dri.so
08:51:59.666: /install/lib/dri/komeda_dri.so
08:51:59.666: /install/lib/dri/libdril_dri.so
08:51:59.666: /install/lib/dri/lima_dri.so
08:51:59.666: /install/lib/dri/mali-dp_dri.so
08:51:59.666: /install/lib/dri/mcde_dri.so
08:51:59.666: /install/lib/dri/mediatek_dri.so
08:51:59.666: /install/lib/dri/meson_dri.so
08:51:59.666: /install/lib/dri/mi0283qt_dri.so
08:51:59.666: /install/lib/dri/msm_dri.so
08:51:59.666: /install/lib/dri/mxsfb-drm_dri.so
08:51:59.666: /install/lib/dri/nouveau_dri.so
08:51:59.666: /install/lib/dri/panel-mipi-dbi_dri.so
08:51:59.666: /install/lib/dri/panfrost_dri.so
08:51:59.666: /install/lib/dri/panthor_dri.so
08:51:59.666: /install/lib/dri/pl111_dri.so
08:51:59.666: /install/lib/dri/rcar-du_dri.so
08:51:59.666: /install/lib/dri/repaper_dri.so
08:51:59.666: /install/lib/dri/rockchip_dri.so
08:51:59.666: /install/lib/dri/rzg2l-du_dri.so
08:51:59.666: /install/lib/dri/ssd130x_dri.so
08:51:59.666: /install/lib/dri/st7586_dri.so
08:51:59.666: /install/lib/dri/st7735r_dri.so
08:51:59.666: /install/lib/dri/sti_dri.so
08:51:59.666: /install/lib/dri/stm_dri.so
08:51:59.666: /install/lib/dri/sun4i-drm_dri.so
08:51:59.666: /install/lib/dri/swrast_dri.so
08:51:59.666: /install/lib/dri/tegra_dri.so
08:51:59.666: /install/lib/dri/udl_dri.so
08:51:59.666: /install/lib/dri/v3d_dri.so
08:51:59.666: /install/lib/dri/vc4_dri.so
08:51:59.666: /install/lib/dri/virtio_gpu_dri.so
08:51:59.666: /install/lib/dri/vkms_dri.so
08:51:59.666: /install/lib/dri/zink_dri.so
08:51:59.666: /install/lib/dri/zynqmp-dpsub_dri.so
08:51:59.666: + '[' true = true ']'
08:51:59.666: + head -0 /dev/dri/renderD128
08:51:59.666: ++ find /sys/devices -name governor
08:51:59.666: ++ grep gpu
08:51:59.666: + DEVFREQ_GOVERNOR=/sys/devices/platform/soc/ffe40000.gpu/devfreq/ffe40000.gpu/governor
08:51:59.666: + test -z /sys/devices/platform/soc/ffe40000.gpu/devfreq/ffe40000.gpu/governor
08:51:59.666: + echo performance
08:51:59.666: + echo performance
08:51:59.666: + tee -a '/sys/devices/system/cpu/cpufreq/policy*/scaling_governor'
08:51:59.666: tee: '/sys/devices/system/cpu/cpufreq/policy*/scaling_governor': No such file or directory
08:51:59.666: performance
08:51:59.666: + true
08:51:59.666: ++ find /sys/devices -name autosuspend_delay_ms
08:51:59.666: ++ grep gpu
08:51:59.666: ++ head -1
08:51:59.666: + GPU_AUTOSUSPEND=/sys/devices/platform/soc/ffe40000.gpu/power/autosuspend_delay_ms
08:51:59.666: + test -z /sys/devices/platform/soc/ffe40000.gpu/power/autosuspend_delay_ms
08:51:59.666: + echo -1
08:51:59.666: + /intel-gpu-freq.sh -s 70% --cpu-set-max 65% -g all -d
08:51:59.666: INFO: intel-gpu-freq.sh: No Intel GPU detected
08:51:59.666: + '[' -x /kdl.sh ']'
08:51:59.666: + echo 'launch kdl.sh!'
08:51:59.666: launch kdl.sh!
08:51:59.666: + BACKGROUND_PIDS='187 '
08:51:59.666: + /kdl.sh
08:51:59.666: + '[' -n '' ']'
08:51:59.666: + '[' -x /capture-devcoredump.sh ']'
08:51:59.667: + /capture-devcoredump.sh
08:51:59.667: + BACKGROUND_PIDS='188 187 '
08:51:59.667: ++ uname -m
08:51:59.667: + ARCH=aarch64
08:51:59.667: + export VK_DRIVER_FILES=/install/share/vulkan/icd.d/panfrost_icd.aarch64.json
08:51:59.667: + VK_DRIVER_FILES=/install/share/vulkan/icd.d/panfrost_icd.aarch64.json
08:51:59.667: + '[' -n '' ']'
08:51:59.667: + '[' -n 1 ']'
08:51:59.667: + WESTON_X11_SOCK=/tmp/.X11-unix/X0
08:51:59.667: + '[' -n '' ']'
08:51:59.667: + export WAYLAND_DISPLAY=wayland-0
08:51:59.667: + WAYLAND_DISPLAY=wayland-0
08:51:59.667: + export DISPLAY=:0
08:51:59.667: + DISPLAY=:0
08:51:59.667: + mkdir -p /tmp/.X11-unix
08:51:59.667: + BACKGROUND_PIDS='194 188 187 '
08:51:59.667: + '[' '!' -S /tmp/.X11-unix/X0 ']'
08:51:59.667: + sleep 1
08:51:59.667: + env weston -Bheadless-backend.so --use-gl -Swayland-0 --xwayland --idle-time=0
08:51:59.667: Date: 2024-12-12 UTC
08:51:59.667: [08:51:51.345] weston 10.0.1
08:51:59.667:                https://wayland.freedesktop.org
08:51:59.667:                Bug reports to: https://gitlab.freedesktop.org/wayland/weston/issues/
08:51:59.667:                Build: 10.0.1
08:51:59.667: [08:51:51.346] Command line: weston -Bheadless-backend.so --use-gl -Swayland-0 --xwayland --idle-time=0
08:51:59.667: [08:51:51.346] OS: Linux, 6.6.21-gf8ea60f0f8b9, #1 SMP PREEMPT Wed May  8 03:40:15 UTC 2024, aarch64
08:51:59.667: [08:51:51.346] Flight recorder: enabled
08:51:59.667: [08:51:51.347] Starting with no config file.
08:51:59.667: [08:51:51.350] Output repaint window is 7 ms maximum.
08:51:59.667: [08:51:51.352] Loading module '/usr/lib/aarch64-linux-gnu/libweston-10/headless-backend.so'
08:51:59.667: [08:51:51.355] Loading module '/usr/lib/aarch64-linux-gnu/libweston-10/gl-renderer.so'
08:51:59.667: + '[' '!' -S /tmp/.X11-unix/X0 ']'
08:51:59.667: + sleep 1
08:51:59.667: [08:51:52.239] EGL client extensions: EGL_EXT_client_extensions
08:51:59.667:                EGL_EXT_device_base EGL_EXT_device_enumeration
08:51:59.667:                EGL_EXT_device_query EGL_EXT_platform_base
08:51:59.667:                EGL_KHR_client_get_all_proc_addresses EGL_KHR_debug
08:51:59.667:                EGL_EXT_platform_device EGL_EXT_explicit_device
08:51:59.667:                EGL_EXT_platform_wayland EGL_KHR_platform_wayland
08:51:59.667:                EGL_EXT_platform_x11 EGL_KHR_platform_x11 EGL_EXT_platform_xcb
08:51:59.667:                EGL_MESA_platform_gbm EGL_KHR_platform_gbm
08:51:59.667:                EGL_MESA_platform_surfaceless
08:51:59.667: [08:51:52.384] EGL device extensions: EGL_EXT_device_drm
08:51:59.667:                EGL_EXT_device_drm_render_node
08:51:59.667: [08:51:52.384] EGL version: 1.5
08:51:59.667: [08:51:52.384] EGL vendor: Mesa Project
08:51:59.667: [08:51:52.384] EGL client APIs: OpenGL OpenGL_ES 
08:51:59.667: [08:51:52.384] EGL extensions: EGL_ANDROID_blob_cache
08:51:59.667:                EGL_ANDROID_native_fence_sync EGL_EXT_config_select_group
08:51:59.667:                EGL_EXT_image_dma_buf_import
08:51:59.667:                EGL_EXT_image_dma_buf_import_modifiers
08:51:59.667:                EGL_EXT_surface_compression EGL_IMG_context_priority
08:51:59.667:                EGL_KHR_cl_event2 EGL_KHR_config_attribs
08:51:59.667:                EGL_KHR_context_flush_control EGL_KHR_create_context
08:51:59.667:                EGL_KHR_create_context_no_error EGL_KHR_fence_sync
08:51:59.667:                EGL_KHR_get_all_proc_addresses EGL_KHR_gl_colorspace
08:51:59.667:                EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image
08:51:59.667:                EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image
08:51:59.667:                EGL_KHR_image_base EGL_KHR_no_config_context
08:51:59.667:                EGL_KHR_partial_update EGL_KHR_reusable_sync
08:51:59.667:                EGL_KHR_surfaceless_context EGL_EXT_pixel_format_float
08:51:59.667:                EGL_KHR_wait_sync EGL_MESA_configless_context
08:51:59.667:                EGL_MESA_drm_image EGL_MESA_gl_interop
08:51:59.667:                EGL_MESA_image_dma_buf_export EGL_MESA_query_driver
08:51:59.667:                EGL_MESA_x11_native_visual_id EGL_WL_bind_wayland_display
08:51:59.667: [08:51:52.387] EGL_KHR_surfaceless_context available
08:51:59.667: [08:51:52.420] Failed to obtain a high priority context.
08:51:59.667: [08:51:52.423] GL version: OpenGL ES 3.1 Mesa 25.0.0-devel (git-4d5e257451)
08:51:59.667: [08:51:52.423] GLSL version: OpenGL ES GLSL ES 3.10
08:51:59.667: [08:51:52.423] GL vendor: Mesa
08:51:59.667: [08:51:52.423] GL renderer: Mali-G52 (Panfrost)
08:51:59.667: [08:51:52.423] GL extensions: GL_EXT_blend_minmax GL_EXT_multi_draw_arrays
08:51:59.667:                GL_EXT_texture_filter_anisotropic
08:51:59.667:                GL_EXT_texture_compression_s3tc GL_EXT_texture_compression_dxt1
08:51:59.667:                GL_EXT_texture_compression_rgtc GL_EXT_texture_format_BGRA8888
08:51:59.667:                GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24
08:51:59.668:                GL_OES_element_index_uint GL_OES_fbo_render_mipmap
08:51:59.668:                GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_standard_derivatives
08:51:59.668:                GL_OES_stencil8 GL_OES_texture_3D GL_OES_texture_float
08:51:59.668:                GL_OES_texture_float_linear GL_OES_texture_half_float
08:51:59.668:                GL_OES_texture_half_float_linear GL_OES_texture_npot
08:51:59.668:                GL_OES_vertex_half_float GL_EXT_draw_instanced
08:51:59.668:                GL_EXT_texture_sRGB_decode GL_OES_EGL_image
08:51:59.668:                GL_OES_depth_texture GL_OES_packed_depth_stencil
08:51:59.668:                GL_EXT_texture_type_2_10_10_10_REV GL_NV_conditional_render
08:51:59.668:                GL_OES_get_program_binary GL_APPLE_texture_max_level
08:51:59.668:                GL_EXT_discard_framebuffer GL_EXT_read_format_bgra
08:51:59.668:                GL_EXT_texture_storage GL_NV_pack_subimage GL_EXT_frag_depth
08:51:59.668:                GL_NV_fbo_color_attachments GL_OES_EGL_image_external
08:51:59.668:                GL_OES_EGL_sync GL_OES_vertex_array_object
08:51:59.668:                GL_ANGLE_pack_reverse_row_order
08:51:59.668:                GL_ANGLE_texture_compression_dxt3
08:51:59.668:                GL_ANGLE_texture_compression_dxt5
08:51:59.668:                GL_EXT_occlusion_query_boolean GL_EXT_texture_rg
08:51:59.668:                GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer
08:51:59.668:                GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil
08:51:59.668:                GL_APPLE_sync GL_EXT_draw_buffers GL_EXT_instanced_arrays
08:51:59.668:                GL_EXT_map_buffer_range GL_EXT_shadow_samplers GL_KHR_debug
08:51:59.668:                GL_KHR_texture_compression_astc_ldr GL_NV_generate_mipmap_sRGB
08:51:59.668:                GL_NV_pixel_buffer_object GL_OES_depth_texture_cube_map
08:51:59.668:                GL_OES_required_internalformat GL_OES_surfaceless_context
08:51:59.668:                GL_EXT_color_buffer_float GL_EXT_debug_label
08:51:59.668:                GL_EXT_sRGB_write_control GL_EXT_separate_shader_objects
08:51:59.668:                GL_EXT_shader_framebuffer_fetch
08:51:59.668:                GL_EXT_shader_implicit_conversions GL_EXT_shader_integer_mix
08:51:59.668:                GL_EXT_base_instance GL_EXT_compressed_ETC1_RGB8_sub_texture
08:51:59.668:                GL_EXT_copy_image GL_EXT_draw_buffers_indexed
08:51:59.668:                GL_EXT_draw_elements_base_vertex GL_EXT_gpu_shader5
08:51:59.668:                GL_EXT_multi_draw_indirect GL_EXT_polygon_offset_clamp
08:51:59.668:                GL_EXT_primitive_bounding_box GL_EXT_render_snorm
08:51:59.668:                GL_EXT_shader_io_blocks GL_EXT_texture_border_clamp
08:51:59.668:                GL_EXT_texture_buffer GL_EXT_texture_norm16 GL_EXT_texture_view
08:51:59.668:                GL_KHR_blend_equation_advanced
08:51:59.668:                GL_KHR_blend_equation_advanced_coherent
08:51:59.668:                GL_KHR_context_flush_control GL_NV_image_formats
08:51:59.668:                GL_NV_shader_noperspective_interpolation GL_OES_copy_image
08:51:59.668:                GL_OES_draw_buffers_indexed GL_OES_draw_elements_base_vertex
08:51:59.668:                GL_OES_gpu_shader5 GL_OES_primitive_bounding_box
08:51:59.668:                GL_OES_sample_shading GL_OES_sample_variables
08:51:59.668:                GL_OES_shader_io_blocks GL_OES_shader_multisample_interpolation
08:51:59.668:                GL_OES_texture_border_clamp GL_OES_texture_buffer
08:51:59.668:                GL_OES_texture_stencil8
08:51:59.668:                GL_OES_texture_storage_multisample_2d_array GL_OES_texture_view
08:51:59.668:                GL_EXT_blend_func_extended GL_EXT_buffer_storage
08:51:59.668:                GL_EXT_float_blend GL_EXT_texture_sRGB_R8
08:51:59.668:                GL_EXT_texture_sRGB_RG8 GL_KHR_no_error
08:51:59.668:                GL_KHR_texture_compression_astc_sliced_3d
08:51:59.668:                GL_OES_EGL_image_external_essl3 GL_OES_shader_image_atomic
08:51:59.668:                GL_EXT_clear_texture GL_EXT_multisampled_render_to_texture
08:51:59.668:                GL_EXT_multisampled_render_to_texture2
08:51:59.668:                GL_EXT_texture_compression_s3tc_srgb
08:51:59.668:                GL_MESA_shader_integer_functions GL_EXT_clip_control
08:51:59.668:                GL_EXT_color_buffer_half_float GL_EXT_texture_compression_bptc
08:51:59.668:                GL_EXT_texture_mirror_clamp_to_edge
08:51:59.668:                GL_KHR_parallel_shader_compile GL_EXT_EGL_image_storage
08:51:59.668:                GL_EXT_shader_framebuffer_fetch_non_coherent
08:51:59.668:                GL_INTEL_blackhole_render GL_MESA_framebuffer_flip_y
08:51:59.668:                GL_EXT_depth_clamp GL_MESA_sampler_objects
08:51:59.668:                GL_EXT_EGL_image_storage_compression
08:51:59.668:                GL_EXT_texture_storage_compression GL_MESA_bgra
08:51:59.668: [08:51:52.506] GL ES 3.1 - renderer features:
08:51:59.668:                read-back format: BGRA
08:51:59.668:                EGL Wayland extension: yes
08:51:59.668: [08:51:52.506] Registered plugin API 'weston_windowed_output_api_v1' of size 16
08:51:59.668: [08:51:52.506] Color manager: no-op
08:51:59.668: [08:51:52.507] Output 'headless' using color profile: built-in default sRGB SDR profile
08:51:59.668: [08:51:52.507] Chosen EGL config details: id:  25 rgba: 8 8 8 0 buf: 24 dep:  0 stcl: 0 int: 1-1 type: pbf vis_id: 0
08:51:59.668: [08:51:52.507] Error: pbuffer surface does not support EGL_BUFFER_PRESERVED, got 0x3095. Continuing anyway.
08:51:59.668: [08:51:52.507] Output 'headless' enabled with head(s) headless
08:51:59.668: [08:51:52.507] Compositor capabilities:
08:51:59.668:                arbitrary surface rotation: yes
08:51:59.669:                screen capture uses y-flip: yes
08:51:59.669:                cursor planes: no
08:51:59.669:                arbitrary resolutions: no
08:51:59.669:                view mask clipping: yes
08:51:59.669:                explicit sync: yes
08:51:59.669:                color operations: yes
08:51:59.669:                presentation clock: CLOCK_MONOTONIC_RAW, id 4
08:51:59.669:                presentation clock resolution: 0.000000001 s
08:51:59.669: [08:51:52.510] Loading module '/usr/lib/aarch64-linux-gnu/weston/desktop-shell.so'
08:51:59.669: [08:51:52.521] launching '/usr/lib/aarch64-linux-gnu/weston-keyboard'
08:51:59.669: [08:51:52.524] Note: support for the deprecated wl_shell interface is disabled. If a legacy client still needs it, it can be re-enabled by passing -Ddeprecated-wl-shell=true to Meson when building Weston.
08:51:59.669: [08:51:52.526] Loading module '/usr/lib/aarch64-linux-gnu/libweston-10/xwayland.so'
08:51:59.669: [08:51:52.669] Registered plugin API 'weston_xwayland_v1' of size 32
08:51:59.669: [08:51:52.669] Registered plugin API 'weston_xwayland_surface_v1' of size 16
08:51:59.669: [08:51:52.669] xserver listening on display :0
08:51:59.669: [08:51:52.669] launching '/usr/lib/aarch64-linux-gnu/weston-desktop-shell'
08:51:59.669: could not load cursor 'dnd-move'
08:51:59.669: could not load cursor 'dnd-copy'
08:51:59.669: could not load cursor 'dnd-none'
08:51:59.669: could not load cursor 'dnd-move'
08:51:59.669: could not load cursor 'dnd-copy'
08:51:59.669: could not load cursor 'dnd-none'
08:51:59.669: + '[' '!' -S /tmp/.X11-unix/X0 ']'
08:51:59.669: + set +x
08:51:59.669: section_end:1733993513:init_stage2

08:51:59.669: Running /install/deqp-runner.sh  ...
08:51:59.669: section_start:1733993513:test_setup[collapsed=true]
[02:31] deqp: preparing test setup
08:51:59.669: + ln -sf /builds/Valentine/mesa/install /install
08:51:59.669: + '[' -z panfrost-g52 ']'
08:51:59.669: ++ realpath -s //install
08:51:59.669: + INSTALL=/install
08:51:59.669: + export LD_LIBRARY_PATH=/install/lib/:/install/lib:/usr/local/lib
08:51:59.669: + LD_LIBRARY_PATH=/install/lib/:/install/lib:/usr/local/lib
08:51:59.669: + export EGL_PLATFORM=surfaceless
08:51:59.669: + EGL_PLATFORM=surfaceless
08:51:59.669: ++ uname -m
08:51:59.669: + ARCH=aarch64
08:51:59.669: + export VK_DRIVER_FILES=//install/share/vulkan/icd.d/panfrost_icd.aarch64.json
08:51:59.669: + VK_DRIVER_FILES=//install/share/vulkan/icd.d/panfrost_icd.aarch64.json
08:51:59.669: + export OCL_ICD_VENDORS=//install/etc/OpenCL/vendors/
08:51:59.669: + OCL_ICD_VENDORS=//install/etc/OpenCL/vendors/
08:51:59.669: + '[' -n '' ']'
08:51:59.669: + SHADER_CACHE_HOME=/tmp
08:51:59.669: + SHADER_CACHE_DIR=/tmp/mesa_shader_cache
08:51:59.669: + findmnt -n tmpfs /tmp
08:51:59.669: /tmp   tmpfs  tmpfs  rw,relatime
08:51:59.669: + '[' -z panfrost-g52-vk-full ']'
08:51:59.669: + '[' -e /install/panfrost-g52-fails.txt ']'
08:51:59.669: + DEQP_RUNNER_OPTIONS=' --baseline /install/panfrost-g52-fails.txt'
08:51:59.669: + touch /install/panfrost-g52-flakes.txt
08:51:59.669: + '[' -n panfrost ']'
08:51:59.669: + '[' -e /install/panfrost-skips.txt ']'
08:51:59.669: + '[' -n '' ']'
08:51:59.669: + '[' -n '' ']'
08:51:59.669: + '[' -e /install/panfrost-g52-skips.txt ']'
08:51:59.669: + DEQP_SKIPS=' /install/panfrost-g52-skips.txt'
08:51:59.669: + '[' -e /install/panfrost-g52-slow-skips.txt ']'
08:51:59.669: + '[' '' '!=' gbm ']'
08:51:59.669: + DEQP_SKIPS=' /install/panfrost-g52-skips.txt /install/x11-skips.txt'
08:51:59.669: + '[' '' = gbm ']'
08:51:59.669: + '[' -n '' ']'
08:51:59.669: + '[' -n panfrost ']'
08:51:59.669: + '[' -z panfrost-g52-vk-full ']'
08:51:59.669: + '[' -n '' ']'
08:51:59.669: + export VK_LAYER_SETTINGS_PATH=/install/panfrost-g52-validation-settings.txt
08:51:59.669: + VK_LAYER_SETTINGS_PATH=/install/panfrost-g52-validation-settings.txt
08:51:59.669: + '[' '' = virpipe ']'
08:51:59.669: + '[' -z panfrost-g52-vk-full ']'
08:51:59.669: section_end:1733993513:test_setup

08:51:59.669: section_start:1733993513:deqp
[02:31] deqp: deqp-runner
08:51:59.669: dEQP VK-main at commit a9f7069b9a on 2024-12-03 23:02:51 +0100
08:51:59.670: dEQP VK version vulkan-cts-1.3.10.0
08:51:59.670: The following local patches are applied on top:
08:51:59.670: - Remove multi-line test results in DRM format modifier tests
08:51:59.670: dEQP GL version opengl-cts-4.6.5.0
08:51:59.670: The following local patches are applied on top:
08:51:59.670: dEQP GLES version opengl-es-cts-3.2.11.0
08:51:59.670: The following local patches are applied on top:
08:51:59.670: + set +e
08:51:59.670: + deqp-runner -V
08:51:59.670: deqp-runner 0.20.2
08:51:59.670: + '[' -z panfrost-g52-vk-full ']'
08:51:59.670: + deqp-runner suite --suite /install/deqp-panfrost-g52-vk-full.toml --output /results --skips /install/all-skips.txt /install/panfrost-g52-skips.txt /install/x11-skips.txt --flakes /install/panfrost-g52-flakes.txt --testlog-to-xml /deqp-tools/testlog-to-xml --fraction-start 1 --fraction 1 --jobs 4 --baseline /install/panfrost-g52-fails.txt
08:51:59.670: dEQP version: 88ecfff5467a1147906cd81dcb4d5d96122b9116 (0x88ecfff5)
08:52:09.796: deviceName: Mali-G52 (Panfrost)
08:52:09.796: Running dEQP on 4 threads in 500-test groups
08:52:09.796: Pass: 0, Duration: 0
08:52:14.860: lava-shell:[   26.774713] zram0: detected capacity change from 0 to 4194304
08:52:19.924: [   26.809737] Adding 2097148k swap on /dev/zram0.  Priority:-2 extents:1 across:2097148k SS
08:52:19.925: [   27.030610] panfrost ffe40000.gpu: shader power transition timeout
08:52:19.925: [   27.032172] panfrost ffe40000.gpu: l2 power transition timeout
08:52:19.925: [   28.335321] weston[194]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set
08:52:25.014: Pass: 66, Skip: 434, Duration: 24, Remaining: 36:54:04
08:52:35.158: Pass: 269, ExpectedFail: 2, Skip: 1729, Duration: 26, Remaining: 10:05:09
08:52:45.289: [08:52:44.327] Spawned Xwayland server, pid 285
08:52:50.352: (WW) Option "-listen" for file descriptors is deprecated
08:52:50.352: Please use "-listenfd" instead.
08:52:50.352: (WW) Option "-listen" for file descriptors is deprecated
08:52:50.352: Please use "-listenfd" instead.
08:52:50.352: [08:52:45.117] xfixes version: 5.0
08:52:50.352: [08:52:45.149] created wm, root 573
08:52:50.352: Pass: 339, ExpectedFail: 2, Skip: 2159, Duration: 47, Remaining: 14:26:15
08:52:50.352: Pass: 450, ExpectedFail: 3, Skip: 3047, Duration: 49, Remaining: 10:44:58
08:53:15.749: Pass: 588, ExpectedFail: 3, Skip: 3909, Duration: 1:11, Remaining: 11:56:33
08:53:25.887: Pass: 751, ExpectedFail: 6, Skip: 5243, Duration: 1:21, Remaining: 10:13:51
08:53:36.036: Pass: 807, ExpectedFail: 6, Skip: 5687, Duration: 1:34, Remaining: 10:57:21
08:53:46.161: Pass: 941, ExpectedFail: 10, Skip: 6549, Duration: 1:41, Remaining: 10:11:09
08:53:51.224: Pass: 1011, ExpectedFail: 10, Skip: 6979, Duration: 1:46, Remaining: 10:03:09
08:54:01.377: Pass: 1068, ExpectedFail: 10, Skip: 7422, Duration: 1:58, Remaining: 10:28:13
08:54:06.455: Pass: 1200, ExpectedFail: 11, Skip: 8289, Duration: 2:05, Remaining: 9:55:04
08:54:11.549: Pass: 1276, ExpectedFail: 11, Skip: 8713, Duration: 2:10, Remaining: 9:49:10
08:54:26.741: Pass: 1339, ExpectedFail: 11, Skip: 9150, Duration: 2:22, Remaining: 10:14:14
08:54:31.816: Pass: 1463, ExpectedFail: 11, Skip: 10026, Duration: 2:28, Remaining: 9:44:15
08:54:36.878: Pass: 1521, ExpectedFail: 12, Skip: 10467, Duration: 2:33, Remaining: 9:35:42
08:54:52.093: Pass: 1579, ExpectedFail: 13, Skip: 10908, Duration: 2:47, Remaining: 10:04:24
08:54:57.155: Pass: 1709, ExpectedFail: 13, Skip: 11778, Duration: 2:53, Remaining: 9:38:14
08:55:12.469: Pass: 1772, ExpectedFail: 14, Skip: 12214, Duration: 3:10, Remaining: 10:14:31
08:55:22.608: Pass: 1897, ExpectedFail: 14, Skip: 13089, Duration: 3:13, Remaining: 9:41:04
08:55:27.669: Pass: 1970, ExpectedFail: 14, Skip: 13516, Duration: 3:27, Remaining: 10:03:37
08:55:37.834: Pass: 2044, ExpectedFail: 14, Skip: 13942, Duration: 3:35, Remaining: 10:07:35
08:55:53.281: Pass: 2214, ExpectedFail: 14, Skip: 15272, Duration: 3:52, Remaining: 9:57:39
08:56:03.413: Pass: 2283, ExpectedFail: 15, Skip: 15702, Duration: 3:59, Remaining: 9:59:59
08:56:13.555: Pass: 2421, ExpectedFail: 16, Skip: 16563, Duration: 4:12, Remaining: 9:59:06
08:56:28.731: Pass: 2540, ExpectedFail: 16, Skip: 17444, Duration: 4:23, Remaining: 9:52:38
08:56:38.922: Pass: 2683, ExpectedFail: 16, Skip: 18301, Duration: 4:37, Remaining: 9:53:32
08:56:49.047: Pass: 2755, ExpectedFail: 17, Skip: 18728, Duration: 4:39, Remaining: 9:45:35
08:56:54.111: Pass: 2818, ExpectedFail: 18, Skip: 19164, Duration: 4:48, Remaining: 9:50:13
08:56:59.171: Pass: 2940, ExpectedFail: 18, Skip: 20042, Duration: 4:59, Remaining: 9:44:49
08:57:09.301: Pass: 3005, ExpectedFail: 19, Skip: 20476, Duration: 5:03, Remaining: 9:41:08
08:57:19.428: Pass: 3092, ExpectedFail: 19, Skip: 20889, Duration: 5:12, Remaining: 9:45:07
08:57:29.557: Pass: 3215, ExpectedFail: 20, Skip: 21765, Duration: 5:21, Remaining: 9:38:15
08:57:29.557: Pass: 3287, ExpectedFail: 20, Skip: 22193, Duration: 5:26, Remaining: 9:35:32
08:57:39.695: ERROR - dEQP error: MESA: error: DRM_IOCTL_PANFROST_CREATE_BO failed (err=28)
08:57:44.760: Pass: 3344, ExpectedFail: 21, Skip: 22635, Duration: 5:37, Remaining: 9:42:55
08:57:49.824: Pass: 3399, ExpectedFail: 23, Skip: 23078, Duration: 5:42, Remaining: 9:40:22
08:57:54.887: Pass: 3464, ExpectedFail: 23, Skip: 23513, Duration: 5:49, Remaining: 9:41:33
08:58:05.016: Pass: 3529, ExpectedFail: 25, Skip: 23946, Duration: 5:57, Remaining: 9:43:06
08:58:10.083: Pass: 3612, ExpectedFail: 25, Skip: 24363, Duration: 6:03, Remaining: 9:42:43
08:58:20.225: Pass: 3669, ExpectedFail: 25, Skip: 24806, Duration: 6:10, Remaining: 9:43:59
08:58:30.356: Pass: 3785, ExpectedFail: 26, Warn: 1, Skip: 25688, Duration: 6:20, Remaining: 9:39:05
08:58:35.422: Pass: 3845, ExpectedFail: 27, Warn: 1, Skip: 26127, Duration: 6:26, Remaining: 9:38:20
08:58:45.536: Pass: 3912, ExpectedFail: 27, Warn: 1, Skip: 26560, Duration: 6:35, Remaining: 9:40:44
08:58:50.594: Pass: 3980, ExpectedFail: 27, Warn: 1, Skip: 26992, Duration: 6:43, Remaining: 9:44:04
08:58:55.654: Pass: 4048, ExpectedFail: 27, Warn: 1, Skip: 27424, Duration: 6:50, Remaining: 9:44:18
08:59:05.779: Pass: 4171, ExpectedFail: 27, Warn: 1, Skip: 28301, Duration: 6:58, Remaining: 9:36:46
08:59:15.910: Pass: 4229, ExpectedFail: 27, Warn: 1, Skip: 28743, Duration: 7:07, Remaining: 9:40:40
08:59:20.983: Pass: 4299, ExpectedFail: 28, Warn: 1, Skip: 29172, Duration: 7:15, Remaining: 9:42:33
08:59:31.118: Pass: 4376, ExpectedFail: 28, Warn: 1, Skip: 29595, Duration: 7:22, Remaining: 9:43:05
08:59:41.233: Pass: 4523, ExpectedFail: 29, Warn: 1, Skip: 30447, Duration: 7:39, Remaining: 9:47:24
08:59:46.296: Pass: 4596, ExpectedFail: 29, Warn: 1, Skip: 30874, Duration: 7:41, Remaining: 9:42:02
08:59:46.296: Pass: 4652, ExpectedFail: 29, Warn: 1, Skip: 31318, Duration: 7:44, Remaining: 9:37:31
09:00:06.599: Pass: 4771, ExpectedFail: 30, Warn: 1, Skip: 32198, Duration: 8:03, Remaining: 9:44:11
09:00:11.661: Pass: 4829, ExpectedFail: 31, Warn: 1, Skip: 32639, Duration: 8:09, Remaining: 9:43:55
09:00:21.799: Pass: 4883, ExpectedFail: 31, Warn: 1, Skip: 33085, Duration: 8:21, Remaining: 9:50:02
09:00:31.951: Pass: 4950, ExpectedFail: 31, Warn: 1, Skip: 33518, Duration: 8:24, Remaining: 9:45:54
09:00:31.951: Pass: 5020, ExpectedFail: 31, Warn: 1, Skip: 33948, Duration: 8:27, Remaining: 9:41:59
09:00:37.018: Pass: 5089, ExpectedFail: 32, Warn: 1, Skip: 34378, Duration: 8:33, Remaining: 9:41:15
09:00:47.151: Pass: 5131, ExpectedFail: 33, Warn: 1, Skip: 34835, Duration: 8:43, Remaining: 9:44:47
09:00:52.240: Pass: 5198, ExpectedFail: 33, Warn: 1, Skip: 35268, Duration: 8:51, Remaining: 9:46:15
09:00:57.305: Pass: 5327, ExpectedFail: 33, Warn: 1, Skip: 36139, Duration: 8:57, Remaining: 9:37:51
09:01:07.448: Pass: 5387, ExpectedFail: 33, Warn: 1, Skip: 36579, Duration: 9:07, Remaining: 9:41:25
09:01:17.571: Pass: 5478, ExpectedFail: 33, Warn: 1, Skip: 36988, Duration: 9:16, Remaining: 9:44:19
09:01:27.732: Pass: 5540, ExpectedFail: 34, Warn: 1, Skip: 37425, Duration: 9:18, Remaining: 9:39:35
09:01:32.793: ERROR - dEQP error: FATAL ERROR: basic_string: construction from null is not valid
09:01:37.874: Pass: 5672, ExpectedFail: 37, Warn: 1, Skip: 38290, Duration: 9:32, Remaining: 9:39:56
09:01:47.995: Pass: 5729, ExpectedFail: 37, Warn: 1, Skip: 38733, Duration: 9:40, Remaining: 9:41:22
09:01:47.995: Pass: 5847, ExpectedFail: 37, Warn: 1, Skip: 39615, Duration: 9:44, Remaining: 9:33:10
09:01:58.160: Pass: 5901, ExpectedFail: 37, Warn: 1, Skip: 40061, Duration: 9:54, Remaining: 9:36:27
09:02:08.323: Pass: 5962, ExpectedFail: 38, Warn: 1, Skip: 40499, Duration: 10:03, Remaining: 9:38:40
09:02:13.391: Pass: 6087, ExpectedFail: 38, Warn: 1, Skip: 41374, Duration: 10:10, Remaining: 9:32:57
09:02:18.474: Pass: 6158, ExpectedFail: 38, Warn: 1, Skip: 41803, Duration: 10:18, Remaining: 9:33:46
09:02:28.600: Pass: 6234, ExpectedFail: 38, Warn: 1, Skip: 42227, Duration: 10:27, Remaining: 9:35:50
09:02:38.742: Pass: 6310, ExpectedFail: 38, Warn: 1, Skip: 42651, Duration: 10:31, Remaining: 9:34:17
09:02:38.742: Pass: 6368, ExpectedFail: 38, Warn: 1, Skip: 43093, Duration: 10:36, Remaining: 9:31:59
09:02:43.801: Pass: 6433, ExpectedFail: 39, Warn: 1, Skip: 43527, Duration: 10:43, Remaining: 9:32:51
09:02:53.923: Pass: 6503, ExpectedFail: 39, Warn: 1, Skip: 43957, Duration: 10:50, Remaining: 9:33:25
09:02:58.997: Pass: 6582, ExpectedFail: 39, Warn: 1, Skip: 44378, Duration: 10:57, Remaining: 9:33:18
09:03:09.168: Pass: 6645, ExpectedFail: 40, Warn: 1, Skip: 44814, Duration: 10:59, Remaining: 9:29:43
09:03:09.168: Pass: 6705, ExpectedFail: 41, Warn: 1, Skip: 45253, Duration: 11:05, Remaining: 9:28:53
09:03:19.319: Pass: 6775, ExpectedFail: 41, Warn: 1, Skip: 45683, Duration: 11:16, Remaining: 9:32:39
09:03:24.380: Pass: 6835, ExpectedFail: 42, Warn: 1, Skip: 46122, Duration: 11:21, Remaining: 9:31:54
09:03:34.546: Pass: 6960, ExpectedFail: 44, Warn: 1, Skip: 46995, Duration: 11:29, Remaining: 9:27:47
09:03:44.675: Pass: 7031, ExpectedFail: 44, Warn: 1, Skip: 47424, Duration: 11:44, Remaining: 9:34:10
09:03:54.803: Pass: 7155, ExpectedFail: 44, Warn: 1, Skip: 48300, Duration: 11:46, Remaining: 9:25:25
09:03:59.863: Pass: 7213, ExpectedFail: 45, Warn: 1, Skip: 48741, Duration: 11:52, Remaining: 9:25:09
09:04:10.081: Pass: 7275, ExpectedFail: 46, Warn: 1, Skip: 49178, Duration: 12:08, Remaining: 9:32:31
09:04:20.255: Pass: 7470, ExpectedFail: 49, Warn: 1, Skip: 50480, Duration: 12:15, Remaining: 9:22:41
09:04:35.445: Pass: 7524, ExpectedFail: 49, Warn: 1, Skip: 50926, Duration: 12:30, Remaining: 9:29:28
09:04:40.510: Pass: 7578, ExpectedFail: 49, Warn: 1, Skip: 51372, Duration: 12:33, Remaining: 9:26:27
09:04:50.634: Pass: 7705, ExpectedFail: 49, Warn: 1, Skip: 52245, Duration: 12:41, Remaining: 9:22:28
09:04:55.698: Pass: 7757, ExpectedFail: 50, Warn: 1, Skip: 52692, Duration: 12:54, Remaining: 9:27:12
09:05:05.832: Pass: 7876, ExpectedFail: 50, Warn: 1, Skip: 53573, Duration: 12:58, Remaining: 9:20:46
09:05:10.903: Pass: 7947, ExpectedFail: 50, Warn: 1, Skip: 54002, Duration: 13:04, Remaining: 9:20:23
09:05:21.027: Pass: 8003, ExpectedFail: 50, Warn: 1, Skip: 54446, Duration: 13:17, Remaining: 9:25:05
09:05:26.095: Pass: 8138, ExpectedFail: 50, Warn: 1, Skip: 55311, Duration: 13:22, Remaining: 9:19:39
09:05:36.224: Pass: 8202, ExpectedFail: 51, Warn: 1, Skip: 55746, Duration: 13:28, Remaining: 9:19:08
09:05:46.346: Pass: 8260, ExpectedFail: 53, Warn: 1, Skip: 56186, Duration: 13:41, Remaining: 9:23:51
09:05:51.412: Pass: 8313, ExpectedFail: 53, Warn: 1, Skip: 56633, Duration: 13:44, Remaining: 9:21:32
09:06:01.574: Pass: 8459, ExpectedFail: 54, Warn: 1, Skip: 57486, Duration: 13:53, Remaining: 9:18:40
09:06:11.702: Pass: 8538, ExpectedFail: 55, Warn: 1, Skip: 57906, Duration: 14:08, Remaining: 9:24:06
09:06:21.870: Pass: 8606, ExpectedFail: 56, Warn: 1, Skip: 58337, Duration: 14:10, Remaining: 9:21:09
09:06:21.870: Pass: 8663, ExpectedFail: 56, Warn: 1, Skip: 58780, Duration: 14:12, Remaining: 9:18:39
09:06:26.933: Pass: 8728, ExpectedFail: 56, Warn: 1, Skip: 59215, Duration: 14:19, Remaining: 9:18:27
09:06:31.993: Pass: 8789, ExpectedFail: 56, Warn: 1, Skip: 59654, Duration: 14:31, Remaining: 9:22:09
09:06:37.054: Pass: 8860, ExpectedFail: 56, Warn: 1, Skip: 60083, Duration: 14:34, Remaining: 9:19:51
09:06:37.054: Pass: 8926, ExpectedFail: 56, Warn: 1, Skip: 60517, Duration: 14:36, Remaining: 9:17:31
09:06:52.254: Pass: 8997, ExpectedFail: 57, Warn: 1, Skip: 60945, Duration: 14:49, Remaining: 9:21:27
09:06:57.314: Pass: 9061, ExpectedFail: 58, Warn: 1, Skip: 61380, Duration: 14:56, Remaining: 9:21:42
09:07:07.455: Pass: 9200, ExpectedFail: 60, Warn: 1, Skip: 62239, Duration: 15:00, Remaining: 9:16:18
09:07:17.572: Pass: 9258, ExpectedFail: 63, Warn: 1, Skip: 62678, Duration: 15:14, Remaining: 9:20:24
09:07:22.632: Pass: 9325, ExpectedFail: 63, Warn: 1, Skip: 63111, Duration: 15:20, Remaining: 9:20:14
09:07:32.762: Pass: 9381, ExpectedFail: 63, Warn: 1, Skip: 63555, Duration: 15:23, Remaining: 9:18:30
09:07:37.825: ERROR - dEQP error: deqp-vk: ../src/panfrost/vulkan/jm/panvk_vX_cmd_dispatch.c:188: panvk_v7_CmdDispatchIndirect: Assertion `!"stub"' failed.
09:07:47.954: Pass: 9536, ExpectedFail: 65, Warn: 1, Skip: 64398, Duration: 15:39, Remaining: 9:20:14
09:07:58.075: Pass: 9599, ExpectedFail: 65, Warn: 1, Skip: 64835, Duration: 15:45, Remaining: 9:19:30
09:07:58.075: Pass: 9662, ExpectedFail: 66, Warn: 1, Skip: 65271, Duration: 15:47, Remaining: 9:16:59
09:07:58.075: Pass: 9724, ExpectedFail: 66, Warn: 1, Skip: 65709, Duration: 15:49, Remaining: 9:14:30
09:08:08.209: Pass: 9802, ExpectedFail: 67, Warn: 1, Skip: 66130, Duration: 16:06, Remaining: 9:20:25
09:08:18.347: Pass: 9880, ExpectedFail: 69, Warn: 1, Skip: 66550, Duration: 16:08, Remaining: 9:18:07
09:08:18.348: Pass: 9937, ExpectedFail: 69, Warn: 1, Skip: 66993, Duration: 16:10, Remaining: 9:15:35
09:08:33.534: Pass: 10069, ExpectedFail: 70, Warn: 1, Skip: 67860, Duration: 16:28, Remaining: 9:18:20
09:08:38.596: Pass: 10132, ExpectedFail: 70, Warn: 1, Skip: 68297, Duration: 16:31, Remaining: 9:16:18
09:08:38.596: Pass: 10197, ExpectedFail: 70, Warn: 1, Skip: 68732, Duration: 16:34, Remaining: 9:14:14
09:08:43.655: Pass: 10277, ExpectedFail: 70, Warn: 1, Skip: 69152, Duration: 16:37, Remaining: 9:12:26
09:08:58.831: Pass: 10338, ExpectedFail: 70, Warn: 1, Skip: 69591, Duration: 16:55, Remaining: 9:18:45
09:09:03.916: Pass: 10402, ExpectedFail: 70, Warn: 1, Skip: 70027, Duration: 16:58, Remaining: 9:16:45
09:09:03.916: Pass: 10456, ExpectedFail: 70, Warn: 1, Skip: 70473, Duration: 17:01, Remaining: 9:14:45
09:09:14.042: Pass: 10517, ExpectedFail: 70, Warn: 1, Skip: 70912, Duration: 17:05, Remaining: 9:13:37
09:09:24.167: Pass: 10571, ExpectedFail: 71, Warn: 1, Skip: 71357, Duration: 17:20, Remaining: 9:18:03
09:09:29.244: Pass: 10707, ExpectedFail: 71, Warn: 1, Skip: 72221, Duration: 17:24, Remaining: 9:13:27
09:09:29.244: Pass: 10772, ExpectedFail: 71, Warn: 1, Skip: 72656, Duration: 17:28, Remaining: 9:12:08
09:09:44.473: Pass: 10824, ExpectedFail: 71, Warn: 1, Skip: 73104, Duration: 17:44, Remaining: 9:16:41
09:09:54.605: Pass: 10887, ExpectedFail: 71, Warn: 1, Skip: 73541, Duration: 17:47, Remaining: 9:15:13
09:09:54.605: Pass: 11025, ExpectedFail: 72, Warn: 1, Skip: 74402, Duration: 17:52, Remaining: 9:10:40
09:10:09.789: Pass: 11096, ExpectedFail: 73, Warn: 1, Skip: 74830, Duration: 18:07, Remaining: 9:15:14
09:10:19.961: Pass: 11145, ExpectedFail: 73, Warn: 1, Skip: 75281, Duration: 18:11, Remaining: 9:14:01
09:10:19.961: Pass: 11292, ExpectedFail: 73, Warn: 1, Skip: 76134, Duration: 18:16, Remaining: 9:09:53
09:10:35.164: Pass: 11354, ExpectedFail: 73, Warn: 1, Skip: 76572, Duration: 18:31, Remaining: 9:13:56
09:10:40.349: ERROR - dEQP error: deqp-vk: ../src/panfrost/vulkan/jm/panvk_vX_cmd_dispatch.c:188: panvk_v7_CmdDispatchIndirect: Assertion `!"stub"' failed.
09:10:40.349: Pass: 11413, ExpectedFail: 76, Warn: 1, Skip: 77010, Duration: 18:35, Remaining: 9:12:48
09:10:50.488: Pass: 11530, ExpectedFail: 79, Warn: 1, Skip: 77890, Duration: 18:42, Remaining: 9:10:10
09:10:55.550: Pass: 11594, ExpectedFail: 79, Warn: 1, Skip: 78326, Duration: 18:55, Remaining: 9:13:04
09:11:05.750: Pass: 11654, ExpectedFail: 81, Warn: 1, Skip: 78764, Duration: 19:01, Remaining: 9:12:39
09:11:10.829: Pass: 11772, ExpectedFail: 84, Warn: 1, Skip: 79643, Duration: 19:06, Remaining: 9:09:02
09:11:31.080: Pass: 11841, ExpectedFail: 85, Warn: 1, Skip: 80073, Duration: 19:29, Remaining: 9:17:01
09:11:36.151: Pass: 11968, ExpectedFail: 86, Warn: 1, Skip: 80945, Duration: 19:35, Remaining: 9:13:35
09:11:46.292: Pass: 12022, ExpectedFail: 87, Warn: 1, Skip: 81390, Duration: 19:40, Remaining: 9:12:48
09:11:56.454: Pass: 12077, ExpectedFail: 88, Warn: 1, Skip: 81834, Duration: 19:52, Remaining: 9:15:23
09:12:01.549: ERROR - dEQP error: deqp-vk: ../src/panfrost/vulkan/jm/panvk_vX_cmd_dispatch.c:188: panvk_v7_CmdDispatchIndirect: Assertion `!"stub"' failed.
09:12:11.685: Pass: 12151, ExpectedFail: 90, Warn: 1, Skip: 82258, Duration: 20:02, Remaining: 9:17:05
09:12:16.752: Pass: 12224, ExpectedFail: 90, Warn: 1, Skip: 82685, Duration: 20:08, Remaining: 9:16:33
09:12:21.812: Pass: 12364, ExpectedFail: 90, Warn: 1, Skip: 83545, Duration: 20:15, Remaining: 9:13:46
09:12:26.874: Pass: 12438, ExpectedFail: 90, Warn: 1, Skip: 83971, Duration: 20:26, Remaining: 9:16:02
09:12:37.003: Pass: 12502, ExpectedFail: 91, Warn: 1, Skip: 84406, Duration: 20:31, Remaining: 9:15:10
09:12:42.066: Pass: 12567, ExpectedFail: 91, Warn: 1, Skip: 84841, Duration: 20:33, Remaining: 9:13:17
09:12:47.127: Pass: 12627, ExpectedFail: 92, Warn: 1, Skip: 85280, Duration: 20:40, Remaining: 9:13:07
09:12:52.189: Pass: 12694, ExpectedFail: 93, Warn: 1, Skip: 85712, Duration: 20:50, Remaining: 9:14:56
09:13:02.339: Pass: 12756, ExpectedFail: 93, Warn: 1, Skip: 86150, Duration: 20:57, Remaining: 9:14:56
09:13:07.408: Pass: 12890, ExpectedFail: 93, Warn: 1, Skip: 87016, Duration: 21:03, Remaining: 9:11:53
09:13:17.537: Pass: 12951, ExpectedFail: 93, Warn: 1, Skip: 87455, Duration: 21:16, Remaining: 9:14:25
09:13:27.712: Pass: 13011, ExpectedFail: 93, Warn: 1, Skip: 87895, Duration: 21:20, Remaining: 9:13:28
09:13:42.930: Pass: 13135, ExpectedFail: 93, Warn: 1, Skip: 88771, Duration: 21:39, Remaining: 9:16:02
09:13:47.996: Pass: 13192, ExpectedFail: 94, Warn: 1, Skip: 89213, Duration: 21:45, Remaining: 9:15:47
09:13:53.066: Pass: 13260, ExpectedFail: 94, Warn: 1, Skip: 89645, Duration: 21:48, Remaining: 9:14:06
09:13:53.066: Pass: 13321, ExpectedFail: 94, Warn: 1, Skip: 90084, Duration: 21:53, Remaining: 9:13:21
09:14:08.266: Pass: 13402, ExpectedFail: 94, Warn: 1, Skip: 90503, Duration: 22:06, Remaining: 9:16:24
09:14:18.397: Pass: 13523, ExpectedFail: 96, Warn: 1, Skip: 91380, Duration: 22:11, Remaining: 9:12:49
09:14:18.397: Pass: 13583, ExpectedFail: 96, Warn: 1, Skip: 91820, Duration: 22:15, Remaining: 9:11:55
09:14:33.582: Pass: 13642, ExpectedFail: 96, Warn: 1, Skip: 92261, Duration: 22:29, Remaining: 9:14:53
09:14:38.638: Pass: 13709, ExpectedFail: 96, Warn: 1, Skip: 92694, Duration: 22:33, Remaining: 9:13:31
09:14:38.638: Pass: 13781, ExpectedFail: 97, Warn: 1, Skip: 93121, Duration: 22:35, Remaining: 9:11:57
09:14:48.766: Pass: 13853, ExpectedFail: 97, Warn: 1, Skip: 93549, Duration: 22:39, Remaining: 9:10:44
09:14:53.828: Pass: 13915, ExpectedFail: 99, Warn: 1, Skip: 93985, Duration: 22:53, Remaining: 9:13:49
09:15:04.014: Pass: 13979, ExpectedFail: 100, Warn: 1, Skip: 94420, Duration: 22:56, Remaining: 9:12:17
09:15:04.014: Pass: 14053, ExpectedFail: 100, Warn: 1, Skip: 94846, Duration: 22:59, Remaining: 9:11:02
09:15:09.094: Pass: 14108, ExpectedFail: 100, Warn: 1, Skip: 95291, Duration: 23:02, Remaining: 9:09:32
09:15:19.218: Pass: 14177, ExpectedFail: 100, Warn: 1, Skip: 95722, Duration: 23:16, Remaining: 9:12:28
09:15:29.348: Pass: 14243, ExpectedFail: 100, Warn: 1, Skip: 96156, Duration: 23:19, Remaining: 9:10:55
09:15:34.417: Pass: 14319, ExpectedFail: 100, Warn: 1, Skip: 96580, Duration: 23:24, Remaining: 9:10:27
09:15:34.417: Pass: 14378, ExpectedFail: 103, Warn: 1, Skip: 97018, Duration: 23:27, Remaining: 9:09:05
09:15:44.576: Pass: 14434, ExpectedFail: 103, Warn: 1, Skip: 97462, Duration: 23:40, Remaining: 9:11:15
09:15:49.633: Pass: 14496, ExpectedFail: 104, Warn: 1, Skip: 97899, Duration: 23:43, Remaining: 9:09:57
09:15:49.633: Pass: 14567, ExpectedFail: 105, Warn: 1, Skip: 98327, Duration: 23:48, Remaining: 9:09:18
09:15:59.753: Pass: 14643, ExpectedFail: 105, Warn: 1, Skip: 98751, Duration: 23:52, Remaining: 9:08:24
09:16:04.811: Pass: 14713, ExpectedFail: 105, Warn: 1, Skip: 99181, Duration: 24:03, Remaining: 9:10:12
09:16:14.982: Pass: 14782, ExpectedFail: 105, Warn: 1, Skip: 99612, Duration: 24:07, Remaining: 9:09:11
09:16:14.982: Pass: 14844, ExpectedFail: 105, Warn: 1, Skip: 100050, Duration: 24:11, Remaining: 9:08:08
09:16:20.049: Pass: 14896, ExpectedFail: 106, Warn: 1, Skip: 100497, Duration: 24:18, Remaining: 9:08:15
09:16:30.177: Pass: 14967, ExpectedFail: 106, Warn: 1, Skip: 100926, Duration: 24:27, Remaining: 9:09:03
09:16:35.247: Pass: 15033, ExpectedFail: 106, Warn: 1, Skip: 101360, Duration: 24:32, Remaining: 9:08:29
09:16:45.406: Pass: 15105, ExpectedFail: 106, Warn: 1, Skip: 101788, Duration: 24:37, Remaining: 9:07:48
09:16:50.471: Pass: 15169, ExpectedFail: 107, Warn: 1, Skip: 102223, Duration: 24:43, Remaining: 9:07:34
09:16:55.564: Pass: 15223, ExpectedFail: 107, Warn: 1, Skip: 102669, Duration: 24:48, Remaining: 9:07:15
09:17:00.629: Pass: 15277, ExpectedFail: 107, Warn: 1, Skip: 103115, Duration: 24:55, Remaining: 9:07:24
09:17:00.629: Pass: 15338, ExpectedFail: 107, Warn: 1, Skip: 103554, Duration: 24:59, Remaining: 9:06:19
09:17:15.818: Pass: 15392, ExpectedFail: 109, Warn: 1, Skip: 103998, Duration: 25:12, Remaining: 9:08:35
09:17:20.889: Pass: 15453, ExpectedFail: 110, Warn: 1, Skip: 104436, Duration: 25:20, Remaining: 9:09:07
09:17:31.025: Pass: 15513, ExpectedFail: 110, Warn: 1, Skip: 104876, Duration: 25:27, Remaining: 9:09:19
09:17:36.083: Pass: 15564, ExpectedFail: 111, Warn: 1, Skip: 105324, Duration: 25:33, Remaining: 9:09:10
09:17:41.162: Pass: 15626, ExpectedFail: 112, Warn: 1, Skip: 105761, Duration: 25:39, Remaining: 9:08:47
09:17:56.370: Pass: 15689, ExpectedFail: 114, Warn: 1, Skip: 106196, Duration: 25:54, Remaining: 9:11:44
09:18:06.529: Pass: 15757, ExpectedFail: 114, Warn: 1, Skip: 106628, Duration: 25:57, Remaining: 9:10:22
09:18:11.595: Pass: 15814, ExpectedFail: 114, Warn: 1, Skip: 107071, Duration: 26:10, Remaining: 9:12:41
09:18:21.721: Pass: 15882, ExpectedFail: 114, Warn: 1, Skip: 107503, Duration: 26:17, Remaining: 9:12:58
09:18:26.783: Pass: 15949, ExpectedFail: 114, Warn: 1, Skip: 107936, Duration: 26:20, Remaining: 9:11:38
09:18:36.917: Pass: 16023, ExpectedFail: 114, Warn: 1, Skip: 108362, Duration: 26:34, Remaining: 9:14:03
09:18:41.983: Pass: 16085, ExpectedFail: 117, Warn: 1, Skip: 108797, Duration: 26:40, Remaining: 9:13:57
09:18:52.117: Pass: 16152, ExpectedFail: 119, Warn: 1, Skip: 109228, Duration: 26:44, Remaining: 9:12:46
09:19:02.257: Pass: 16279, ExpectedFail: 120, Warn: 1, Skip: 110100, Duration: 26:57, Remaining: 9:12:56
09:19:07.343: Pass: 16345, ExpectedFail: 121, Warn: 1, Skip: 110533, Duration: 27:02, Remaining: 9:12:13
09:19:17.465: Pass: 16413, ExpectedFail: 122, Warn: 1, Skip: 110964, Duration: 27:08, Remaining: 9:11:50
09:19:17.465: Pass: 16483, ExpectedFail: 122, Warn: 1, Skip: 111394, Duration: 27:11, Remaining: 9:10:50
09:19:27.601: Pass: 16544, ExpectedFail: 123, Warn: 1, Skip: 111832, Duration: 27:21, Remaining: 9:11:53
09:19:27.601: Pass: 16607, ExpectedFail: 123, Warn: 1, Skip: 112269, Duration: 27:25, Remaining: 9:10:57
09:19:37.860: Pass: 16680, ExpectedFail: 124, Warn: 1, Skip: 112695, Duration: 27:33, Remaining: 9:11:18
09:19:48.005: Pass: 16805, ExpectedFail: 124, Warn: 1, Skip: 113570, Duration: 27:45, Remaining: 9:10:57
09:19:53.072: Pass: 16879, ExpectedFail: 124, Warn: 1, Skip: 113996, Duration: 27:51, Remaining: 9:10:33
09:19:58.145: Pass: 16940, ExpectedFail: 124, Warn: 1, Skip: 114435, Duration: 27:56, Remaining: 9:10:01
09:20:13.465: Pass: 17072, ExpectedFail: 125, Warn: 1, Skip: 115302, Duration: 28:09, Remaining: 9:09:56
09:20:18.533: Pass: 17133, ExpectedFail: 126, Warn: 1, Skip: 115740, Duration: 28:15, Remaining: 9:09:47
09:20:28.677: Pass: 17201, ExpectedFail: 126, Warn: 1, Skip: 116172, Duration: 28:19, Remaining: 9:09:01
09:20:33.733: Pass: 17325, ExpectedFail: 126, Warn: 1, Skip: 117048, Duration: 28:33, Remaining: 9:09:09
09:20:43.862: Pass: 17388, ExpectedFail: 128, Warn: 1, Skip: 117483, Duration: 28:42, Remaining: 9:09:50
09:20:53.987: Pass: 17462, ExpectedFail: 128, Warn: 1, Skip: 117909, Duration: 28:46, Remaining: 9:08:49
09:20:53.987: Pass: 17530, ExpectedFail: 128, Warn: 1, Skip: 118341, Duration: 28:50, Remaining: 9:07:56
09:20:59.048: Pass: 17601, ExpectedFail: 128, Warn: 1, Skip: 118770, Duration: 28:57, Remaining: 9:08:10
09:21:09.195: Pass: 17662, ExpectedFail: 129, Warn: 1, Skip: 119208, Duration: 29:05, Remaining: 9:08:28
09:21:14.260: Pass: 17721, ExpectedFail: 129, Warn: 1, Skip: 119649, Duration: 29:09, Remaining: 9:07:54
09:21:14.260: Pass: 17779, ExpectedFail: 130, Warn: 1, Skip: 120090, Duration: 29:13, Remaining: 9:06:49
09:21:19.324: Pass: 17843, ExpectedFail: 130, Warn: 1, Skip: 120526, Duration: 29:19, Remaining: 9:06:33
09:21:29.453: Pass: 17908, ExpectedFail: 131, Warn: 1, Skip: 120960, Duration: 29:28, Remaining: 9:07:21
09:21:34.512: Pass: 17973, ExpectedFail: 133, Warn: 1, Skip: 121393, Duration: 29:34, Remaining: 9:07:05
09:21:44.649: Pass: 18039, ExpectedFail: 134, Warn: 1, Skip: 121826, Duration: 29:36, Remaining: 9:05:43
09:21:49.717: Pass: 18106, ExpectedFail: 135, Warn: 1, Skip: 122258, Duration: 29:43, Remaining: 9:05:49
09:22:04.936: Pass: 18166, ExpectedFail: 136, Warn: 1, Skip: 122697, Duration: 30:02, Remaining: 9:09:35
09:22:09.999: Pass: 18315, ExpectedFail: 137, Warn: 1, Skip: 123547, Duration: 30:08, Remaining: 9:07:14
09:22:30.272: Pass: 18447, ExpectedFail: 139, Warn: 1, Skip: 124413, Duration: 30:26, Remaining: 9:08:36
09:22:40.405: Pass: 18522, ExpectedFail: 140, Warn: 1, Skip: 124837, Duration: 30:28, Remaining: 9:07:19
09:22:40.405: Pass: 18581, ExpectedFail: 140, Warn: 1, Skip: 125278, Duration: 30:31, Remaining: 9:06:10
09:22:40.405: Pass: 18646, ExpectedFail: 140, Warn: 1, Skip: 125713, Duration: 30:36, Remaining: 9:05:36
09:22:50.543: Pass: 18699, ExpectedFail: 140, Warn: 1, Skip: 126160, Duration: 30:50, Remaining: 9:07:52
09:23:00.685: Pass: 18822, ExpectedFail: 140, Warn: 1, Skip: 127037, Duration: 30:55, Remaining: 9:05:15
09:23:00.685: Pass: 18893, ExpectedFail: 140, Warn: 1, Skip: 127466, Duration: 30:59, Remaining: 9:04:33
09:23:15.869: Pass: 18953, ExpectedFail: 141, Warn: 1, Skip: 127905, Duration: 31:14, Remaining: 9:07:00
09:23:25.993: Pass: 19081, ExpectedFail: 141, Warn: 1, Skip: 128777, Duration: 31:19, Remaining: 9:04:24
09:23:25.994: Pass: 19141, ExpectedFail: 142, Warn: 1, Skip: 129216, Duration: 31:23, Remaining: 9:03:48
09:23:41.215: Pass: 19201, ExpectedFail: 142, Warn: 1, Skip: 129656, Duration: 31:38, Remaining: 9:05:57
09:23:46.275: Pass: 19347, ExpectedFail: 142, Warn: 1, Skip: 130510, Duration: 31:42, Remaining: 9:03:30
09:23:56.390: Pass: 19415, ExpectedFail: 142, Warn: 1, Skip: 130942, Duration: 31:46, Remaining: 9:02:38
09:24:06.535: Pass: 19469, ExpectedFail: 143, Warn: 1, Skip: 131387, Duration: 32:03, Remaining: 9:05:34
09:24:11.598: Pass: 19530, ExpectedFail: 144, Warn: 1, Skip: 131825, Duration: 32:05, Remaining: 9:04:15
09:24:11.598: Pass: 19608, ExpectedFail: 144, Warn: 1, Skip: 132247, Duration: 32:08, Remaining: 9:03:09
09:24:11.598: Pass: 19673, ExpectedFail: 144, Warn: 1, Skip: 132682, Duration: 32:10, Remaining: 9:01:52
09:24:31.830: Pass: 19749, ExpectedFail: 144, Warn: 1, Skip: 133106, Duration: 32:28, Remaining: 9:04:52
09:24:36.890: Pass: 19890, ExpectedFail: 147, Warn: 1, Skip: 133962, Duration: 32:33, Remaining: 9:02:40
09:24:47.008: Pass: 19954, ExpectedFail: 149, Warn: 1, Skip: 134396, Duration: 32:37, Remaining: 9:01:50
09:24:57.139: Pass: 20020, ExpectedFail: 150, Warn: 1, Skip: 134829, Duration: 32:52, Remaining: 9:04:16
09:25:02.318: Pass: 20148, ExpectedFail: 152, Warn: 1, Skip: 135699, Duration: 32:56, Remaining: 9:01:26
09:25:07.383: Pass: 20210, ExpectedFail: 152, Warn: 1, Skip: 136137, Duration: 33:01, Remaining: 9:00:59
09:25:22.566: Pass: 20261, ExpectedFail: 153, Warn: 1, Skip: 136585, Duration: 33:19, Remaining: 9:04:09
09:25:32.725: Pass: 20383, ExpectedFail: 156, Warn: 1, Skip: 137460, Duration: 33:22, Remaining: 9:01:11
09:25:42.870: Pass: 20520, ExpectedFail: 157, Warn: 1, Skip: 138322, Duration: 33:42, Remaining: 9:03:10
09:25:53.063: Pass: 20637, ExpectedFail: 160, Warn: 1, Skip: 139202, Duration: 33:45, Remaining: 9:00:16
09:25:53.063: Pass: 20716, ExpectedFail: 160, Warn: 1, Skip: 139623, Duration: 33:50, Remaining: 8:59:47
09:26:08.241: Pass: 20783, ExpectedFail: 161, Warn: 1, Skip: 140055, Duration: 34:06, Remaining: 9:02:10
09:26:18.407: Pass: 20981, ExpectedFail: 161, Warn: 1, Skip: 141357, Duration: 34:14, Remaining: 8:58:59
09:26:33.599: Pass: 21039, ExpectedFail: 161, Warn: 1, Skip: 141799, Duration: 34:30, Remaining: 9:01:21
09:26:38.664: Pass: 21186, ExpectedFail: 163, Warn: 1, Skip: 142650, Duration: 34:38, Remaining: 8:59:55
09:26:48.783: Pass: 21249, ExpectedFail: 164, Warn: 1, Skip: 143086, Duration: 34:44, Remaining: 8:59:41
09:26:53.846: Pass: 21307, ExpectedFail: 166, Warn: 1, Skip: 143526, Duration: 34:52, Remaining: 9:00:10
09:27:03.964: Pass: 21376, ExpectedFail: 167, Warn: 1, Skip: 143956, Duration: 35:02, Remaining: 9:00:52
09:27:09.078: Pass: 21441, ExpectedFail: 169, Warn: 1, Skip: 144389, Duration: 35:08, Remaining: 9:00:50
09:27:14.180: Pass: 21491, ExpectedFail: 169, Warn: 1, Skip: 144839, Duration: 35:13, Remaining: 9:00:23
09:27:24.297: Pass: 21545, ExpectedFail: 169, Warn: 1, Skip: 145285, Duration: 35:22, Remaining: 9:00:52
09:27:34.438: Pass: 21618, ExpectedFail: 171, Warn: 1, Skip: 145710, Duration: 35:26, Remaining: 9:00:14
09:27:39.503: Pass: 21689, ExpectedFail: 171, Warn: 1, Skip: 146139, Duration: 35:36, Remaining: 9:01:07
09:27:49.624: Pass: 21753, ExpectedFail: 171, Warn: 1, Skip: 146575, Duration: 35:44, Remaining: 9:01:27
09:27:59.747: Pass: 21890, ExpectedFail: 171, Warn: 1, Skip: 147438, Duration: 35:50, Remaining: 8:59:19
09:28:04.804: Pass: 21942, ExpectedFail: 171, Warn: 1, Skip: 147886, Duration: 35:58, Remaining: 8:59:51
09:28:09.868: Pass: 22001, ExpectedFail: 171, Warn: 1, Skip: 148327, Duration: 36:09, Remaining: 9:00:54
09:28:19.995: Pass: 22074, ExpectedFail: 172, Warn: 1, Skip: 148753, Duration: 36:14, Remaining: 9:00:16
09:28:30.131: Pass: 22137, ExpectedFail: 172, Warn: 1, Skip: 149190, Duration: 36:21, Remaining: 9:00:28
09:28:35.191: Pass: 22206, ExpectedFail: 172, Warn: 1, Skip: 149621, Duration: 36:27, Remaining: 9:00:05
09:28:40.255: Pass: 22267, ExpectedFail: 172, Warn: 1, Skip: 150060, Duration: 36:32, Remaining: 8:59:47
09:28:45.312: Pass: 22324, ExpectedFail: 172, Warn: 1, Skip: 150503, Duration: 36:39, Remaining: 8:59:52
09:28:50.383: Pass: 22395, ExpectedFail: 172, Warn: 1, Skip: 150932, Duration: 36:45, Remaining: 8:59:33
09:29:00.515: Pass: 22469, ExpectedFail: 172, Warn: 1, Skip: 151358, Duration: 36:51, Remaining: 8:59:24
09:29:00.515: Pass: 22535, ExpectedFail: 172, Warn: 1, Skip: 151792, Duration: 36:56, Remaining: 8:59:02
09:29:05.581: Pass: 22599, ExpectedFail: 172, Warn: 1, Skip: 152228, Duration: 37:03, Remaining: 8:58:57
09:29:10.640: Pass: 22658, ExpectedFail: 172, Warn: 1, Skip: 152669, Duration: 37:08, Remaining: 8:58:31
09:29:15.704: Pass: 22721, ExpectedFail: 175, Warn: 1, Skip: 153103, Duration: 37:14, Remaining: 8:58:32
09:29:20.766: Pass: 22791, ExpectedFail: 175, Warn: 1, Skip: 153533, Duration: 37:20, Remaining: 8:58:09
09:29:30.890: Pass: 22857, ExpectedFail: 175, Warn: 1, Skip: 153967, Duration: 37:27, Remaining: 8:58:21
09:29:41.024: Pass: 22930, ExpectedFail: 175, Warn: 1, Skip: 154394, Duration: 37:32, Remaining: 8:57:45
09:29:46.084: Pass: 22995, ExpectedFail: 175, Warn: 1, Skip: 154829, Duration: 37:38, Remaining: 8:57:44
09:29:46.084: Pass: 23065, ExpectedFail: 176, Warn: 1, Skip: 155258, Duration: 37:43, Remaining: 8:57:18
09:29:56.222: Pass: 23123, ExpectedFail: 176, Warn: 1, Skip: 155700, Duration: 37:51, Remaining: 8:57:35
09:30:01.303: Pass: 23195, ExpectedFail: 178, Warn: 1, Skip: 156126, Duration: 37:56, Remaining: 8:57:02
09:30:11.436: Pass: 23256, ExpectedFail: 178, Warn: 1, Skip: 156565, Duration: 38:06, Remaining: 8:57:52
09:30:16.507: Pass: 23321, ExpectedFail: 178, Warn: 1, Skip: 157000, Duration: 38:10, Remaining: 8:57:14
09:30:26.656: Pass: 23391, ExpectedFail: 179, Warn: 1, Skip: 157429, Duration: 38:15, Remaining: 8:56:51
09:30:26.656: Pass: 23470, ExpectedFail: 180, Warn: 1, Skip: 157849, Duration: 38:19, Remaining: 8:56:01
09:30:31.733: Pass: 23555, ExpectedFail: 180, Warn: 1, Skip: 158264, Duration: 38:31, Remaining: 8:57:15
09:30:41.875: Pass: 23613, ExpectedFail: 180, Warn: 1, Skip: 158706, Duration: 38:39, Remaining: 8:57:31
09:30:46.941: Pass: 23680, ExpectedFail: 180, Warn: 1, Skip: 159139, Duration: 38:44, Remaining: 8:57:14
09:31:07.210: Pass: 23816, ExpectedFail: 181, Warn: 1, Skip: 160002, Duration: 39:02, Remaining: 8:58:18
09:31:17.340: Pass: 23879, ExpectedFail: 181, Warn: 1, Skip: 160439, Duration: 39:07, Remaining: 8:57:49
09:31:27.478: Pass: 24061, ExpectedFail: 183, Warn: 1, Skip: 161755, Duration: 39:26, Remaining: 8:57:27
09:31:37.600: Pass: 24128, ExpectedFail: 183, Warn: 1, Skip: 162188, Duration: 39:30, Remaining: 8:56:48
09:31:37.600: Pass: 24264, ExpectedFail: 183, Warn: 1, Skip: 163052, Duration: 39:34, Remaining: 8:54:35
09:31:52.790: Pass: 24325, ExpectedFail: 183, Warn: 1, Skip: 163491, Duration: 39:50, Remaining: 8:56:44
09:32:02.948: Pass: 24384, ExpectedFail: 183, Warn: 1, Skip: 163932, Duration: 39:54, Remaining: 8:56:06
09:32:08.010: Pass: 24521, ExpectedFail: 183, Warn: 1, Skip: 164795, Duration: 39:59, Remaining: 8:54:10
09:32:18.135: Pass: 24583, ExpectedFail: 184, Warn: 1, Skip: 165232, Duration: 40:13, Remaining: 8:55:50
09:32:23.195: Pass: 24655, ExpectedFail: 184, Warn: 1, Skip: 165660, Duration: 40:17, Remaining: 8:55:07
09:32:23.195: Pass: 24722, ExpectedFail: 185, Warn: 1, Skip: 166092, Duration: 40:19, Remaining: 8:54:08
09:32:33.310: Pass: 24795, ExpectedFail: 186, Warn: 1, Skip: 166518, Duration: 40:24, Remaining: 8:53:40
09:32:38.368: Pass: 24865, ExpectedFail: 186, Warn: 1, Skip: 166948, Duration: 40:37, Remaining: 8:54:55
09:32:48.486: Pass: 24918, ExpectedFail: 186, Warn: 1, Skip: 167395, Duration: 40:40, Remaining: 8:54:13
09:33:03.671: Pass: 25027, ExpectedFail: 188, Warn: 1, Skip: 168284, Duration: 40:59, Remaining: 8:55:25
09:33:08.740: Pass: 25099, ExpectedFail: 188, Warn: 1, Skip: 168712, Duration: 41:03, Remaining: 8:54:44
09:33:08.740: Pass: 25172, ExpectedFail: 188, Warn: 1, Skip: 169139, Duration: 41:06, Remaining: 8:53:58
09:33:23.925: Pass: 25232, ExpectedFail: 188, Warn: 1, Skip: 169579, Duration: 41:19, Remaining: 8:55:17
09:33:34.049: Pass: 25298, ExpectedFail: 188, Warn: 1, Skip: 170013, Duration: 41:24, Remaining: 8:54:47
09:33:39.110: Pass: 25350, ExpectedFail: 188, Warn: 1, Skip: 170461, Duration: 41:29, Remaining: 8:54:25
09:33:44.171: Pass: 25416, ExpectedFail: 188, Warn: 1, Skip: 170895, Duration: 41:43, Remaining: 8:56:03
09:33:54.287: Pass: 25474, ExpectedFail: 189, Warn: 1, Skip: 171336, Duration: 41:47, Remaining: 8:55:22
09:33:54.287: Pass: 25528, ExpectedFail: 190, Warn: 1, Skip: 171781, Duration: 41:51, Remaining: 8:54:45
09:34:09.502: Pass: 25606, ExpectedFail: 190, Warn: 1, Skip: 172203, Duration: 42:08, Remaining: 8:56:48
09:34:34.827: Pass: 25733, ExpectedFail: 190, Warn: 1, Skip: 173076, Duration: 42:31, Remaining: 8:58:46
09:34:39.893: Pass: 25791, ExpectedFail: 190, Warn: 1, Skip: 173518, Duration: 42:34, Remaining: 8:57:56
09:35:00.142: Pass: 25937, ExpectedFail: 190, Warn: 1, Skip: 174372, Duration: 42:55, Remaining: 8:59:33
09:35:10.271: Pass: 26061, ExpectedFail: 190, Warn: 1, Skip: 175248, Duration: 43:05, Remaining: 8:58:40
09:35:20.418: Pass: 26131, ExpectedFail: 190, Warn: 1, Skip: 175678, Duration: 43:16, Remaining: 8:59:35
09:35:25.504: Pass: 26205, ExpectedFail: 190, Warn: 1, Skip: 176104, Duration: 43:25, Remaining: 8:59:52
09:35:35.623: Pass: 26276, ExpectedFail: 192, Warn: 1, Skip: 176531, Duration: 43:27, Remaining: 8:58:56
09:35:35.623: Pass: 26335, ExpectedFail: 192, Warn: 1, Skip: 176972, Duration: 43:29, Remaining: 8:57:55
09:35:45.740: Pass: 26402, ExpectedFail: 192, Warn: 1, Skip: 177405, Duration: 43:41, Remaining: 8:58:55
09:35:50.824: Pass: 26459, ExpectedFail: 193, Warn: 1, Skip: 177847, Duration: 43:48, Remaining: 8:59:00
09:35:55.886: Pass: 26520, ExpectedFail: 193, Warn: 1, Skip: 178286, Duration: 43:51, Remaining: 8:58:11
09:35:55.886: Pass: 26586, ExpectedFail: 196, Warn: 1, Skip: 178717, Duration: 43:53, Remaining: 8:57:14
09:36:06.003: Pass: 26648, ExpectedFail: 197, Warn: 1, Skip: 179154, Duration: 44:04, Remaining: 8:57:58
09:36:16.153: Pass: 26710, ExpectedFail: 197, Warn: 1, Skip: 179592, Duration: 44:15, Remaining: 8:58:48
09:36:26.279: Pass: 26847, ExpectedFail: 198, Warn: 1, Skip: 180454, Duration: 44:18, Remaining: 8:56:37
09:36:36.407: Pass: 26901, ExpectedFail: 198, Warn: 1, Skip: 180900, Duration: 44:27, Remaining: 8:57:04
09:36:41.491: Pass: 26969, ExpectedFail: 199, Warn: 1, Skip: 181331, Duration: 44:39, Remaining: 8:57:59
09:36:46.717: Pass: 27039, ExpectedFail: 199, Warn: 1, Skip: 181761, Duration: 44:41, Remaining: 8:57:03
09:36:56.839: Pass: 27169, ExpectedFail: 200, Warn: 1, Skip: 182630, Duration: 44:51, Remaining: 8:56:12
09:37:06.963: Pass: 27234, ExpectedFail: 200, Warn: 1, Skip: 183065, Duration: 45:03, Remaining: 8:57:14
09:37:12.021: Pass: 27308, ExpectedFail: 200, Warn: 1, Skip: 183491, Duration: 45:05, Remaining: 8:56:21
09:37:22.169: Pass: 27448, ExpectedFail: 200, Warn: 1, Skip: 184351, Duration: 45:15, Remaining: 8:55:28
09:37:32.290: Pass: 27508, ExpectedFail: 200, Warn: 1, Skip: 184791, Duration: 45:27, Remaining: 8:56:29
09:37:37.364: Pass: 27571, ExpectedFail: 200, Warn: 1, Skip: 185228, Duration: 45:29, Remaining: 8:55:31
09:37:47.496: Pass: 27704, ExpectedFail: 200, Warn: 1, Skip: 186095, Duration: 45:38, Remaining: 8:54:33
09:37:52.557: Pass: 27766, ExpectedFail: 200, Warn: 1, Skip: 186533, Duration: 45:51, Remaining: 8:55:44
09:38:02.710: Pass: 27827, ExpectedFail: 200, Warn: 1, Skip: 186972, Duration: 45:57, Remaining: 8:55:38
09:38:07.779: Pass: 27890, ExpectedFail: 200, Warn: 1, Skip: 187409, Duration: 46:00, Remaining: 8:54:52
09:38:12.838: Pass: 28013, ExpectedFail: 201, Warn: 1, Skip: 188285, Duration: 46:12, Remaining: 8:54:22
09:38:22.963: Pass: 28069, ExpectedFail: 202, Warn: 1, Skip: 188728, Duration: 46:20, Remaining: 8:54:38
09:38:33.081: Pass: 28129, ExpectedFail: 203, Warn: 1, Skip: 189167, Duration: 46:24, Remaining: 8:54:00
09:38:38.154: Pass: 28250, ExpectedFail: 203, Warn: 1, Skip: 190046, Duration: 46:37, Remaining: 8:53:56
09:38:48.310: Pass: 28316, ExpectedFail: 206, Warn: 1, Skip: 190477, Duration: 46:44, Remaining: 8:53:59
09:38:53.369: Pass: 28374, ExpectedFail: 206, Warn: 1, Skip: 190919, Duration: 46:48, Remaining: 8:53:16
09:38:53.369: Pass: 28435, ExpectedFail: 206, Warn: 1, Skip: 191358, Duration: 46:50, Remaining: 8:52:25
09:39:03.512: Pass: 28493, ExpectedFail: 206, Warn: 1, Skip: 191800, Duration: 47:01, Remaining: 8:53:14
09:39:13.658: Pass: 28566, ExpectedFail: 206, Warn: 1, Skip: 192227, Duration: 47:08, Remaining: 8:53:14
09:39:18.718: Pass: 28636, ExpectedFail: 206, Warn: 1, Skip: 192657, Duration: 47:14, Remaining: 8:52:57
09:39:33.930: Pass: 28785, ExpectedFail: 208, Warn: 1, Skip: 193506, Duration: 47:29, Remaining: 8:53:07
09:39:38.998: Pass: 28856, ExpectedFail: 208, Warn: 1, Skip: 193935, Duration: 47:33, Remaining: 8:52:37
09:39:38.999: Pass: 28903, ExpectedFail: 209, Warn: 1, Skip: 194387, Duration: 47:37, Remaining: 8:52:08
09:39:54.186: Pass: 29028, ExpectedFail: 210, Warn: 1, Skip: 195261, Duration: 47:51, Remaining: 8:52:10
09:40:04.318: Pass: 29086, ExpectedFail: 210, Warn: 1, Skip: 195703, Duration: 47:55, Remaining: 8:51:35
09:40:04.318: Pass: 29150, ExpectedFail: 211, Warn: 1, Skip: 196138, Duration: 48:00, Remaining: 8:51:09
09:40:19.506: Pass: 29269, ExpectedFail: 214, Warn: 1, Skip: 197016, Duration: 48:17, Remaining: 8:51:47
09:40:29.633: Pass: 29398, ExpectedFail: 214, Warn: 1, Skip: 197887, Duration: 48:24, Remaining: 8:50:29
09:40:44.904: Pass: 29533, ExpectedFail: 214, Warn: 1, Skip: 198752, Duration: 48:41, Remaining: 8:51:06
09:40:50.008: Pass: 29594, ExpectedFail: 215, Warn: 1, Skip: 199190, Duration: 48:48, Remaining: 8:50:58
09:41:00.319: Pass: 29655, ExpectedFail: 215, Warn: 1, Skip: 199629, Duration: 48:56, Remaining: 8:51:10
09:41:10.472: Pass: 29733, ExpectedFail: 215, Warn: 1, Skip: 200051, Duration: 49:06, Remaining: 8:51:42
09:41:20.587: Pass: 29796, ExpectedFail: 216, Warn: 1, Skip: 200487, Duration: 49:10, Remaining: 8:51:15
09:41:25.712: Pass: 29854, ExpectedFail: 218, Warn: 1, Skip: 200927, Duration: 49:20, Remaining: 8:51:43
09:41:25.712: Pass: 29918, ExpectedFail: 219, Warn: 1, Skip: 201362, Duration: 49:23, Remaining: 8:50:58
09:41:30.777: Pass: 29996, ExpectedFail: 219, Warn: 1, Skip: 201784, Duration: 49:29, Remaining: 8:50:57
09:41:46.006: Pass: 30061, ExpectedFail: 219, Warn: 1, Skip: 202219, Duration: 49:43, Remaining: 8:52:05
09:41:56.263: Pass: 30120, ExpectedFail: 219, Warn: 1, Skip: 202660, Duration: 49:46, Remaining: 8:51:27
09:42:01.444: Pass: 30193, ExpectedFail: 219, Warn: 1, Skip: 203087, Duration: 49:57, Remaining: 8:52:05
09:42:06.508: Pass: 30258, ExpectedFail: 220, Warn: 1, Skip: 203521, Duration: 50:05, Remaining: 8:52:15
09:42:18.265: Pass: 30323, ExpectedFail: 221, Warn: 1, Skip: 203955, Duration: 50:09, Remaining: 8:51:46
09:42:23.367: Pass: 30449, ExpectedFail: 221, Warn: 1, Skip: 204829, Duration: 50:21, Remaining: 8:51:27
09:42:33.601: Pass: 30504, ExpectedFail: 223, Warn: 1, Skip: 205272, Duration: 50:29, Remaining: 8:51:31
09:42:38.736: Pass: 30567, ExpectedFail: 224, Warn: 1, Skip: 205708, Duration: 50:33, Remaining: 8:51:03
09:42:48.924: Pass: 30699, ExpectedFail: 224, Warn: 1, Skip: 206576, Duration: 50:46, Remaining: 8:50:46
09:43:09.018: Pass: 30768, ExpectedFail: 224, Warn: 1, Skip: 207007, Duration: 50:58, Remaining: 8:51:38
09:43:14.077: Pass: 30950, ExpectedFail: 224, Warn: 1, Skip: 208325, Duration: 51:09, Remaining: 8:49:58
09:43:24.235: Pass: 31007, ExpectedFail: 225, Warn: 1, Skip: 208767, Duration: 51:20, Remaining: 8:50:39
09:43:29.296: Pass: 31125, ExpectedFail: 226, Warn: 1, Skip: 209648, Duration: 51:23, Remaining: 8:48:44
09:43:39.457: Pass: 31192, ExpectedFail: 226, Warn: 1, Skip: 210081, Duration: 51:36, Remaining: 8:49:45
09:43:44.543: Pass: 31262, ExpectedFail: 226, Warn: 1, Skip: 210511, Duration: 51:43, Remaining: 8:49:47
09:43:54.688: Pass: 31399, ExpectedFail: 226, Warn: 1, Skip: 211374, Duration: 51:46, Remaining: 8:47:56
09:43:59.844: Pass: 31458, ExpectedFail: 226, Warn: 1, Skip: 211815, Duration: 51:59, Remaining: 8:48:48
09:44:09.988: Pass: 31523, ExpectedFail: 227, Warn: 1, Skip: 212249, Duration: 52:05, Remaining: 8:48:46
09:44:15.060: Pass: 31591, ExpectedFail: 227, Warn: 1, Skip: 212681, Duration: 52:09, Remaining: 8:48:13
09:44:25.198: Pass: 31719, ExpectedFail: 230, Warn: 1, Skip: 213550, Duration: 52:22, Remaining: 8:47:59
09:44:30.261: Pass: 31791, ExpectedFail: 231, Warn: 1, Skip: 213977, Duration: 52:27, Remaining: 8:47:44
09:44:35.335: Pass: 31856, ExpectedFail: 231, Warn: 1, Skip: 214412, Duration: 52:33, Remaining: 8:47:32
09:44:45.515: Pass: 31923, ExpectedFail: 231, Warn: 1, Skip: 214845, Duration: 52:44, Remaining: 8:48:05
09:44:50.572: Pass: 32042, ExpectedFail: 232, Warn: 1, Skip: 215725, Duration: 52:50, Remaining: 8:46:47
09:45:00.759: Pass: 32110, ExpectedFail: 233, Warn: 1, Skip: 216156, Duration: 52:56, Remaining: 8:46:43
09:45:11.025: Pass: 32160, ExpectedFail: 234, Warn: 1, Skip: 216605, Duration: 53:07, Remaining: 8:47:19
09:45:16.106: Pass: 32294, ExpectedFail: 234, Warn: 1, Skip: 217471, Duration: 53:14, Remaining: 8:46:06
09:45:26.283: Pass: 32353, ExpectedFail: 234, Warn: 1, Skip: 217912, Duration: 53:21, Remaining: 8:46:08
09:45:31.354: Pass: 32416, ExpectedFail: 235, Warn: 1, Skip: 218348, Duration: 53:30, Remaining: 8:46:25
09:45:41.574: Pass: 32481, ExpectedFail: 235, Warn: 1, Skip: 218783, Duration: 53:37, Remaining: 8:46:31
09:45:46.705: Pass: 32541, ExpectedFail: 235, Warn: 1, Skip: 219223, Duration: 53:40, Remaining: 8:45:50
09:45:56.856: Pass: 32596, ExpectedFail: 237, Warn: 1, Skip: 219666, Duration: 53:47, Remaining: 8:45:47
09:46:01.972: Pass: 32667, ExpectedFail: 238, Warn: 1, Skip: 220094, Duration: 53:54, Remaining: 8:45:47
09:46:12.208: Pass: 32719, ExpectedFail: 239, Warn: 1, Skip: 220541, Duration: 54:00, Remaining: 8:45:38
09:46:12.208: Pass: 32786, ExpectedFail: 239, Warn: 1, Skip: 220974, Duration: 54:04, Remaining: 8:45:04
09:46:17.273: Pass: 32844, ExpectedFail: 240, Warn: 1, Skip: 221415, Duration: 54:11, Remaining: 8:45:05
09:46:27.421: Pass: 32914, ExpectedFail: 240, Warn: 1, Skip: 221845, Duration: 54:17, Remaining: 8:44:58
09:46:32.488: Pass: 32983, ExpectedFail: 240, Warn: 1, Skip: 222276, Duration: 54:24, Remaining: 8:44:56
09:46:32.488: Pass: 33052, ExpectedFail: 240, Warn: 1, Skip: 222707, Duration: 54:26, Remaining: 8:44:11
09:46:42.627: Pass: 33118, ExpectedFail: 240, Warn: 1, Skip: 223141, Duration: 54:36, Remaining: 8:44:34
09:46:42.627: Pass: 33184, ExpectedFail: 241, Warn: 1, Skip: 223574, Duration: 54:41, Remaining: 8:44:11
09:46:52.763: Pass: 33257, ExpectedFail: 241, Warn: 1, Skip: 224001, Duration: 54:48, Remaining: 8:44:11
09:46:57.828: Pass: 33302, ExpectedFail: 241, Warn: 1, Skip: 224456, Duration: 54:52, Remaining: 8:43:47
09:47:08.019: Pass: 33363, ExpectedFail: 241, Warn: 1, Skip: 224895, Duration: 55:00, Remaining: 8:43:53
09:47:08.020: Pass: 33421, ExpectedFail: 241, Warn: 1, Skip: 225337, Duration: 55:03, Remaining: 8:43:15
09:47:13.088: Pass: 33490, ExpectedFail: 242, Warn: 1, Skip: 225767, Duration: 55:11, Remaining: 8:43:24
09:47:23.281: Pass: 33564, ExpectedFail: 243, Warn: 1, Skip: 226192, Duration: 55:16, Remaining: 8:43:03
09:47:33.441: Pass: 33628, ExpectedFail: 244, Warn: 1, Skip: 226627, Duration: 55:22, Remaining: 8:42:55
09:47:33.441: Pass: 33690, ExpectedFail: 244, Warn: 1, Skip: 227065, Duration: 55:25, Remaining: 8:42:21
09:47:43.028: Pass: 33757, ExpectedFail: 244, Warn: 1, Skip: 227498, Duration: 55:37, Remaining: 8:43:00
09:47:48.186: Pass: 33824, ExpectedFail: 245, Warn: 1, Skip: 227930, Duration: 55:39, Remaining: 8:42:18
09:47:58.340: Pass: 33877, ExpectedFail: 245, Warn: 1, Skip: 228377, Duration: 55:46, Remaining: 8:42:20
09:47:58.340: Pass: 33938, ExpectedFail: 245, Warn: 1, Skip: 228816, Duration: 55:49, Remaining: 8:41:35
09:48:03.420: Pass: 33987, ExpectedFail: 245, Warn: 1, Skip: 229267, Duration: 55:59, Remaining: 8:42:05
09:48:08.490: Pass: 34053, ExpectedFail: 245, Warn: 1, Skip: 229701, Duration: 56:02, Remaining: 8:41:32
09:48:13.551: Pass: 34114, ExpectedFail: 245, Warn: 1, Skip: 230140, Duration: 56:13, Remaining: 8:42:03
09:48:23.760: Pass: 34181, ExpectedFail: 246, Warn: 1, Skip: 230572, Duration: 56:19, Remaining: 8:41:54
09:48:33.948: Pass: 34247, ExpectedFail: 246, Warn: 1, Skip: 231006, Duration: 56:24, Remaining: 8:41:35
09:48:44.134: Pass: 34366, ExpectedFail: 248, Warn: 1, Skip: 231885, Duration: 56:42, Remaining: 8:42:07
09:48:49.200: Pass: 34429, ExpectedFail: 248, Warn: 1, Skip: 232322, Duration: 56:45, Remaining: 8:41:32
09:48:49.200: Pass: 34498, ExpectedFail: 248, Warn: 1, Skip: 232753, Duration: 56:48, Remaining: 8:40:57
09:49:09.516: Pass: 34566, ExpectedFail: 249, Warn: 1, Skip: 233184, Duration: 57:07, Remaining: 8:42:49
09:49:14.595: Pass: 34695, ExpectedFail: 253, Warn: 1, Skip: 234051, Duration: 57:13, Remaining: 8:41:35
09:49:29.771: Pass: 34747, ExpectedFail: 253, Warn: 1, Skip: 234499, Duration: 57:28, Remaining: 8:42:41
09:49:39.882: Pass: 34808, ExpectedFail: 253, Warn: 1, Skip: 234938, Duration: 57:31, Remaining: 8:42:01
09:49:44.952: Pass: 34871, ExpectedFail: 253, Warn: 1, Skip: 235375, Duration: 57:36, Remaining: 8:41:43
09:49:55.099: Pass: 35001, ExpectedFail: 254, Warn: 1, Skip: 236244, Duration: 57:52, Remaining: 8:42:07
09:50:00.177: Pass: 35069, ExpectedFail: 254, Warn: 1, Skip: 236676, Duration: 57:55, Remaining: 8:41:24
09:50:00.177: Pass: 35125, ExpectedFail: 257, Warn: 1, Skip: 237117, Duration: 57:59, Remaining: 8:40:57
09:50:20.546: Pass: 35254, ExpectedFail: 258, Warn: 1, Skip: 237987, Duration: 58:17, Remaining: 8:41:32
09:50:25.619: Pass: 35326, ExpectedFail: 260, Warn: 1, Skip: 238413, Duration: 58:19, Remaining: 8:40:51
09:50:25.619: Pass: 35386, ExpectedFail: 261, Warn: 1, Skip: 238852, Duration: 58:22, Remaining: 8:40:13
09:50:45.890: Pass: 35510, ExpectedFail: 261, Warn: 1, Skip: 239728, Duration: 58:45, Remaining: 8:41:31
09:50:56.019: Pass: 35637, ExpectedFail: 261, Warn: 1, Skip: 240601, Duration: 58:56, Remaining: 8:40:55
09:51:06.159: Pass: 35697, ExpectedFail: 262, Warn: 1, Skip: 241040, Duration: 59:02, Remaining: 8:40:49
09:51:11.240: Pass: 35770, ExpectedFail: 262, Warn: 1, Skip: 241467, Duration: 59:08, Remaining: 8:40:43
09:51:21.367: Pass: 35893, ExpectedFail: 264, Warn: 1, Skip: 242342, Duration: 59:18, Remaining: 8:40:06
09:51:26.444: Pass: 35947, ExpectedFail: 264, Warn: 1, Skip: 242788, Duration: 59:25, Remaining: 8:40:06
09:51:36.587: Pass: 36018, ExpectedFail: 264, Warn: 1, Skip: 243217, Duration: 59:34, Remaining: 8:40:14
09:51:46.780: Pass: 36141, ExpectedFail: 266, Warn: 1, Skip: 244092, Duration: 59:42, Remaining: 8:39:23
09:52:01.976: Pass: 36204, ExpectedFail: 266, Warn: 1, Skip: 244529, Duration: 59:58, Remaining: 8:40:43
09:52:07.084: Pass: 36337, ExpectedFail: 268, Warn: 1, Skip: 245394, Duration: 1:00:04, Remaining: 8:39:30
09:52:27.360: Pass: 36405, ExpectedFail: 269, Warn: 1, Skip: 245825, Duration: 1:00:23, Remaining: 8:41:13
09:52:32.464: Pass: 36467, ExpectedFail: 270, Warn: 1, Skip: 246262, Duration: 1:00:25, Remaining: 8:40:29
09:52:32.464: Pass: 36539, ExpectedFail: 270, Warn: 1, Skip: 246690, Duration: 1:00:28, Remaining: 8:39:53
09:52:42.604: Pass: 36612, ExpectedFail: 270, Warn: 1, Skip: 247117, Duration: 1:00:38, Remaining: 8:40:18
09:52:47.673: Pass: 36666, ExpectedFail: 270, Warn: 1, Skip: 247563, Duration: 1:00:47, Remaining: 8:40:32
09:52:52.775: Pass: 36737, ExpectedFail: 271, Warn: 1, Skip: 247991, Duration: 1:00:49, Remaining: 8:39:50
09:52:52.775: Pass: 36808, ExpectedFail: 271, Warn: 1, Skip: 248420, Duration: 1:00:52, Remaining: 8:39:13
09:53:03.007: Pass: 36871, ExpectedFail: 272, Warn: 1, Skip: 248856, Duration: 1:01:02, Remaining: 8:39:39
09:53:13.146: Pass: 36942, ExpectedFail: 272, Warn: 1, Skip: 249285, Duration: 1:01:11, Remaining: 8:39:49
09:53:23.267: Pass: 36991, ExpectedFail: 272, Warn: 1, Skip: 249736, Duration: 1:01:16, Remaining: 8:39:30
09:53:23.267: Pass: 37058, ExpectedFail: 272, Warn: 1, Skip: 250169, Duration: 1:01:19, Remaining: 8:39:02
09:53:28.342: Pass: 37137, ExpectedFail: 272, Warn: 1, Skip: 250590, Duration: 1:01:26, Remaining: 8:39:01
09:53:38.465: Pass: 37207, ExpectedFail: 272, Warn: 1, Skip: 251020, Duration: 1:01:34, Remaining: 8:39:05
09:53:48.643: Pass: 37267, ExpectedFail: 272, Warn: 1, Skip: 251460, Duration: 1:01:43, Remaining: 8:39:21
09:53:53.723: Pass: 37393, ExpectedFail: 272, Warn: 1, Skip: 252334, Duration: 1:01:50, Remaining: 8:38:21
09:53:58.787: Pass: 37455, ExpectedFail: 273, Warn: 1, Skip: 252771, Duration: 1:01:57, Remaining: 8:38:15
09:54:08.911: Pass: 37518, ExpectedFail: 274, Warn: 1, Skip: 253207, Duration: 1:02:07, Remaining: 8:38:43
09:54:19.047: Pass: 37647, ExpectedFail: 274, Warn: 1, Skip: 254078, Duration: 1:02:14, Remaining: 8:37:43
09:54:24.114: Pass: 37721, ExpectedFail: 275, Warn: 1, Skip: 254503, Duration: 1:02:22, Remaining: 8:37:48
09:54:34.284: Pass: 37781, ExpectedFail: 276, Warn: 1, Skip: 254942, Duration: 1:02:30, Remaining: 8:37:57
09:54:39.356: Pass: 37908, ExpectedFail: 277, Warn: 1, Skip: 255814, Duration: 1:02:38, Remaining: 8:37:03
09:54:49.478: Pass: 37961, ExpectedFail: 278, Warn: 1, Skip: 256260, Duration: 1:02:45, Remaining: 8:37:03
09:54:59.622: Pass: 38024, ExpectedFail: 278, Warn: 1, Skip: 256697, Duration: 1:02:56, Remaining: 8:37:30
09:55:04.687: Pass: 38136, ExpectedFail: 278, Warn: 1, Skip: 257585, Duration: 1:03:02, Remaining: 8:36:25
09:55:09.752: Pass: 38202, ExpectedFail: 279, Warn: 1, Skip: 258018, Duration: 1:03:09, Remaining: 8:36:20
09:55:24.959: Pass: 38261, ExpectedFail: 279, Warn: 1, Skip: 258459, Duration: 1:03:20, Remaining: 8:36:55
09:55:30.030: Pass: 38378, ExpectedFail: 281, Warn: 1, Skip: 259340, Duration: 1:03:28, Remaining: 8:36:00
09:55:40.158: Pass: 38438, ExpectedFail: 282, Warn: 1, Skip: 259779, Duration: 1:03:35, Remaining: 8:36:00
09:55:45.226: Pass: 38509, ExpectedFail: 284, Warn: 1, Skip: 260206, Duration: 1:03:44, Remaining: 8:36:12
09:55:55.372: Pass: 38582, ExpectedFail: 285, Warn: 1, Skip: 260632, Duration: 1:03:46, Remaining: 8:35:33
09:56:00.561: Pass: 38641, ExpectedFail: 285, Warn: 1, Skip: 261073, Duration: 1:03:52, Remaining: 8:35:25
09:56:05.694: Pass: 38698, ExpectedFail: 286, Warn: 1, Skip: 261515, Duration: 1:03:59, Remaining: 8:35:19
09:56:15.902: Pass: 38760, ExpectedFail: 287, Warn: 1, Skip: 261952, Duration: 1:04:08, Remaining: 8:35:36
09:56:20.973: Pass: 38829, ExpectedFail: 288, Warn: 1, Skip: 262382, Duration: 1:04:17, Remaining: 8:35:47
09:56:20.973: Pass: 38888, ExpectedFail: 288, Warn: 1, Skip: 262823, Duration: 1:04:19, Remaining: 8:35:10
09:56:31.149: Pass: 38957, ExpectedFail: 289, Warn: 1, Skip: 263253, Duration: 1:04:23, Remaining: 8:34:40
09:56:36.222: Pass: 39026, ExpectedFail: 290, Warn: 1, Skip: 263683, Duration: 1:04:32, Remaining: 8:34:56
09:56:36.223: ERROR - dEQP error: FATAL ERROR: basic_string: construction from null is not valid
09:56:46.365: Pass: 39081, ExpectedFail: 291, Warn: 1, Skip: 264127, Duration: 1:04:43, Remaining: 8:35:23
09:56:56.501: Pass: 39216, ExpectedFail: 292, Warn: 1, Skip: 264991, Duration: 1:04:47, Remaining: 8:34:04
09:57:01.665: Pass: 39288, ExpectedFail: 292, Warn: 1, Skip: 265419, Duration: 1:04:59, Remaining: 8:34:43
09:57:11.813: Pass: 39346, ExpectedFail: 292, Warn: 1, Skip: 265861, Duration: 1:05:07, Remaining: 8:34:45
09:57:16.874: Pass: 39464, ExpectedFail: 294, Warn: 1, Skip: 266741, Duration: 1:05:10, Remaining: 8:33:18
09:57:27.019: Pass: 39521, ExpectedFail: 295, Warn: 1, Skip: 267183, Duration: 1:05:22, Remaining: 8:34:01
09:57:32.096: Pass: 39583, ExpectedFail: 295, Warn: 1, Skip: 267621, Duration: 1:05:30, Remaining: 8:34:05
09:57:42.209: Pass: 39704, ExpectedFail: 296, Warn: 1, Skip: 268499, Duration: 1:05:33, Remaining: 8:32:35
09:57:47.273: Pass: 39767, ExpectedFail: 298, Warn: 1, Skip: 268934, Duration: 1:05:45, Remaining: 8:33:12
09:57:57.391: Pass: 39833, ExpectedFail: 299, Warn: 1, Skip: 269367, Duration: 1:05:54, Remaining: 8:33:22
09:58:02.474: Pass: 39949, ExpectedFail: 299, Warn: 1, Skip: 270251, Duration: 1:05:57, Remaining: 8:31:56
09:58:12.649: Pass: 40018, ExpectedFail: 299, Warn: 1, Skip: 270682, Duration: 1:06:09, Remaining: 8:32:36
09:58:17.709: Pass: 40082, ExpectedFail: 299, Warn: 1, Skip: 271118, Duration: 1:06:16, Remaining: 8:32:36
09:58:27.890: Pass: 40152, ExpectedFail: 299, Warn: 1, Skip: 271548, Duration: 1:06:19, Remaining: 8:31:57
09:58:32.952: Pass: 40270, ExpectedFail: 299, Warn: 1, Skip: 272430, Duration: 1:06:31, Remaining: 8:31:40
09:58:43.094: Pass: 40328, ExpectedFail: 300, Warn: 1, Skip: 272871, Duration: 1:06:42, Remaining: 8:32:12
09:58:53.226: Pass: 40383, ExpectedFail: 300, Warn: 1, Skip: 273316, Duration: 1:06:44, Remaining: 8:31:33
09:58:58.298: Pass: 40525, ExpectedFail: 301, Warn: 1, Skip: 274173, Duration: 1:06:55, Remaining: 8:31:03
09:59:08.434: Pass: 40594, ExpectedFail: 301, Warn: 1, Skip: 274604, Duration: 1:07:07, Remaining: 8:31:39
09:59:18.585: Pass: 40738, ExpectedFail: 304, Warn: 1, Skip: 275457, Duration: 1:07:10, Remaining: 8:30:12
09:59:28.711: Pass: 40807, ExpectedFail: 304, Warn: 1, Skip: 275888, Duration: 1:07:19, Remaining: 8:30:28
09:59:33.816: Pass: 40871, ExpectedFail: 304, Warn: 1, Skip: 276324, Duration: 1:07:30, Remaining: 8:30:58
09:59:38.884: Pass: 40985, ExpectedFail: 304, Warn: 1, Skip: 277210, Duration: 1:07:33, Remaining: 8:29:33
09:59:49.021: Pass: 41051, ExpectedFail: 304, Warn: 1, Skip: 277644, Duration: 1:07:43, Remaining: 8:29:50
09:59:59.153: Pass: 41122, ExpectedFail: 305, Warn: 1, Skip: 278072, Duration: 1:07:54, Remaining: 8:30:22
10:00:14.393: Pass: 41308, ExpectedFail: 307, Warn: 1, Skip: 279384, Duration: 1:08:10, Remaining: 8:29:34
10:00:19.469: Pass: 41364, ExpectedFail: 307, Warn: 1, Skip: 279828, Duration: 1:08:18, Remaining: 8:29:44
10:00:29.628: Pass: 41499, ExpectedFail: 308, Warn: 1, Skip: 280692, Duration: 1:08:23, Remaining: 8:28:30
10:00:34.693: Pass: 41565, ExpectedFail: 309, Warn: 1, Skip: 281125, Duration: 1:08:34, Remaining: 8:29:02
10:00:44.836: Pass: 41629, ExpectedFail: 310, Warn: 1, Skip: 281560, Duration: 1:08:41, Remaining: 8:28:58
10:00:49.898: Pass: 41777, ExpectedFail: 313, Warn: 1, Skip: 282409, Duration: 1:08:46, Remaining: 8:27:49
10:01:00.021: Pass: 41837, ExpectedFail: 313, Warn: 1, Skip: 282849, Duration: 1:08:57, Remaining: 8:28:18
10:01:05.091: Pass: 41893, ExpectedFail: 314, Warn: 1, Skip: 283292, Duration: 1:09:04, Remaining: 8:28:14
10:01:15.232: Pass: 42030, ExpectedFail: 315, Warn: 1, Skip: 284154, Duration: 1:09:10, Remaining: 8:27:11
10:01:25.370: Pass: 42088, ExpectedFail: 316, Warn: 1, Skip: 284595, Duration: 1:09:21, Remaining: 8:27:44
10:01:35.488: Pass: 42147, ExpectedFail: 316, Warn: 1, Skip: 285036, Duration: 1:09:30, Remaining: 8:27:52
10:01:40.554: Pass: 42277, ExpectedFail: 317, Warn: 1, Skip: 285905, Duration: 1:09:37, Remaining: 8:26:58
10:01:45.625: Pass: 42349, ExpectedFail: 317, Warn: 1, Skip: 286333, Duration: 1:09:44, Remaining: 8:27:00
10:01:55.808: Pass: 42413, ExpectedFail: 317, Warn: 1, Skip: 286769, Duration: 1:09:52, Remaining: 8:27:01
10:02:00.874: Pass: 42554, ExpectedFail: 318, Warn: 1, Skip: 287627, Duration: 1:10:00, Remaining: 8:26:17
10:02:11.022: Pass: 42620, ExpectedFail: 318, Warn: 1, Skip: 288061, Duration: 1:10:08, Remaining: 8:26:19
10:02:21.151: Pass: 42686, ExpectedFail: 318, Warn: 1, Skip: 288495, Duration: 1:10:17, Remaining: 8:26:33
10:02:26.214: Pass: 42767, ExpectedFail: 318, Warn: 1, Skip: 288914, Duration: 1:10:26, Remaining: 8:26:44
10:02:36.367: Pass: 42827, ExpectedFail: 318, Warn: 1, Skip: 289354, Duration: 1:10:32, Remaining: 8:26:37
10:02:41.427: Pass: 42958, ExpectedFail: 318, Warn: 1, Skip: 290223, Duration: 1:10:41, Remaining: 8:25:56
10:02:51.568: Pass: 43017, ExpectedFail: 319, Warn: 1, Skip: 290663, Duration: 1:10:48, Remaining: 8:25:56
10:03:01.703: Pass: 43076, ExpectedFail: 319, Warn: 1, Skip: 291104, Duration: 1:10:57, Remaining: 8:26:10
10:03:06.769: Pass: 43138, ExpectedFail: 319, Warn: 1, Skip: 291542, Duration: 1:11:00, Remaining: 8:25:41
10:03:06.769: Pass: 43205, ExpectedFail: 319, Warn: 1, Skip: 291975, Duration: 1:11:05, Remaining: 8:25:19
10:03:11.834: Pass: 43260, ExpectedFail: 319, Warn: 1, Skip: 292420, Duration: 1:11:10, Remaining: 8:25:10
10:03:27.102: Pass: 43323, ExpectedFail: 319, Warn: 1, Skip: 292857, Duration: 1:11:22, Remaining: 8:25:40
10:03:32.163: Pass: 43453, ExpectedFail: 321, Warn: 1, Skip: 293725, Duration: 1:11:30, Remaining: 8:24:55
10:03:42.286: Pass: 43516, ExpectedFail: 321, Warn: 1, Skip: 294162, Duration: 1:11:33, Remaining: 8:24:26
10:03:47.366: Pass: 43591, ExpectedFail: 322, Warn: 1, Skip: 294586, Duration: 1:11:46, Remaining: 8:25:07
10:03:57.502: Pass: 43662, ExpectedFail: 323, Warn: 1, Skip: 295014, Duration: 1:11:54, Remaining: 8:25:08
10:04:02.650: Pass: 43717, ExpectedFail: 324, Warn: 1, Skip: 295458, Duration: 1:11:57, Remaining: 8:24:42
10:04:02.650: Pass: 43795, ExpectedFail: 324, Warn: 1, Skip: 295880, Duration: 1:12:00, Remaining: 8:24:09
10:04:22.918: Pass: 43862, ExpectedFail: 324, Warn: 1, Skip: 296313, Duration: 1:12:19, Remaining: 8:25:33
10:04:27.997: Pass: 43928, ExpectedFail: 324, Warn: 1, Skip: 296747, Duration: 1:12:22, Remaining: 8:25:02
10:04:38.120: Pass: 44076, ExpectedFail: 325, Warn: 1, Skip: 297598, Duration: 1:12:28, Remaining: 8:24:00
10:04:48.271: Pass: 44140, ExpectedFail: 325, Warn: 1, Skip: 298034, Duration: 1:12:46, Remaining: 8:25:19
10:04:53.340: Pass: 44316, ExpectedFail: 327, Warn: 1, Skip: 299356, Duration: 1:12:52, Remaining: 8:23:26
10:05:13.621: Pass: 44360, ExpectedFail: 328, Warn: 1, Skip: 299811, Duration: 1:13:09, Remaining: 8:24:34
10:05:18.684: Pass: 44430, ExpectedFail: 328, Warn: 1, Skip: 300241, Duration: 1:13:12, Remaining: 8:24:03
10:05:18.684: Pass: 44576, ExpectedFail: 329, Warn: 1, Skip: 301094, Duration: 1:13:16, Remaining: 8:22:51
10:05:38.968: Pass: 44644, ExpectedFail: 329, Warn: 1, Skip: 301526, Duration: 1:13:34, Remaining: 8:24:05
10:05:44.035: Pass: 44820, ExpectedFail: 330, Warn: 1, Skip: 302849, Duration: 1:13:41, Remaining: 8:22:22
10:05:59.312: Pass: 44890, ExpectedFail: 330, Warn: 1, Skip: 303279, Duration: 1:13:59, Remaining: 8:23:34
10:06:09.439: Pass: 45107, ExpectedFail: 331, Warn: 1, Skip: 304561, Duration: 1:14:05, Remaining: 8:21:49
10:06:24.617: ERROR - dEQP error: deqp-vk: ../src/panfrost/vulkan/jm/panvk_vX_cmd_dispatch.c:188: panvk_v7_CmdDispatchIndirect: Assertion `!"stub"' failed.
10:06:29.717: Pass: 45169, ExpectedFail: 331, Warn: 1, Skip: 304999, Duration: 1:14:23, Remaining: 8:22:58
10:06:29.717: ERROR - dEQP error: FATAL ERROR: basic_string: construction from null is not valid
10:06:34.776: Pass: 45305, ExpectedFail: 332, Warn: 1, Skip: 305862, Duration: 1:14:28, Remaining: 8:21:56
10:06:49.978: Pass: 45437, ExpectedFail: 335, Warn: 1, Skip: 306727, Duration: 1:14:47, Remaining: 8:22:25
10:06:55.039: Pass: 45558, ExpectedFail: 336, Warn: 1, Skip: 307605, Duration: 1:14:53, Remaining: 8:21:31
10:07:15.307: Pass: 45671, ExpectedFail: 336, Warn: 1, Skip: 308492, Duration: 1:15:10, Remaining: 8:21:43
10:07:20.370: Pass: 45744, ExpectedFail: 337, Warn: 1, Skip: 308918, Duration: 1:15:12, Remaining: 8:21:09
10:07:20.370: Pass: 45816, ExpectedFail: 337, Warn: 1, Skip: 309346, Duration: 1:15:17, Remaining: 8:20:51
10:07:35.550: Pass: 45948, ExpectedFail: 337, Warn: 1, Skip: 310214, Duration: 1:15:34, Remaining: 8:21:10
10:07:40.655: Pass: 46030, ExpectedFail: 337, Warn: 1, Skip: 310632, Duration: 1:15:39, Remaining: 8:20:56
10:07:50.776: Pass: 46159, ExpectedFail: 338, Warn: 1, Skip: 311502, Duration: 1:15:41, Remaining: 8:19:33
10:08:00.910: Pass: 46227, ExpectedFail: 339, Warn: 1, Skip: 311933, Duration: 1:15:58, Remaining: 8:20:34
10:08:05.977: Pass: 46291, ExpectedFail: 339, Warn: 1, Skip: 312369, Duration: 1:16:03, Remaining: 8:20:18
10:08:05.977: Pass: 46359, ExpectedFail: 339, Warn: 1, Skip: 312801, Duration: 1:16:05, Remaining: 8:19:43
10:08:26.243: Pass: 46504, ExpectedFail: 339, Warn: 1, Skip: 313656, Duration: 1:16:24, Remaining: 8:20:10
Terminated
section_end:1733998106:lava_submit


[79:04] ERROR: lava_submit: ret code: 143


[79:04] ERROR: unknown-section: ret code: 143

section_end:1733998107:step_script
WARNING: script canceled externally (UI, API)
section_start:1733998107:after_script
Running after_script
Using docker image sha256:ebc7b3fe89be4d390775303adddb33539c235a2663165d78d6887b799bd64788 for registry.freedesktop.org/valentine/mesa/debian/x86_64_pyutils:20241002-pyutils--e195d80f35b45cc73668be3767b923fd76c70ed5 with digest registry.freedesktop.org/bbrezillon/mesa/debian/x86_64_pyutils@sha256:bd9ee9f72b4e805e3a91aa9b18e146a0cd222b0ac39dd92614b312415681e5b8 ...
Running after script...
$ curl -L --retry 4 -f --retry-all-errors --retry-delay 60 -s "https://${JOB_RESULTS_PATH}" | tar --warning=no-timestamp --zstd -x
zstd: /*stdin*\: unexpected end of file 
tar: Child returned status 1
tar: Error is not recoverable: exiting now
section_end:1733998349:after_script
WARNING: after_script failed, but job will continue unaffected: exit code 1
section_start:1733998349:upload_artifacts_on_failure
Uploading artifacts for failed job
Uploading artifacts...
results/: found 11 matching artifact files and directories 
Uploading artifacts as "archive" to coordinator... 201 Created  id=68088111 responseStatus=201 Created token=glcbt-64
Uploading artifacts...
WARNING: results/junit.xml: no matching files. Ensure that the artifact path is relative to the working directory (/builds/Valentine/mesa) 
ERROR: No files to upload                          
section_end:1733998351:upload_artifacts_on_failure
section_start:1733998351:cleanup_file_variables
Cleaning up project directory and file based variables
section_end:1733998352:cleanup_file_variables
ERROR: Job failed: canceled