Running with gitlab-runner 15.3.0 (bbcb5aba)
  on mesa-lava-lima-meson-gxl-s805x-libretech-ac AN2HRxPy
section_start:1662479594:prepare_executor
Preparing the "docker" executor
Using Docker executor with image registry.freedesktop.org/enunes/mesa/debian/x86_build:2022-07-29-s390xxx--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd ...
Authenticating with credentials from job payload (GitLab Registry)
Pulling docker image registry.freedesktop.org/enunes/mesa/debian/x86_build:2022-07-29-s390xxx--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd ...
Using docker image sha256:9eb064d431da23ecae2e1be9d8281797ba211487e2e0811c17eec3aa1838224a for registry.freedesktop.org/enunes/mesa/debian/x86_build:2022-07-29-s390xxx--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd with digest registry.freedesktop.org/enunes/mesa/debian/x86_build@sha256:15bc28363df747856b81fcb16ed636b10e6be9fd8a0292d8e40651b56dc0468f ...
section_end:1662479597:prepare_executor
section_start:1662479597:prepare_script
Preparing environment
Running on runner-an2hrxpy-project-2991-concurrent-1 via mesa-lava-lima-meson-gxl-s805x-libretech-ac...
section_end:1662479598:prepare_script
section_start:1662479598:get_sources
Getting source from Git repository
Skipping Git repository setup
Skipping Git checkout
Skipping Git submodules setup
section_end:1662479598:get_sources
section_start:1662479598:download_artifacts
Downloading artifacts
Downloading artifacts for debian-arm64 (27956098)...
Downloading artifacts from coordinator... ok        id=27956098 responseStatus=200 OK token=Rxjc4s97
section_end:1662479601:download_artifacts
section_start:1662479601:step_script
Executing "step_script" stage of the job script
Using docker image sha256:9eb064d431da23ecae2e1be9d8281797ba211487e2e0811c17eec3aa1838224a for registry.freedesktop.org/enunes/mesa/debian/x86_build:2022-07-29-s390xxx--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd with digest registry.freedesktop.org/enunes/mesa/debian/x86_build@sha256:15bc28363df747856b81fcb16ed636b10e6be9fd8a0292d8e40651b56dc0468f ...
$ echo -e "\e[0Ksection_start:$(date +%s):unset_env_vars_section[collapsed=true]\r\e[0KUnsetting vulnerable environment variables"
section_start:1662479601:unset_env_vars_section[collapsed=true]
Unsetting vulnerable environment variables
$ echo -n "${CI_JOB_JWT}" > "${CI_JOB_JWT_FILE}"
$ unset CI_JOB_JWT
$ echo -e "\e[0Ksection_end:$(date +%s):unset_env_vars_section\r\e[0K"
section_end:1662479601:unset_env_vars_section

$ ./artifacts/lava/lava-submit.sh
+ wget -q --method=HEAD https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/done
+ BASE_SYSTEM_HOST_PATH=minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64
+ rm -rf results
+ mkdir -p results/job-rootfs-overlay/
+ cp artifacts/ci-common/capture-devcoredump.sh results/job-rootfs-overlay/
+ cp artifacts/ci-common/init-stage1.sh artifacts/ci-common/init-stage2.sh results/job-rootfs-overlay/
+ cp artifacts/ci-common/intel-gpu-freq.sh results/job-rootfs-overlay/
+ KERNEL_IMAGE_BASE_URL=https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64
+ artifacts/ci-common/generate-env.sh
+ tar zcf job-rootfs-overlay.tar.gz -C results/job-rootfs-overlay/ .
+ ci-fairy minio login --token-file /minio_jwt
+ ci-fairy minio cp job-rootfs-overlay.tar.gz minio://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136/job-rootfs-overlay.tar.gz
+ touch results/lava.log
+ tail -f results/lava.log
+ PYTHONPATH=artifacts/
+ artifacts/lava/lava_job_submitter.py --dump-yaml --pipeline-info 'lima-mali450-deqp-egl:arm64: https://gitlab.freedesktop.org/enunes/mesa/-/pipelines/682113 on lima-ci-deqp-egl /1' --rootfs-url-prefix https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64 --kernel-url-prefix https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64 --build-url https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/mesa-arm64.tar.zst --job-rootfs-overlay-url https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136/job-rootfs-overlay.tar.gz --job-timeout 30 --first-stage-init artifacts/ci-common/init-stage1.sh --ci-project-dir /builds/enunes/mesa --device-type meson-gxl-s805x-libretech-ac --dtb meson-gxl-s805x-libretech-ac --jwt-file /minio_jwt --kernel-image-name Image --kernel-image-type image --boot-method u-boot --visibility-group mesa-ci --lava-tags '' --mesa-job-name lima-mali450-deqp-egl:arm64
2022-09-06 15:53:25.593298: Proxy for http://10.0.70.21 created.
section_start:1662479605:yaml_dump[collapsed=true]
LAVA job definition (YAML)
actions:
- deploy:
    dtb:
      url: https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/meson-gxl-s805x-libretech-ac.dtb
    kernel:
      type: image
      url: https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/Image
    nfsrootfs:
      compression: zstd
      url: https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/lava-rootfs.tar.zst
    os: oe
    timeout:
      minutes: 10
    to: tftp
- boot:
    commands: nfs
    failure_retry: 3
    method: u-boot
    prompts:
    - 'lava-shell:'
- test:
    definitions:
    - from: inline
      lava-signal: kmsg
      name: mesa
      path: inline/mesa.yaml
      repository:
        metadata:
          description: Mesa test plan
          format: Lava-Test Test Definition 1.0
          name: mesa
          os:
          - oe
          scope:
          - functional
        run:
          steps:
          - set -ex
          - cd /
          - mount -t proc none /proc
          - mount -t sysfs none /sys
          - mount -t debugfs none /sys/kernel/debug
          - mount -t devtmpfs none /dev || echo possibly already mounted
          - mkdir -p /dev/pts
          - mount -t devpts devpts /dev/pts
          - mount -t tmpfs tmpfs /tmp
          - echo "nameserver 8.8.8.8" > /etc/resolv.conf
          - '[ -z "$NFS_SERVER_IP" ] || echo "$NFS_SERVER_IP caching-proxy" >> /etc/hosts'
          - for i in 1 2 3; do sntp -sS pool.ntp.org && break || sleep 2; done || true
          - set +x
          - set -x
          - echo "export CI_JOB_JWT_FILE=/minio_jwt" >> /set-job-env-vars.sh
          - mkdir -p /builds/enunes/mesa
          - wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/mesa-arm64.tar.zst | tar --zstd -x -C /builds/enunes/mesa
          - wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136/job-rootfs-overlay.tar.gz | tar -xz -C /
          - sleep 1
          - lava-test-case 'mesa-ci_lima-mali450-deqp-egl:arm64' --shell /init-stage2.sh
    failure_retry: 1
    timeout:
      minutes: 30
context:
  extra_nfsroot_args: ' init=/init rootwait usbcore.quirks=0bda:8153:k'
device_type: meson-gxl-s805x-libretech-ac
job_name: 'mesa: lima-mali450-deqp-egl:arm64: https://gitlab.freedesktop.org/enunes/mesa/-/pipelines/682113 on lima-ci-deqp-egl /1'
priority: 75
timeouts:
  action:
    minutes: 3
  actions:
    depthcharge-action:
      minutes: 9
  job:
    minutes: 30
visibility:
  group:
  - mesa-ci

section_end:1662479605:yaml_dump

2022-09-06 15:53:25.625381: LAVA job definition validated successfully
2022-09-06 15:53:25.652926: Waiting for job 25747 to start.
2022-09-06 15:53:45.727921: Job 25747 started.
section_start:1662479625:lava_boot[collapsed=true]
LAVA boot
2022-09-06 15:53:50.757506: lava-dispatcher, installed at version: 2022.04
2022-09-06 15:53:50.757559: start: 0 validate
2022-09-06 15:53:50.757567: Start time: 2022-09-06 15:53:39.525253+00:00 (UTC)
2022-09-06 15:53:50.757573: validate duration: 0.97
2022-09-06 15:53:50.757578: start: 1 tftp-deploy (timeout 00:10:00) [common]
2022-09-06 15:53:50.757583: downloading https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/Image
2022-09-06 15:53:55.798738: 32MB downloaded in 6.51s (4.95MB/s)
2022-09-06 15:53:55.798802: downloading https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/meson-gxl-s805x-libretech-ac.dtb
2022-09-06 15:53:55.798830: 0MB downloaded in 0.42s (0.06MB/s)
2022-09-06 15:53:55.798851: downloading https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/2022-08-30-piglit-update-0--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd/arm64/lava-rootfs.tar.zst
2022-09-06 15:53:55.798861: Using unzstd to decompress zstd
2022-09-06 15:54:46.903542: 391MB downloaded in 53.86s (7.27MB/s)
2022-09-06 15:55:02.506433: Loading test definitions
2022-09-06 15:55:02.506480: Creating lava-test-runner.conf files
2022-09-06 15:55:02.506488: [common] Applying overlay to NFS
2022-09-06 15:55:02.506493: Converting downloaded kernel to a uImage
2022-09-06 15:55:02.506498: end: 1 tftp-deploy (duration 00:01:13) [common]
2022-09-06 15:55:02.506503: start: 2 uboot-action (timeout 00:03:00) [common]
2022-09-06 15:55:02.506508: Using kernel file from prepare-kernel: 25747/tftp-deploy-4e37gkg_/kernel/uImage
2022-09-06 15:55:02.506513: Parsed boot commands:
2022-09-06 15:55:02.506517: - setenv autoload no
2022-09-06 15:55:02.506522: - setenv initrd_high 0xffffffff
2022-09-06 15:55:02.506527: - setenv fdt_high 0xffffffff
2022-09-06 15:55:02.506533: - dhcp
2022-09-06 15:55:02.506538: - setenv serverip 10.0.70.21
2022-09-06 15:55:02.506543: - tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage
2022-09-06 15:55:02.506548: - setenv initrd_size ${filesize}
2022-09-06 15:55:02.506563: - tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb
2022-09-06 15:55:02.506642: - setenv bootargs console=ttyAML0,115200n8
2022-09-06 15:55:02.506693: - setenv bootargs ${bootargs} root=/dev/nfs rw
2022-09-06 15:55:02.506768: - setenv bootargs ${bootargs} nfsroot=10.0.70.21:/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq,tcp,hard,vers=3 init=/init rootwait usbcore.quirks=0bda:8153:k
2022-09-06 15:55:02.506776: - setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10
2022-09-06 15:55:02.506782: - setenv bootargs ${bootargs} ip=dhcp
2022-09-06 15:55:02.506787: - bootm 0x08080000 - 0x08008000
2022-09-06 15:55:02.506849: [common] connect-device Connecting to device using 'telnet 10.0.70.21 3022'
2022-09-06 15:55:07.535661: Not interrupting bootloader, waiting for bootloader prompt
2022-09-06 15:55:07.535725: Trying 10.0.70.21...
2022-09-06 15:55:07.535738: Connected to 10.0.70.21.
2022-09-06 15:55:07.535748: Escape character is '^]'.
2022-09-06 15:55:07.535757: port 3022 device /dev/ttyUSBh1p3,115200
2022-09-06 15:55:07.535765: ��GXL:BL1:9ac50e:bb16dc;FEAT:ADFC318C:0;POC:1;RCY:0;SPI:0;0.0;CHK:0;
2022-09-06 15:55:07.535774: TE: 35047
2022-09-06 15:55:07.535783: BL2 Built : 15:21:18, Aug 28 2019. gxl g1bf2b53 - luan.yuan@droid15-sz
2022-09-06 15:55:07.535792: set vcck to 1120 mv
2022-09-06 15:55:07.535802: set vddee to 1000 mv
2022-09-06 15:55:07.535811: Board ID = 11
2022-09-06 15:55:07.536017: CPU clk: 1200MHz
2022-09-06 15:55:07.536042: DDR enable rdbi
2022-09-06 15:55:07.536050: DDR use ext vref
2022-09-06 15:55:07.536057: DDR ZQ power down
2022-09-06 15:55:07.536266: DQS-corr enabled
2022-09-06 15:55:07.536286: DDR scramble enabled
2022-09-06 15:55:07.536294: DDR4 chl: Rank0 16bit @ 1200MHz
2022-09-06 15:55:07.536459: bist_test rank: 0 11 00 23 27 15 3a 12 00 25 28 15 3b 1f 1f 1f 1e 1e 1e 1e 1e 1e 1f 1f 1f 694  
2022-09-06 15:55:07.536482: Rank0: 1024MB(auto)-2T-18
2022-09-06 15:55:07.536492: AddrBus test pass!
2022-09-06 15:55:07.536501: Set ddr ssc: ppm1000-
2022-09-06 15:55:07.536664: Load fip header from SPI, src: 0x0000c000, des: 0x01400000, size: 0x00004000, part: 0
2022-09-06 15:55:07.536831: New fip structure!
2022-09-06 15:55:07.537001: Load bl30 from SPI, src: 0x00010000, des: 0x013c0000, size: 0x0000d600, part: 0
2022-09-06 15:55:07.537182: Load bl31 from SPI, src: 0x00020000, des: 0x05100000, size: 0x0002b400, part: 0
2022-09-06 15:55:07.537211: Load bl33 from SPI, src: 0x0004c000, des: 0x01000000, size: 0x00086600, part: 0
2022-09-06 15:55:07.537219: NOTICE:  BL3-1: v1.0(release):53f813e
2022-09-06 15:55:07.537225: NOTICE:  BL3-1: Built : 15:51:23, May 22 2019
2022-09-06 15:55:07.537232: [BL31]: GXL CPU setup!
2022-09-06 15:55:07.537240: NOTICE:  BL3-1: GXL normal boot!
2022-09-06 15:55:07.537406: NOTICE:  BL3-1: BL33 decompress pass
2022-09-06 15:55:07.537414: mpu_config_enable:system pre init ok
2022-09-06 15:55:07.537421: dmc sec lock
2022-09-06 15:55:07.537428: [Image: gxl_v1.1.3390-6ac5299 2019-09-26 14:09:24 luan.yuan@droid15-sz]
2022-09-06 15:55:07.537603: OPS=0x34
2022-09-06 15:55:07.537614: 21 0d 34 00 13 b2 b1 52 5b 00 41 ac 21 83 54 02 
2022-09-06 15:55:07.537620: [0.762753 Inits done]
2022-09-06 15:55:07.537705: secure task start!
2022-09-06 15:55:07.537810: high task start!
2022-09-06 15:55:07.537819: low task start!
2022-09-06 15:55:07.537826: ERROR:   Error initializing runtime service opteed_fast
2022-09-06 15:55:07.537977: U-Boot 2021.07+ (Jul 27 2021 - 19:48:21 +0000) Libre Computer AML-S805X-AC
2022-09-06 15:55:07.538002: DRAM:  1 GiB
2022-09-06 15:55:07.538011: MMC:   mmc@74000: 0
2022-09-06 15:55:07.538018: Loading Environment from SPIFlash... SF: Detected gd25lq128 with page size 256 Bytes, erase size 64 KiB, total 16 MiB
2022-09-06 15:55:07.538025: OK
2022-09-06 15:55:07.538196: ** No partition table - mmc 0 **
2022-09-06 15:55:07.538211: Couldn't find partition mmc 0:1
2022-09-06 15:55:07.538294: Error: could not access storage.
2022-09-06 15:55:07.538383: In:    serial
2022-09-06 15:55:07.538391: Out:   serial
2022-09-06 15:55:07.538398: Err:   serial
2022-09-06 15:55:07.538405: [BL31]: tee size: 0
2022-09-06 15:55:07.538563: Net:   eth0: ethernet@c9410000
2022-09-06 15:55:07.538581: starting USB...
2022-09-06 15:55:07.538587: Bus usb@c9000000: dwc3_meson_gxl_get_phys: usb2 ports: 2
2022-09-06 15:55:07.538732: Register 2000140 NbrPorts 2
2022-09-06 15:55:07.538876: Starting the controller
2022-09-06 15:55:07.538897: USB XHCI 1.00
2022-09-06 15:55:07.538904: scanning bus usb@c9000000 for devices... 1 USB Device(s) found
2022-09-06 15:55:07.538909:        scanning usb for storage devices... 0 Storage Device(s) found
2022-09-06 15:55:07.538915: $ setenv autoload no

2022-09-06 15:55:07.539134: => setenv autoload no
2022-09-06 15:55:07.539186: setenv autoload no
2022-09-06 15:55:07.539195: $ setenv initrd_high 0xffffffff

2022-09-06 15:55:07.539204: => setenv initrd_high 0xffffffff
2022-09-06 15:55:07.539211: setenv initrd_high 0xffffffff
2022-09-06 15:55:07.539357: $ setenv fdt_high 0xffffffff

2022-09-06 15:55:07.539508: => setenv fdt_high 0xffffffff
2022-09-06 15:55:07.539670: setenv fdt_high 0xffffffff
2022-09-06 15:55:07.539690: $ dhcp

2022-09-06 15:55:07.539699: => dhcp
2022-09-06 15:55:07.539706: dhcp
2022-09-06 15:55:07.539712: Speed: 100, full duplex
2022-09-06 15:55:07.539717: BOOTP broadcast 1
2022-09-06 15:55:07.539872: DHCP client bound to address 10.0.70.83 (1 ms)
2022-09-06 15:55:07.539889: $ setenv serverip 10.0.70.21

2022-09-06 15:55:07.540040: => setenv serverip 10.0.70.21
2022-09-06 15:55:07.540052: setenv serverip 10.0.70.21
2022-09-06 15:55:07.540211: $ tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage

2022-09-06 15:55:07.540228: => tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage
2022-09-06 15:55:07.540236: tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage
2022-09-06 15:55:07.540401: Speed: 100, full duplex
2022-09-06 15:55:07.540410: Using ethernet@c9410000 device
2022-09-06 15:55:07.540416: TFTP from server 10.0.70.21; our IP address is 10.0.70.83
2022-09-06 15:55:07.540422: Filename '25747/tftp-deploy-4e37gkg_/kernel/uImage'.
2022-09-06 15:55:07.540581: Load address: 0x8080000
2022-09-06 15:55:07.540603: Loading: *#################################################################
2022-09-06 15:55:07.540618: 	 #################################################################
2022-09-06 15:55:07.540629: 	 #################################################################
2022-09-06 15:55:07.540792: 	 #################################################################
2022-09-06 15:55:07.540802: 	 #################################################################
2022-09-06 15:55:07.540809: 	 #################################################################
2022-09-06 15:55:07.540816: 	 #################################################################
2022-09-06 15:55:07.540968: 	 #################################################################
2022-09-06 15:55:12.564447: 	 #################################################################
2022-09-06 15:55:12.564754: 	 #################################################################
2022-09-06 15:55:12.564765: 	 #################################################################
2022-09-06 15:55:12.564771: 	 #################################################################
2022-09-06 15:55:12.564776: 	 #################################################################
2022-09-06 15:55:12.564781: 	 #################################################################
2022-09-06 15:55:12.564785: 	 #################################################################
2022-09-06 15:55:12.564790: 	 #################################################################
2022-09-06 15:55:12.564795: 	 #################################################################
2022-09-06 15:55:12.564799: 	 #################################################################
2022-09-06 15:55:12.564804: 	 #################################################################
2022-09-06 15:55:12.564808: 	 #################################################################
2022-09-06 15:55:12.564813: 	 #################################################################
2022-09-06 15:55:12.564817: 	 #################################################################
2022-09-06 15:55:12.564822: 	 #################################################################
2022-09-06 15:55:12.564827: 	 #################################################################
2022-09-06 15:55:12.564831: 	 #################################################################
2022-09-06 15:55:12.564836: 	 #################################################################
2022-09-06 15:55:12.564841: 	 #################################################################
2022-09-06 15:55:12.564846: 	 #################################################################
2022-09-06 15:55:12.564851: 	 #################################################################
2022-09-06 15:55:12.564857: 	 #################################################################
2022-09-06 15:55:12.564862: 	 #################################################################
2022-09-06 15:55:12.564936: 	 #################################################################
2022-09-06 15:55:12.564964: 	 #################################################################
2022-09-06 15:55:12.564971: 	 #################################################################
2022-09-06 15:55:12.564975: 	 #################################################################
2022-09-06 15:55:22.618554: [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
2022-09-06 15:55:22.618653: [    0.000000] Linux version 5.17.9linux-v5.17-for-mesa-ci-b78f7870d97b.tar.bz2 (root@runner-6k-yadhv-project-176-concurrent-0) (gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2) #1 SMP PREEMPT Tue Aug 30 13:32:45 UTC 2022
2022-09-06 15:55:22.618665: [    0.000000] Machine model: Libre Computer AML-S805X-AC
2022-09-06 15:55:22.618677: [    0.000000] efi: UEFI not found.
2022-09-06 15:55:22.618685: [    0.000000] [Firmware Bug]: Kernel image misaligned at boot, please fix your bootloader!
2022-09-06 15:55:22.618702: [    0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'hwrom@0': base 0x0000000000000000, size 16 MiB
2022-09-06 15:55:22.618710: [    0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'secmon@5000000': base 0x0000000005000000, size 3 MiB
2022-09-06 15:55:22.618720: [    0.000000] Reserved memory: created CMA memory pool at 0x000000002fc00000, size 256 MiB
2022-09-06 15:55:22.618728: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
2022-09-06 15:55:22.618735: [    0.000000] NUMA: No NUMA configuration found
2022-09-06 15:55:22.618742: [    0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x000000003fe5afff]
2022-09-06 15:55:22.618751: [    0.000000] NUMA: NODE_DATA [mem 0x3fc40b40-0x3fc42fff]
2022-09-06 15:55:22.618758: [    0.000000] Zone ranges:
2022-09-06 15:55:22.618765: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fe5afff]
2022-09-06 15:55:22.618772: [    0.000000]   DMA32    empty
2022-09-06 15:55:22.618781: [    0.000000]   Normal   empty
2022-09-06 15:55:22.618789: [    0.000000] Movable zone start for each node
2022-09-06 15:55:22.618796: [    0.000000] Early memory node ranges
2022-09-06 15:55:22.618802: [    0.000000]   node   0: [mem 0x0000000000000000-0x00000000052fffff]
2022-09-06 15:55:22.618809: [    0.000000]   node   0: [mem 0x0000000005300000-0x00000000072fffff]
2022-09-06 15:55:22.618818: [    0.000000]   node   0: [mem 0x0000000007300000-0x000000000fffffff]
2022-09-06 15:55:22.618825: [    0.000000]   node   0: [mem 0x0000000010000000-0x00000000101fffff]
2022-09-06 15:55:22.618832: [    0.000000]   node   0: [mem 0x0000000010200000-0x000000003fe5afff]
2022-09-06 15:55:22.618839: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003fe5afff]
2022-09-06 15:55:22.618848: [    0.000000] On node 0, zone DMA: 421 pages in unavailable ranges
2022-09-06 15:55:22.618855: [    0.000000] psci: probing for conduit method from DT.
2022-09-06 15:55:22.618862: [    0.000000] psci: PSCIv0.2 detected in firmware.
2022-09-06 15:55:22.618869: [    0.000000] psci: Using standard PSCI v0.2 function IDs
2022-09-06 15:55:22.618876: [    0.000000] psci: Trusted OS migration not required
2022-09-06 15:55:22.618885: [    0.000000] percpu: Embedded 20 pages/cpu s41432 r8192 d32296 u81920
2022-09-06 15:55:22.618892: [    0.000000] Detected VIPT I-cache on CPU0
2022-09-06 15:55:22.618899: [    0.000000] CPU features: kernel page table isolation forced ON by KASLR
2022-09-06 15:55:22.618906: [    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
2022-09-06 15:55:22.618913: [    0.000000] CPU features: detected: ARM erratum 845719
2022-09-06 15:55:22.618922: [    0.000000] Fallback order for Node 0: 0 
2022-09-06 15:55:22.618929: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 257633
2022-09-06 15:55:22.618935: [    0.000000] Policy zone: DMA
2022-09-06 15:55:22.618976: [    0.000000] Kernel command line: console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=10.0.70.21:/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq,tcp,hard,vers=3 init=/init rootwait usbcore.quirks=0bda:8153:k rootwait rootdelay=5 carrier_timeout=10 ip=dhcp
2022-09-06 15:55:22.618998: [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
2022-09-06 15:55:22.619007: [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
2022-09-06 15:55:22.619014: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
2022-09-06 15:55:22.619064: [    0.000000] Memory: 676520K/1046892K available (15936K kernel code, 3128K rwdata, 8236K rodata, 5568K init, 475K bss, 108228K reserved, 262144K cma-reserved)
2022-09-06 15:55:22.619080: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
2022-09-06 15:55:22.619088: [    0.000000] rcu: Preemptible hierarchical RCU implementation.
2022-09-06 15:55:22.619094: [    0.000000] rcu: 	RCU event tracing is enabled.
2022-09-06 15:55:22.619101: [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
2022-09-06 15:55:22.619310: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
2022-09-06 15:55:22.619366: [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
2022-09-06 15:55:22.619383: [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
2022-09-06 15:55:22.619390: [    0.000000] Root IRQ handler: gic_handle_irq
2022-09-06 15:55:22.619397: [    0.000000] GIC: Using split EOI/Deactivate mode
2022-09-06 15:55:22.619404: [    0.000000] random: get_random_bytes called from start_kernel+0x3fc/0x598 with crng_init=0
2022-09-06 15:55:22.619540: [    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
2022-09-06 15:55:22.619649: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
2022-09-06 15:55:22.619710: [    0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
2022-09-06 15:55:22.619719: [    0.000696] Console: colour dummy device 80x25
2022-09-06 15:55:22.619726: [    0.000771] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
2022-09-06 15:55:22.619793: [    0.000784] pid_max: default: 32768 minimum: 301
2022-09-06 15:55:22.620045: [    0.000840] LSM: Security Framework initializing
2022-09-06 15:55:22.620068: [    0.000938] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
2022-09-06 15:55:22.620077: [    0.000953] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
2022-09-06 15:55:22.620084: [    0.002194] cblist_init_generic: Setting adjustable number of callback queues.
2022-09-06 15:55:22.620262: [    0.002218] cblist_init_generic: Setting shift to 2 and lim to 1.
2022-09-06 15:55:22.620320: [    0.002347] rcu: Hierarchical SRCU implementation.
2022-09-06 15:55:22.620333: [    0.003459] EFI services will not be available.
2022-09-06 15:55:22.620340: [    0.003865] smp: Bringing up secondary CPUs ...
2022-09-06 15:55:22.620347: [    0.004442] Detected VIPT I-cache on CPU1
2022-09-06 15:55:22.620356: [    0.004517] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
2022-09-06 15:55:22.620362: [    0.005098] Detected VIPT I-cache on CPU2
2022-09-06 15:55:22.620369: [    0.005157] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
2022-09-06 15:55:22.620512: [    0.005705] Detected VIPT I-cache on CPU3
2022-09-06 15:55:22.620523: [    0.005761] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
2022-09-06 15:55:22.620531: [    0.005841] smp: Brought up 1 node, 4 CPUs
2022-09-06 15:55:22.620591: [    0.005865] SMP: Total of 4 processors activated.
2022-09-06 15:55:22.620611: [    0.005872] CPU features: detected: 32-bit EL0 Support
2022-09-06 15:55:22.620619: [    0.005876] CPU features: detected: 32-bit EL1 Support
2022-09-06 15:55:22.620625: [    0.005883] CPU features: detected: CRC32 instructions
2022-09-06 15:55:22.620632: [    0.012446] CPU: All CPU(s) started at EL2
2022-09-06 15:55:22.620684: [    0.012516] alternatives: patching kernel code
2022-09-06 15:55:22.620713: [    0.013819] devtmpfs: initialized
2022-09-06 15:55:22.620721: [    0.018338] KASLR disabled due to lack of seed
2022-09-06 15:55:22.620845: [    0.018525] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
2022-09-06 15:55:22.620986: [    0.018545] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
2022-09-06 15:55:22.621007: [    0.026495] pinctrl core: initialized pinctrl subsystem
2022-09-06 15:55:22.621073: [    0.027229] DMI not present or invalid.
2022-09-06 15:55:22.621153: [    0.027668] NET: Registered PF_NETLINK/PF_ROUTE protocol family
2022-09-06 15:55:22.621167: [    0.029115] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
2022-09-06 15:55:22.621245: [    0.029269] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
2022-09-06 15:55:22.621346: [    0.029362] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
2022-09-06 15:55:22.621405: [    0.029450] audit: initializing netlink subsys (disabled)
2022-09-06 15:55:22.621415: [    0.029643] audit: type=2000 audit(0.028:1): state=initialized audit_enabled=0 res=1
2022-09-06 15:55:22.621422: [    0.030601] thermal_sys: Registered thermal governor 'step_wise'
2022-09-06 15:55:22.621432: [    0.030614] thermal_sys: Registered thermal governor 'power_allocator'
2022-09-06 15:55:22.621488: [    0.030844] cpuidle: using governor menu
2022-09-06 15:55:22.621504: [    0.031077] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
2022-09-06 15:55:22.621511: [    0.031194] ASID allocator initialised with 32768 entries
2022-09-06 15:55:22.621518: [    0.032089] Serial: AMBA PL011 UART driver
2022-09-06 15:55:22.621563: [    0.047057] platform c883a000.hdmi-tx: Fixing up cyclic dependency with d0100000.vpu
2022-09-06 15:55:22.621641: [    0.049192] platform hdmi-connector: Fixing up cyclic dependency with c883a000.hdmi-tx
2022-09-06 15:55:22.621688: [    0.059154] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
2022-09-06 15:55:22.621756: [    0.059176] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
2022-09-06 15:55:22.621839: [    0.059182] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
2022-09-06 15:55:22.621856: [    0.059188] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
2022-09-06 15:55:22.621863: [    0.060746] ACPI: Interpreter disabled.
2022-09-06 15:55:22.621870: [    0.063553] iommu: Default domain type: Translated 
2022-09-06 15:55:22.621914: [    0.063574] iommu: DMA domain TLB invalidation policy: strict mode 
2022-09-06 15:55:22.621962: [    0.063891] vgaarb: loaded
2022-09-06 15:55:22.622094: [    0.064185] SCSI subsystem initialized
2022-09-06 15:55:22.622176: [    0.064552] usbcore: registered new interface driver usbfs
2022-09-06 15:55:22.622195: [    0.064593] usbcore: registered new interface driver hub
2022-09-06 15:55:22.622373: [    0.064624] usbcore: registered new device driver usb
2022-09-06 15:55:22.622474: [    0.065164] pps_core: LinuxPPS API ver. 1 registered
2022-09-06 15:55:22.622534: [    0.065172] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
2022-09-06 15:55:22.622546: [    0.065187] PTP clock support registered
2022-09-06 15:55:22.622555: [    0.065223] EDAC MC: Ver: 3.0.0
2022-09-06 15:55:22.622562: [    0.066769] FPGA manager framework
2022-09-06 15:55:22.622614: [    0.066874] Advanced Linux Sound Architecture Driver Initialized.
2022-09-06 15:55:22.622631: [    0.067873] clocksource: Switched to clocksource arch_sys_counter
2022-09-06 15:55:22.622643: [    0.068090] VFS: Disk quotas dquot_6.6.0
2022-09-06 15:55:22.622651: [    0.068135] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
2022-09-06 15:55:22.622718: [    0.068319] pnp: PnP ACPI: disabled
2022-09-06 15:55:22.622730: [    0.073378] NET: Registered PF_INET protocol family
2022-09-06 15:55:22.622739: [    0.073530] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
2022-09-06 15:55:22.622747: [    0.074205] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
2022-09-06 15:55:22.622800: [    0.074285] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
2022-09-06 15:55:22.622814: [    0.074349] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
2022-09-06 15:55:22.622823: [    0.074456] TCP: Hash tables configured (established 8192 bind 8192)
2022-09-06 15:55:22.622831: [    0.074582] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
2022-09-06 15:55:22.622837: [    0.074613] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
2022-09-06 15:55:22.622923: [    0.074756] NET: Registered PF_UNIX/PF_LOCAL protocol family
2022-09-06 15:55:22.622981: [    0.075138] RPC: Registered named UNIX socket transport module.
2022-09-06 15:55:22.622999: [    0.075152] RPC: Registered udp transport module.
2022-09-06 15:55:22.623006: [    0.075157] RPC: Registered tcp transport module.
2022-09-06 15:55:22.623049: [    0.075161] RPC: Registered tcp NFSv4.1 backchannel transport module.
2022-09-06 15:55:22.623073: [    0.075173] PCI: CLS 0 bytes, default 64
2022-09-06 15:55:22.623081: [    0.076021] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
2022-09-06 15:55:22.623088: [    0.076520] kvm [1]: IPA Size Limit: 40 bits
2022-09-06 15:55:22.623097: [    0.078006] kvm [1]: vgic interrupt IRQ9
2022-09-06 15:55:22.623162: [    0.078130] kvm [1]: Hyp mode initialized successfully
2022-09-06 15:55:22.623185: [    0.079576] workingset: timestamp_bits=42 max_order=18 bucket_order=0
2022-09-06 15:55:22.623263: [    0.085259] squashfs: version 4.0 (2009/01/31) Phillip Lougher
2022-09-06 15:55:22.623322: [    0.085886] NFS: Registering the id_resolver key type
2022-09-06 15:55:22.623340: [    0.085925] Key type id_resolver registered
2022-09-06 15:55:22.623348: [    0.085930] Key type id_legacy registered
2022-09-06 15:55:22.623415: [    0.085999] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
2022-09-06 15:55:22.623427: [    0.086007] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
2022-09-06 15:55:22.623505: [    0.086176] 9p: Installing v9fs 9p2000 file system support
2022-09-06 15:55:22.623567: [    0.122705] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
2022-09-06 15:55:22.623585: [    0.122720] io scheduler mq-deadline registered
2022-09-06 15:55:22.623593: [    0.122726] io scheduler kyber registered
2022-09-06 15:55:22.623600: [    0.123674] irq_meson_gpio: 110 to 8 gpio interrupt mux initialized
2022-09-06 15:55:22.623641: [    0.133443] EINJ: ACPI disabled.
2022-09-06 15:55:22.623662: [    0.145920] soc soc0: Amlogic Meson GXL (S805X) Revision 21:d (34:2) Detected
2022-09-06 15:55:22.623740: [    0.151847] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
2022-09-06 15:55:22.623799: [    0.154587] c81004c0.serial: ttyAML0 at MMIO 0xc81004c0 (irq = 22, base_baud = 1500000) is a meson_uart
2022-09-06 15:55:22.623809: [    1.089867] printk: console [ttyAML0] enabled
2022-09-06 15:55:22.623818: [    1.094921] msm_serial: driver initialized
2022-09-06 15:55:22.623867: [    1.110551] lima d00c0000.gpu: gp - mali450 version major 0 minor 0
2022-09-06 15:55:22.623959: [    1.111226] lima d00c0000.gpu: pp0 - mali450 version major 0 minor 0
2022-09-06 15:55:22.623983: [    1.117554] lima d00c0000.gpu: pp1 - mali450 version major 0 minor 0
2022-09-06 15:55:22.623991: [    1.123821] lima d00c0000.gpu: pp2 - mali450 version major 0 minor 0
2022-09-06 15:55:22.623998: [    1.130121] lima d00c0000.gpu: l2 cache 8K, 4-way, 64byte cache line, 128bit external bus
2022-09-06 15:55:22.624007: [    1.138197] lima d00c0000.gpu: l2 cache 64K, 4-way, 64byte cache line, 128bit external bus
2022-09-06 15:55:22.624183: [    1.146794] lima d00c0000.gpu: bus rate = 166666667
2022-09-06 15:55:22.624260: [    1.151237] lima d00c0000.gpu: mod rate = 24000000
2022-09-06 15:55:22.624375: [    1.156039] lima d00c0000.gpu: dev_pm_opp_set_regulators: no regulator (mali) found: -19
2022-09-06 15:55:22.624455: [    1.164566] lima d00c0000.gpu: Failed to register cooling device
2022-09-06 15:55:22.624522: [    1.170231] [drm] Initialized lima 1.1.0 20191231 for d00c0000.gpu on minor 0
2022-09-06 15:55:22.624537: [    1.183008] loop: module loaded
2022-09-06 15:55:22.624547: [    1.183832] megasas: 07.719.03.00-rc1
2022-09-06 15:55:22.624554: [    1.189512] tun: Universal TUN/TAP device driver, 1.6
2022-09-06 15:55:22.624621: [    1.190427] thunder_xcv, ver 1.0
2022-09-06 15:55:22.624639: [    1.192419] thunder_bgx, ver 1.0
2022-09-06 15:55:22.624710: [    1.195574] nicpf, ver 1.0
2022-09-06 15:55:22.624805: [    1.199139] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version
2022-09-06 15:55:22.624861: [    1.205389] hns3: Copyright (c) 2017 Huawei Corporation.
2022-09-06 15:55:22.624993: [    1.210736] hclge is initializing
2022-09-06 15:55:22.625119: [    1.213941] e1000: Intel(R) PRO/1000 Network Driver
2022-09-06 15:55:22.625196: [    1.218749] e1000: Copyright (c) 1999-2006 Intel Corporation.
2022-09-06 15:55:22.625270: [    1.224501] e1000e: Intel(R) PRO/1000 Network Driver
2022-09-06 15:55:22.625290: [    1.229359] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
2022-09-06 15:55:22.625299: [    1.235259] igb: Intel(R) Gigabit Ethernet Network Driver
2022-09-06 15:55:22.625305: [    1.240577] igb: Copyright (c) 2007-2014 Intel Corporation.
2022-09-06 15:55:22.625312: [    1.246120] igbvf: Intel(R) Gigabit Virtual Function Network Driver
2022-09-06 15:55:22.625370: [    1.252300] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
2022-09-06 15:55:22.625393: [    1.258192] sky2: driver version 1.30
2022-09-06 15:55:22.625454: [    1.263588] usbcore: registered new interface driver r8152
2022-09-06 15:55:22.625483: [    1.267273] usbcore: registered new interface driver asix
2022-09-06 15:55:22.625567: [    1.272607] usbcore: registered new interface driver ax88179_178a
2022-09-06 15:55:22.625625: [    1.278639] usbcore: registered new interface driver cdc_ether
2022-09-06 15:55:22.625637: [    1.284418] usbcore: registered new interface driver smsc95xx
2022-09-06 15:55:22.625644: [    1.290100] usbcore: registered new interface driver net1080
2022-09-06 15:55:22.625654: [    1.295705] usbcore: registered new interface driver cdc_subset
2022-09-06 15:55:22.625697: [    1.301578] usbcore: registered new interface driver zaurus
2022-09-06 15:55:22.625721: [    1.307117] usbcore: registered new interface driver cdc_ncm
2022-09-06 15:55:22.625730: [    1.312870] VFIO - User Level meta-driver version: 0.3
2022-09-06 15:55:22.625737: [    1.320077] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
2022-09-06 15:55:22.625787: [    1.324243] ehci-pci: EHCI PCI platform driver
2022-09-06 15:55:22.625884: [    1.328688] ehci-platform: EHCI generic platform driver
2022-09-06 15:55:22.625898: [    1.334008] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
2022-09-06 15:55:22.625982: [    1.339964] ohci-pci: OHCI PCI platform driver
2022-09-06 15:55:22.626036: [    1.344383] ohci-platform: OHCI generic platform driver
2022-09-06 15:55:22.626120: [    1.350278] usbcore: registered new interface driver usb-storage
2022-09-06 15:55:22.626200: [    1.357915] i2c_dev: i2c /dev entries driver
2022-09-06 15:55:22.626248: [    1.365301] sdhci: Secure Digital Host Controller Interface driver
2022-09-06 15:55:22.626306: [    1.365844] sdhci: Copyright(c) Pierre Ossman
2022-09-06 15:55:22.626316: [    1.370829] Synopsys Designware Multimedia Card Interface Driver
2022-09-06 15:55:22.626323: [    1.377263] sdhci-pltfm: SDHCI platform and OF driver helper
2022-09-06 15:55:22.626332: [    1.377626] meson-gx-mmc d0074000.mmc: allocated mmc-pwrseq
2022-09-06 15:55:22.626389: [    1.387345] ledtrig-cpu: registered to indicate activity on CPUs
2022-09-06 15:55:22.626402: [    1.394696] meson-sm: secure-monitor enabled
2022-09-06 15:55:22.626409: [    1.398611] usbcore: registered new interface driver usbhid
2022-09-06 15:55:22.626418: [    1.402966] usbhid: USB HID core driver
2022-09-06 15:55:22.626457: [    1.408011] platform-mhu c883c404.mailbox: Platform MHU Mailbox registered
2022-09-06 15:55:22.626546: [    1.418208] gnss: GNSS driver registered with major 511
2022-09-06 15:55:22.626565: [    1.422212] NET: Registered PF_PACKET protocol family
2022-09-06 15:55:22.626573: [    1.423899] 9pnet: Installing 9P2000 support
2022-09-06 15:55:22.626628: [    1.428020] Key type dns_resolver registered
2022-09-06 15:55:22.626642: [    1.451735] meson-drm d0100000.vpu: Queued 1 outputs on vpu
2022-09-06 15:55:22.626652: [    1.452397] meson-drm d0100000.vpu: CVBS Output connector not available
2022-09-06 15:55:22.626660: [    1.483942] meson-dw-hdmi c883a000.hdmi-tx: Detected HDMI TX controller v2.01a with HDCP (meson_dw_hdmi_phy)
2022-09-06 15:55:22.626744: [    1.488196] mmc1: new HS200 MMC card at address 0001
2022-09-06 15:55:22.626803: [    1.493703] meson-dw-hdmi c883a000.hdmi-tx: registered DesignWare HDMI I2C bus driver
2022-09-06 15:55:22.626818: [    1.493763] mmcblk1: mmc1:0001 H8G4a� 7.28 GiB 
2022-09-06 15:55:22.626830: [    1.502244] meson-drm d0100000.vpu: bound c883a000.hdmi-tx (ops meson_dw_hdmi_ops)
2022-09-06 15:55:22.626883: [    1.508441] mmcblk1boot0: mmc1:0001 H8G4a� 4.00 MiB 
2022-09-06 15:55:22.626899: [    1.513473] [drm] Initialized meson 1.0.0 20161109 for d0100000.vpu on minor 1
2022-09-06 15:55:22.626910: [    1.519310] mmcblk1boot1: mmc1:0001 H8G4a� 4.00 MiB 
2022-09-06 15:55:22.626918: [    1.525031] meson-drm d0100000.vpu: [drm] Cannot find any crtc or sizes
2022-09-06 15:55:22.626978: [    1.531154] mmcblk1rpmb: mmc1:0001 H8G4a� 4.00 MiB, chardev (234:0)
2022-09-06 15:55:22.626996: [    1.540865] meson8b-dwmac c9410000.ethernet: IRQ eth_wake_irq not found
2022-09-06 15:55:22.627004: [    1.549140] meson8b-dwmac c9410000.ethernet: IRQ eth_lpi not found
2022-09-06 15:55:22.627011: [    1.555328] meson8b-dwmac c9410000.ethernet: PTP uses main clock
2022-09-06 15:55:22.627071: [    1.561931] meson8b-dwmac c9410000.ethernet: User ID: 0x11, Synopsys ID: 0x37
2022-09-06 15:55:22.627155: [    1.568283] meson8b-dwmac c9410000.ethernet: 	DWMAC1000
2022-09-06 15:55:22.627171: [    1.573430] meson8b-dwmac c9410000.ethernet: DMA HW capability register supported
2022-09-06 15:55:22.627184: [    1.580846] meson8b-dwmac c9410000.ethernet: RX Checksum Offload Engine supported
2022-09-06 15:55:22.627300: [    1.588263] meson8b-dwmac c9410000.ethernet: COE Type 2
2022-09-06 15:55:22.627323: [    1.593437] meson8b-dwmac c9410000.ethernet: TX Checksum insertion supported
2022-09-06 15:55:22.627332: [    1.600423] meson8b-dwmac c9410000.ethernet: Wake-Up On Lan supported
2022-09-06 15:55:22.627339: [    1.606874] meson8b-dwmac c9410000.ethernet: Normal descriptors
2022-09-06 15:55:22.627348: [    1.612673] meson8b-dwmac c9410000.ethernet: Ring mode enabled
2022-09-06 15:55:22.627410: [    1.618456] meson8b-dwmac c9410000.ethernet: Enable RX Mitigation via HW Watchdog Timer
2022-09-06 15:55:22.627420: [    1.629222] dwc3-meson-g12a d0078080.usb: USB2 ports: 2
2022-09-06 15:55:22.627428: [    1.631555] dwc3-meson-g12a d0078080.usb: USB3 ports: 0
2022-09-06 15:55:22.627434: [    2.149749] dwc2 c9100000.usb: supply vusb_d not found, using dummy regulator
2022-09-06 15:55:22.627485: [    2.151401] dwc2 c9100000.usb: supply vusb_a not found, using dummy regulator
2022-09-06 15:55:22.627571: [    2.158633] dwc2 c9100000.usb: EPs: 7, dedicated fifos, 712 entries in SPRAM
2022-09-06 15:55:22.627592: [    2.168181] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
2022-09-06 15:55:22.627656: [    2.170761] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
2022-09-06 15:55:22.627729: [    2.178488] xhci-hcd xhci-hcd.2.auto: hcc params 0x0228f664 hci version 0x100 quirks 0x0000000002010010
2022-09-06 15:55:22.627779: [    2.187707] xhci-hcd xhci-hcd.2.auto: irq 47, io mem 0xc9000000
2022-09-06 15:55:22.627884: [    2.194254] hub 1-0:1.0: USB hub found
2022-09-06 15:55:22.627960: [    2.197263] hub 1-0:1.0: 2 ports detected
2022-09-06 15:55:22.628044: [    2.201508] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
2022-09-06 15:55:22.628106: [    2.206644] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
2022-09-06 15:55:22.628192: [    2.214233] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
2022-09-06 15:55:22.628210: [    2.220736] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
2022-09-06 15:55:22.628218: [    2.229297] hub 2-0:1.0: USB hub found
2022-09-06 15:55:22.628225: [    2.232454] hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19)
2022-09-06 15:55:22.628302: [    2.241822] scpi_protocol scpi: SCP Protocol legacy pre-1.0 firmware
2022-09-06 15:55:22.628388: [    2.330883] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)
2022-09-06 15:55:22.628449: [    2.336693] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0
2022-09-06 15:55:22.628463: [    2.354215] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found
2022-09-06 15:55:22.628479: [    2.356195] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW
2022-09-06 15:55:22.628533: [    2.363582] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode
2022-09-06 15:55:22.628551: [    4.221266] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off
2022-09-06 15:55:22.628866: 	 #######################T ######
2022-09-06 15:55:22.628885: 	 3.2 MiB/s
2022-09-06 15:55:22.628891: done
2022-09-06 15:55:22.628896: Bytes transferred = 33808960 (203e240 hex)
2022-09-06 15:55:22.628900: $ setenv initrd_size ${filesize}

2022-09-06 15:55:22.628907: => setenv initrd_size ${filesize}
2022-09-06 15:55:22.628912: setenv initrd_size ${filesize}
2022-09-06 15:55:22.628923: $ tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb

2022-09-06 15:55:22.628930: => tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb
2022-09-06 15:55:22.628935: tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb
2022-09-06 15:55:22.628940: Speed: 100, full duplex
2022-09-06 15:55:22.628983: Using ethernet@c9410000 device
2022-09-06 15:55:22.628988: TFTP from server 10.0.70.21; our IP address is 10.0.70.83
2022-09-06 15:55:22.628993: Filename '25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb'.
2022-09-06 15:55:22.628997: Load address: 0x8008000
2022-09-06 15:55:22.629002: Loading: *##
2022-09-06 15:55:22.629006: 	 3.8 MiB/s
2022-09-06 15:55:22.629010: done
2022-09-06 15:55:22.629015: Bytes transferred = 28084 (6db4 hex)
2022-09-06 15:55:22.629019: $ setenv bootargs console=ttyAML0,115200n8

2022-09-06 15:55:22.629116: =>setenv bootargs console=ttyAML0,115200n8
2022-09-06 15:55:22.629129:  setenv bootargs console=ttyAML0,115200n8
2022-09-06 15:55:22.629174: $ setenv bootargs ${bootargs} root=/dev/nfs rw

2022-09-06 15:55:22.629190: =>setenv bootargs ${bootargs} root=/dev/nfs rw
2022-09-06 15:55:22.629196:  setenv bootargs ${bootargs} root=/dev/nfs rw
2022-09-06 15:55:22.629200: $ setenv bootargs ${bootargs} nfsroot=10.0.70.21:/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq,tcp,hard,vers=3 init=/init rootwait usbcore.quirks=0bda:8153:k

2022-09-06 15:55:22.629207: =>setenv bootargs ${bootargs} nfsroot=10.0.70.21:/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq,tcp,hard,vers=3 init=/init rootwait usbcore.quirks=0bda:8153:k
2022-09-06 15:55:22.629212:  setenv bootargs ${bootargs} nfsroot=10.0.70.21:/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq,tcp,hard,vers=3 init=/init rootwait usbcore.quirks=0bda:8153:k
2022-09-06 15:55:22.629216: $ setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10

2022-09-06 15:55:22.629264: =>setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10
2022-09-06 15:55:22.629275:  setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10
2022-09-06 15:55:22.629280: $ setenv bootargs ${bootargs} ip=dhcp

2022-09-06 15:55:22.629286: =>setenv bootargs ${bootargs} ip=dhcp
2022-09-06 15:55:22.629291:  setenv bootargs ${bootargs} ip=dhcp
2022-09-06 15:55:22.629297: $ bootm 0x08080000 - 0x08008000

2022-09-06 15:55:22.629304: => bootm 0x08080000 - 0x08008000
2022-09-06 15:55:22.629345: bootm 0x08080000 - 0x08008000
2022-09-06 15:55:22.629385: ## Booting kernel from Legacy Image at 08080000 ...
2022-09-06 15:55:22.629454:    Image Name:   
2022-09-06 15:55:22.629514:    Created:      2022-09-06  15:54:52 UTC
2022-09-06 15:55:22.629521:    Image Type:   AArch64 Linux Kernel Image (uncompressed)
2022-09-06 15:55:22.629528:    Data Size:    33808896 Bytes = 32.2 MiB
2022-09-06 15:55:22.629533:    Load Address: 08080000
2022-09-06 15:55:22.629538:    Entry Point:  08080000
2022-09-06 15:55:22.629542:    Verifying Checksum ... OK
2022-09-06 15:55:22.629596: ## Flattened Device Tree blob at 08008000
2022-09-06 15:55:22.629604:    Booting using the fdt blob at 0x8008000
2022-09-06 15:55:22.629609:    Loading Kernel Image
2022-09-06 15:55:22.629613:    Loading Device Tree to 000000001fff6000, end 000000001ffffdb3 ... OK
2022-09-06 15:55:22.629618: Starting kernel ...
2022-09-06 15:55:22.629623: Parsing kernel messages
2022-09-06 15:55:22.629627: [    0.000000] 	Trampoline variant of Tasks RCU enabled.
2022-09-06 15:55:22.629632: domain-0 init dvfs: 4
2022-09-06 15:55:27.777477: [    4.248085] Sending DHCP requests .., OK
2022-09-06 15:55:27.777531: [    6.688463] IP-Config: Got DHCP answer from 10.0.70.100, my address is 10.0.70.83
2022-09-06 15:55:27.777551: [    6.693909] IP-Config: Complete:
2022-09-06 15:55:27.777590: [    6.697073]      device=eth0, hwaddr=5e:de:60:b9:7e:8a, ipaddr=10.0.70.83, mask=255.255.255.0, gw=10.0.70.100
2022-09-06 15:55:27.777605: [    6.706894]      host=meson-gxl-s805x-libretech-ac-3, domain=enunes.eu, nis-domain=(none)
2022-09-06 15:55:27.777617: [    6.715004]      bootserver=0.0.0.0, rootserver=10.0.70.21, rootpath=
2022-09-06 15:55:27.777644: [    6.715071]      nameserver0=10.0.70.100
2022-09-06 15:55:27.777678: [    6.728187] ALSA device list:
2022-09-06 15:55:27.777708: [    6.728354]   No soundcards found.
2022-09-06 15:55:27.777738: [    6.737605] Waiting 5 sec before mounting root device...
2022-09-06 15:55:32.829320: [   11.786422] VFS: Mounted root (nfs filesystem) on device 0:21.
2022-09-06 15:55:32.829492: [   11.787214] devtmpfs: mounted
2022-09-06 15:55:32.829682: [   11.791661] Freeing unused kernel memory: 5568K
2022-09-06 15:55:32.829779: [   11.807990] Run /init as init process
2022-09-06 15:55:32.829978: [   11.830847] random: fast init done
2022-09-06 15:55:32.830609: [   13.190492] <LAVA_SIGNAL_STARTRUN 0_mesa 25747_1.4.2.4.1>
2022-09-06 15:55:32.830935: [   14.257177] NET: Registered PF_INET6 protocol family
2022-09-06 15:55:32.831098: [   14.258499] Segment Routing with IPv6
2022-09-06 15:55:32.831232: [   14.260204] In-situ OAM (IOAM) with IPv6
2022-09-06 15:55:32.831567: [   17.011896] random: crng init done
2022-09-06 15:55:32.831774: sh: 0: can't access tty; job control turned off
2022-09-06 15:55:32.831887: $ 

2022-09-06 15:55:32.832137: lava-shell:
2022-09-06 15:55:32.832284: $ export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq'

2022-09-06 15:55:32.832559: lava-shell:export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq'
2022-09-06 15:55:32.832696: export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq'
2022-09-06 15:55:32.832836: $ export NFS_SERVER_IP='10.0.70.21'

2022-09-06 15:55:32.833109: lava-shell:export NFS_SERVER_IP='10.0.70.21'
2022-09-06 15:55:32.833229: export NFS_SERVER_IP='10.0.70.21'
2022-09-06 15:55:32.833369: end: 2 uboot-action (duration 00:00:33) [common]
2022-09-06 15:55:32.833511: start: 3 lava-test-retry (timeout 00:28:14) [common]
2022-09-06 15:55:32.833651: $ #

2022-09-06 15:55:32.833945: lava-shell:#
2022-09-06 15:55:32.834062: #
2022-09-06 15:55:32.834201: $ export SHELL=/bin/sh

2022-09-06 15:55:32.834453: lava-shell:export SHELL=/bin/sh
2022-09-06 15:55:32.834592: export SHELL=/bin/sh
2022-09-06 15:55:32.834733: $ . /lava-25747/environment

2022-09-06 15:55:32.834981: lava-shell:. /lava-25747/environment
2022-09-06 15:55:32.835125: . /lava-25747/environment
2022-09-06 15:55:32.835262: $ /lava-25747/bin/lava-test-runner /lava-25747/0

2022-09-06 15:55:32.835519: lava-shell:/lava-25747/bin/lava-test-runner /lava-25747/0
2022-09-06 15:55:32.835640: Test shell timeout: 10s (minimum of the action and connection timeout)
2022-09-06 15:55:32.835790: /lava-25747/bin/lava-test-runner /lava-25747/0
2022-09-06 15:55:32.835927: + export TESTRUN_ID=0_mesa
2022-09-06 15:55:32.836067: + cd /lava-25747/0/tests/0_mesa
2022-09-06 15:55:32.836206: + cat uuid
2022-09-06 15:55:32.836344: + UUID=25747_1.4.2.4.1
2022-09-06 15:55:32.836486: + set +x
2022-09-06 15:55:32.836625: section_end:1662479732:lava_boot

2022-09-06 15:55:32.836879: section_start:1662479732:0_mesa-25747_1-4-2-4-1
test_suite 0_mesa 25747_1.4.2.4.1 - Timeout: 0:05:00
2022-09-06 15:55:32.837136: Starting test lava.0_mesa (25747_1.4.2.4.1)
2022-09-06 15:55:32.837256: Skipping test definition patterns.
2022-09-06 15:55:32.837395: + set -ex
2022-09-06 15:55:32.837542: + cd /
2022-09-06 15:55:32.837684: + mount -t proc none /proc
2022-09-06 15:55:32.837830: + mount -t sysfs none /sys
2022-09-06 15:55:32.837971: + mount -t debugfs none /sys/kernel/debug
2022-09-06 15:55:32.838112: + mount -t devtmpfs none /dev
2022-09-06 15:55:32.838260: mount: /dev: none already mounted on /proc.
2022-09-06 15:55:32.838403: + echo possibly already mounted
2022-09-06 15:55:32.838549: possibly already mounted
2022-09-06 15:55:32.838691: + mkdir -p /dev/pts
2022-09-06 15:55:32.838834: + mount -t devpts devpts /dev/pts
2022-09-06 15:55:32.838981: + mount -t tmpfs tmpfs /tmp
2022-09-06 15:55:32.839123: + echo nameserver 8.8.8.8
2022-09-06 15:55:32.839266: + [ -z 10.0.70.21 ]
2022-09-06 15:55:32.839410: + echo 10.0.70.21 caching-proxy
2022-09-06 15:55:32.839554: + sntp -sS pool.ntp.org
2022-09-06 15:55:32.839697: sntp 4.2.8p15@1.3728-o Wed Sep 23 11:46:38 UTC 2020 (1)
2022-09-06 15:55:32.839840: kod_init_kod_db(): Cannot open KoD db file /var/lib/sntp/kod: No such file or directory
2022-09-06 15:55:32.839999: 1970-01-01 00:00:14.369008 (+0000) +1662479713.703259 +/- 1108319809.162661 pool.ntp.org 78.108.102.237 s2 no-leap
2022-09-06 15:55:32.840148: + break
2022-09-06 15:55:32.840290: + set +x
2022-09-06 15:55:32.840436: + echo export CI_JOB_JWT_FILE=/minio_jwt
2022-09-06 15:55:32.840581: + mkdir -p /builds/enunes/mesa
2022-09-06 15:55:32.840723: + wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/mesa-arm64.tar.zst
2022-09-06 15:55:32.840866: + tar --zstd -x -C /builds/enunes/mesa
2022-09-06 15:55:32.841011: --2022-09-06 15:55:28--  https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/mesa-arm64.tar.zst
2022-09-06 15:55:32.841176: Resolving minio-packet.freedesktop.org (minio-packet.freedesktop.org)... 147.75.198.117
2022-09-06 15:55:32.841327: Connecting to minio-packet.freedesktop.org (minio-packet.freedesktop.org)|147.75.198.117|:443... connected.
2022-09-06 15:55:38.171601: HTTP request sent, awaiting response... 
2022-09-06 15:55:38.171736:   HTTP/1.1 200 OK
2022-09-06 15:55:38.171776:   Date: Tue, 06 Sep 2022 15:55:31 GMT
2022-09-06 15:55:38.171813:   Content-Type: binary/octet-stream
2022-09-06 15:55:38.171850:   Content-Length: 13549533
2022-09-06 15:55:38.171887:   Connection: keep-alive
2022-09-06 15:55:38.171924:   Accept-Ranges: bytes
2022-09-06 15:55:38.171961:   Content-Security-Policy: block-all-mixed-content
2022-09-06 15:55:38.172007:   ETag: \"141b73ea0ae0cc1bd0f0ce50a433d407-2\"
2022-09-06 15:55:38.172045:   Last-Modified: Tue, 06 Sep 2022 15:52:40 GMT
2022-09-06 15:55:38.172081:   Vary: Origin
2022-09-06 15:55:38.172118:   X-Amz-Request-Id: 17125081D68BB24E
2022-09-06 15:55:38.172154:   X-Xss-Protection: 1; mode=block
2022-09-06 15:55:38.172190:   x-amz-expiration: expiry-date=\"Fri, 07 Oct 2022 00:00:00 GMT\", rule-id=\"Artifacts - 4 weeks\"
2022-09-06 15:55:38.172226:   Strict-Transport-Security: max-age=15724800; includeSubDomains
2022-09-06 15:55:38.172262:   Access-Control-Allow-Origin: *
2022-09-06 15:55:38.172299: Length: 13549533 (13M) [binary/octet-stream]
2022-09-06 15:55:38.172342: Saving to: 'STDOUT'
2022-09-06 15:55:48.220621:      0K ........ ....                      100% 1.14M=11s
2022-09-06 15:55:48.220808: 2022-09-06 15:55:42 (1.14 MB/s) - written to stdout [13549533/13549533]
2022-09-06 15:55:48.220962: + wget -S --progress=dot:giga -O- https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136/job-rootfs-overlay.tar.gz
2022-09-06 15:55:48.221125: + tar -xz -C /
2022-09-06 15:55:48.221257: --2022-09-06 15:55:42--  https://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136/job-rootfs-overlay.tar.gz
2022-09-06 15:55:48.221382: Resolving minio-packet.freedesktop.org (minio-packet.freedesktop.org)... 147.75.198.117
2022-09-06 15:55:48.221518: Connecting to minio-packet.freedesktop.org (minio-packet.freedesktop.org)|147.75.198.117|:443... connected.
2022-09-06 15:55:48.221667: HTTP request sent, awaiting response... 
2022-09-06 15:55:53.248662: [   30.794467] <LAVA_SIGNAL_STARTTC mesa-ci_lima-mali450-deqp-egl:arm64>
2022-09-06 15:55:53.249802:   HTTP/1.1 200 OK
2022-09-06 15:55:53.249850:   Date: Tue, 06 Sep 2022 15:55:43 GMT
2022-09-06 15:55:53.250013:   Content-Type: binary/octet-stream
2022-09-06 15:55:53.250056:   Content-Length: 8813
2022-09-06 15:55:53.250163:   Connection: keep-alive
2022-09-06 15:55:53.250253:   Accept-Ranges: bytes
2022-09-06 15:55:53.250395:   Content-Security-Policy: block-all-mixed-content
2022-09-06 15:55:53.250435:   ETag: \"823ffb7e46f908d48e413e4c3860f730\"
2022-09-06 15:55:53.250527:   Last-Modified: Tue, 06 Sep 2022 15:53:25 GMT
2022-09-06 15:55:53.250703:   Vary: Origin
2022-09-06 15:55:53.250810:   X-Amz-Request-Id: 17125084AFFBDC2B
2022-09-06 15:55:53.250848:   X-Xss-Protection: 1; mode=block
2022-09-06 15:55:53.250936:   x-amz-expiration: expiry-date=\"Fri, 07 Oct 2022 00:00:00 GMT\", rule-id=\"Artifacts - 4 weeks\"
2022-09-06 15:55:53.251128:   Strict-Transport-Security: max-age=15724800; includeSubDomains
2022-09-06 15:55:53.251247:   Access-Control-Allow-Origin: *
2022-09-06 15:55:53.251287: Length: 8813 (8.6K) [binary/octet-stream]
2022-09-06 15:55:53.251388: Saving to: 'STDOUT'
2022-09-06 15:55:53.251542:      0K                                    100% 90.5K=0.1s
2022-09-06 15:55:53.251583: 2022-09-06 15:55:43 (90.5 KB/s) - written to stdout [8813/8813]
2022-09-06 15:55:53.251672: + sleep 1
2022-09-06 15:55:53.251842: + lava-test-case mesa-ci_lima-mali450-deqp-egl:arm64 --shell /init-stage2.sh
2022-09-06 15:55:53.251883: section_end:1662479753:0_mesa-25747_1-4-2-4-1

2022-09-06 15:55:53.252002: section_start:1662479753:mesa-ci_lima-mali450-deqp-egl-arm64
test_case mesa-ci_lima-mali450-deqp-egl:arm64 - Timeout: 0:30:00
2022-09-06 15:55:53.252196: + [ -z  ]
2022-09-06 15:55:53.252236: + [  = true ]
2022-09-06 15:55:53.252324: + ln -sf /builds/enunes/mesa/install /install
2022-09-06 15:55:53.252502: + export LD_LIBRARY_PATH=/install/lib
2022-09-06 15:55:53.252542: + export LIBGL_DRIVERS_PATH=/install/lib/dri
2022-09-06 15:55:53.252651: + export XDG_CACHE_HOME=/tmp
2022-09-06 15:55:53.252739: + python3 -c import sys;print(\":\".join(sys.path))
2022-09-06 15:55:53.252914: + export PYTHONPATH=:/usr/lib/python39.zip:/usr/lib/python3.9:/usr/lib/python3.9/lib-dynload:/usr/local/lib/python3.9/dist-packages:/usr/lib/python3/dist-packages:/usr/lib/python3.9/dist-packages
2022-09-06 15:55:53.253022: + [  = true ]
2022-09-06 15:55:53.253063: + [ -n  ]
2022-09-06 15:55:53.253174: + BACKGROUND_PIDS=169 
2022-09-06 15:55:53.253263: + [ -n+  1 ]
2022-09-06 15:55:53.253360: /capture-devcoredump.sh
2022-09-06 15:55:53.253453: + echo touch /xorg-started; sleep 100000
2022-09-06 15:55:53.253566: + BACKGROUND_PIDS=170 169 
2022-09-06 15:55:53.253656: + [ -e+  /xorg-started ]
2022-09-06 15:55:53.253763: env xinit /bin/sh /xorg-script -- /usr/bin/Xorg -noreset -s 0 -dpms -logfile /Xorg.0.log
2022-09-06 15:55:53.253870: + sleep 5
2022-09-06 15:55:53.253959: X.Org X Server 1.20.11
2022-09-06 15:55:53.254051: X Protocol Version 11, Revision 0
2022-09-06 15:55:53.254188: Build Operating System: linux Debian
2022-09-06 15:55:53.254293: Current Operating System: Linux meson-gxl-s805x-libretech-ac-3 5.17.9linux-v5.17-for-mesa-ci-b78f7870d97b.tar.bz2 #1 SMP PREEMPT Tue Aug 30 13:32:45 UTC 2022 aarch64
2022-09-06 15:55:53.254334: Kernel command line: console=ttyAML0,115200n8 root=/dev/nfs rw nfsroot=10.0.70.21:/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq,tcp,hard,vers=3 init=/init rootwait usbcore.quirks=0bda:8153:k rootwait rootdelay=5 carrier_timeout=10 ip=dhcp
2022-09-06 15:55:53.254434: Build Date: 16 December 2021  05:08:23PM
2022-09-06 15:55:53.254590: xorg-server 2:1.20.11-1+deb11u1 (https://www.debian.org/support) 
2022-09-06 15:55:53.254637: Current version of pixman: 0.40.0
2022-09-06 15:55:53.254725: 	Before reporting problems, check http://wiki.x.org
2022-09-06 15:55:53.254916: 	to make sure that you have the latest version.
2022-09-06 15:55:53.255023: Markers: (--) probed, (**) from config file, (==) default setting,
2022-09-06 15:55:53.255063: 	(++) from command line, (!!) notice, (II) informational,
2022-09-06 15:55:53.255151: 	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
2022-09-06 15:55:53.255324: (++) Log file: \"/Xorg.0.log\", Time: Tue Sep  6 15:55:45 2022
2022-09-06 15:55:53.255376: (==) Using system config directory \"/usr/share/X11/xorg.conf.d\"
2022-09-06 15:55:53.255468: (EE) 
2022-09-06 15:55:53.255620: Fatal server error:
2022-09-06 15:55:53.255661: (EE) no screens found(EE) 
2022-09-06 15:55:53.255760: (EE) 
2022-09-06 15:55:53.255871: Please consult the The X.Org Foundation support 
2022-09-06 15:55:53.255978: 	 at http://wiki.x.org
2022-09-06 15:55:53.256018:  for help. 
2022-09-06 15:55:53.256106: (EE) Please also check the log file at \"/Xorg.0.log\" for additional information.
2022-09-06 15:55:53.256283: (EE) 
2022-09-06 15:55:53.256400: (EE) Server terminated with error (1). Closing log file.
2022-09-06 15:55:53.256441: + [ -e /xorg-started ]
2022-09-06 15:55:58.278200: + sleep 5
2022-09-06 15:55:58.278295: + [ -e /xorg-started ]
2022-09-06 15:56:03.306770: + sleep 5
2022-09-06 15:56:03.307173: + [ -e /xorg-started ]
2022-09-06 15:56:08.329296: + sleep 5
2022-09-06 15:56:08.329654: xinit: giving up
2022-09-06 15:56:08.329788: xinit: unable to connect to X server: Invalid argument
2022-09-06 15:56:08.329832: xinit: server error
2022-09-06 15:56:08.329873: + [ -e /xorg-started ]
2022-09-06 15:56:13.352896: + sleep 5
2022-09-06 15:56:13.353247: + export DISPLAY=:0
2022-09-06 15:56:18.437989: + RESULT=fail
2022-09-06 15:56:18.438080: + set +e
2022-09-06 15:56:18.438121: + sh -c /install/deqp-runner.sh
2022-09-06 15:56:18.438219: section_start:1662479770:test_setup[collapsed=true]
preparing test setup
2022-09-06 15:56:18.438348: + ln -sf /builds/enunes/mesa/install /install
2022-09-06 15:56:18.438422: + '[' -z lima ']'
2022-09-06 15:56:18.438495: ++ pwd
2022-09-06 15:56:18.438559: + INSTALL=//install
2022-09-06 15:56:18.438633: ++ pwd
2022-09-06 15:56:18.438720: + export LD_LIBRARY_PATH=//install/lib/
2022-09-06 15:56:18.438792: + LD_LIBRARY_PATH=//install/lib/
2022-09-06 15:56:18.438865: + export EGL_PLATFORM=surfaceless
2022-09-06 15:56:18.439036: + EGL_PLATFORM=surfaceless
2022-09-06 15:56:18.439152: ++ pwd
2022-09-06 15:56:18.439193: ++ uname -m
2022-09-06 15:56:18.439272: + export VK_ICD_FILENAMES=//install/share/vulkan/icd.d/_icd.aarch64.json
2022-09-06 15:56:18.439346: + VK_ICD_FILENAMES=//install/share/vulkan/icd.d/_icd.aarch64.json
2022-09-06 15:56:18.439416: ++ pwd
2022-09-06 15:56:18.439490: + RESULTS=//results
2022-09-06 15:56:18.439634: + mkdir -p //results
2022-09-06 15:56:18.439676: + SHADER_CACHE_HOME=/tmp
2022-09-06 15:56:18.439749: + SHADER_CACHE_DIR=/tmp/mesa_shader_cache
2022-09-06 15:56:18.439816: + findmnt -n tmpfs /tmp
2022-09-06 15:56:18.439885: /tmp   tmpfs  tmpfs  rw,relatime
2022-09-06 15:56:18.439952: + HANG_DETECTION_CMD=
2022-09-06 15:56:18.440026: + '[' -z '' ']'
2022-09-06 15:56:18.440102: + '[' -z egl ']'
2022-09-06 15:56:18.440168: + DEQP_WIDTH=256
2022-09-06 15:56:18.440247: + DEQP_HEIGHT=256
2022-09-06 15:56:18.440318: + DEQP_CONFIG=rgba8888d24s8ms0
2022-09-06 15:56:18.440388: + DEQP_VARIANT=master
2022-09-06 15:56:18.440460: + DEQP_OPTIONS=' --deqp-surface-width=256 --deqp-surface-height=256'
2022-09-06 15:56:18.440529: + DEQP_OPTIONS=' --deqp-surface-width=256 --deqp-surface-height=256 --deqp-surface-type=pbuffer'
2022-09-06 15:56:18.440613: + DEQP_OPTIONS=' --deqp-surface-width=256 --deqp-surface-height=256 --deqp-surface-type=pbuffer --deqp-gl-config-name=rgba8888d24s8ms0'
2022-09-06 15:56:18.440690: + DEQP_OPTIONS=' --deqp-surface-width=256 --deqp-surface-height=256 --deqp-surface-type=pbuffer --deqp-gl-config-name=rgba8888d24s8ms0 --deqp-visibility=hidden'
2022-09-06 15:56:18.440766: + '[' egl = vk -a -z '' ']'
2022-09-06 15:56:18.440853: + '[' egl = vk ']'
2022-09-06 15:56:18.440918: + '[' egl = gles2 -o egl = gles3 -o egl = gles31 -o egl = egl ']'
2022-09-06 15:56:18.440992: + MUSTPASS=/deqp/mustpass/egl-master.txt
2022-09-06 15:56:18.441057: + DEQP=/deqp/modules/egl/deqp-egl
2022-09-06 15:56:18.441142: + cp /deqp/mustpass/egl-master.txt /tmp/case-list.txt
2022-09-06 15:56:18.441214: + '[' -n '' ']'
2022-09-06 15:56:18.441289: + '[' -n '' ']'
2022-09-06 15:56:18.441363: + '[' -n '' ']'
2022-09-06 15:56:18.441441: + '[' -n '' ']'
2022-09-06 15:56:18.441581: + '[' '!' -s /tmp/case-list.txt ']'
2022-09-06 15:56:18.441622: + '[' -e //install/lima-fails.txt ']'
2022-09-06 15:56:18.441695: + DEQP_RUNNER_OPTIONS=' --baseline //install/lima-fails.txt'
2022-09-06 15:56:18.441769: + touch //install/lima-flakes.txt
2022-09-06 15:56:18.441853: + '[' -n '' ']'
2022-09-06 15:56:18.441920: + '[' -n '' ']'
2022-09-06 15:56:18.441992: + '[' -n '' ']'
2022-09-06 15:56:18.442064: + '[' -e //install/lima-skips.txt ']'
2022-09-06 15:56:18.442132: + DEQP_SKIPS=' //install/lima-skips.txt'
2022-09-06 15:56:18.442206: + '[' '' = virpipe ']'
2022-09-06 15:56:18.442282: + '[' -z '' ']'
2022-09-06 15:56:18.442356: + '[' -n '' ']'
2022-09-06 15:56:18.442453: + '[' egl '!=' vk -a egl '!=' egl ']'
2022-09-06 15:56:18.442524: + set +x
2022-09-06 15:56:18.442596: section_end:1662479770:test_setup

2022-09-06 15:56:18.442749: section_start:1662479770:deqp[collapsed=false]
deqp-runner
2022-09-06 15:56:18.442884: + set +e
2022-09-06 15:56:18.442977: + '[' -z '' ']'
2022-09-06 15:56:18.443045: + deqp-runner run --deqp /deqp/modules/egl/deqp-egl --output //results --caselist /tmp/case-list.txt --skips //install/all-skips.txt //install/lima-skips.txt --flakes //install/lima-flakes.txt --testlog-to-xml /deqp/executor/testlog-to-xml --jobs 4 --baseline //install/lima-fails.txt -- --deqp-surface-width=256 --deqp-surface-height=256 --deqp-surface-type=pbuffer --deqp-gl-config-name=rgba8888d24s8ms0 --deqp-visibility=hidden
2022-09-06 15:56:18.443129: Running dEQP on 4 threads in 500-test groups
2022-09-06 15:56:18.443200: Pass: 0, Duration: 0
2022-09-06 15:56:18.443281: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.443322: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.443413: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.443482: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.443554: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c3.r1.log\")
2022-09-06 15:56:18.443625: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c1.r1.log\")
2022-09-06 15:56:18.443662: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c0.r1.log\")
2022-09-06 15:56:18.443758: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c2.r1.log\")
2022-09-06 15:56:18.443837: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.443912: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.443990: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c7.r1.log\")
2022-09-06 15:56:18.444131: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c5.r1.log\")
2022-09-06 15:56:18.444172: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.444245: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c6.r1.log\")
2022-09-06 15:56:18.444323: ERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85
2022-09-06 15:56:18.444408: ERROR - Failure getting run results: No results parsed.  Is your caselist out of sync with your deqp binary? (See \"//results/c4.r1.log\")
2022-09-06 15:56:18.444481: Pass: 0, Skip: 186, Missing: 3574, Duration: 0, Remaining: 0
2022-09-06 15:56:18.444566: Slowest tests:
2022-09-06 15:56:18.444633:   dEQP-EGL.functional.negative_partial_update.not_current_surface (0.00s)
2022-09-06 15:56:18.444708:   dEQP-EGL.functional.create_context_ext.robust_gl_33.rgb565_depth_stencil (0.00s)
2022-09-06 15:56:18.444795:   dEQP-EGL.functional.sharing.gles2.multithread.random.images.copytexsubimage2d.2 (0.00s)
2022-09-06 15:56:18.444862:   dEQP-EGL.functional.sharing.gles2.multithread.random.textures.copytexsubimage2d.1 (0.00s)
2022-09-06 15:56:18.444958:   dEQP-EGL.functional.sharing.gles2.multithread.random_egl_sync.images.copyteximage2d.12 (0.00s)
2022-09-06 15:56:18.445037: Some failures found:
2022-09-06 15:56:18.445123:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_clear,Missing
2022-09-06 15:56:18.445192:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_clear_clear,Missing
2022-09-06 15:56:18.445272:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_none,Missing
2022-09-06 15:56:18.445309:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_render,Missing
2022-09-06 15:56:18.445380:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_even_clear,Missing
2022-09-06 15:56:18.445454:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_even_none,Missing
2022-09-06 15:56:18.445516:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_clear,Missing
2022-09-06 15:56:18.445600:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_clear_clear,Missing
2022-09-06 15:56:18.445667:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_clear_render,Missing
2022-09-06 15:56:18.445741:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_none,Missing
2022-09-06 15:56:18.445811:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_render,Missing
2022-09-06 15:56:18.445885:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_render_render,Missing
2022-09-06 15:56:18.446061:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_none_even_none,Missing
2022-09-06 15:56:18.446158:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_clear,Missing
2022-09-06 15:56:18.446195:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_clear_clear,Missing
2022-09-06 15:56:18.446275:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_clear_render,Missing
2022-09-06 15:56:18.446345:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_none,Missing
2022-09-06 15:56:18.446414:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_render,Missing
2022-09-06 15:56:18.446482:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_render_clear,Missing
2022-09-06 15:56:18.446562:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_render_render,Missing
2022-09-06 15:56:18.446633:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_even_clear,Missing
2022-09-06 15:56:18.446707:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_even_none,Missing
2022-09-06 15:56:18.446781:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_even_render,Missing
2022-09-06 15:56:18.446864:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_render_even_clear,Missing
2022-09-06 15:56:18.446935:   dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_render_even_clear_clear,Missing
2022-09-06 15:56:18.447005:   ... and more (see failures.csv)
2022-09-06 15:56:18.447068: + DEQP_EXITCODE=1
2022-09-06 15:56:18.447141: + set +x
2022-09-06 15:56:18.447207: section_end:1662479771:deqp

2022-09-06 15:56:18.447343: System load: 0.25 0.09 0.03
2022-09-06 15:56:18.447438: # of CPU cores: 4
2022-09-06 15:56:18.447505: section_start:1662479771:test_post_process[collapsed=true]
post-processing test results
2022-09-06 15:56:18.447654: + find //results -name '*.xml'
2022-09-06 15:56:18.447727: + sort -n
2022-09-06 15:56:18.447795: + sed -n '1,+49!p'
2022-09-06 15:56:18.447973: + xargs rm -f
2022-09-06 15:56:18.448069: + find //results -name '*.xml' -exec cp /deqp/testlog.css /deqp/testlog.xsl //results/ ';' -quit
2022-09-06 15:56:18.448109: + deqp-runner junit --testsuite dEQP --results //results/failures.csv --output //results/junit.xml --limit 50 --template 'See https://enunes.pages.freedesktop.org/-/mesa/-/jobs/27956136/artifacts/results/{{testcase}}.xml'
2022-09-06 15:56:18.448182: + '[' -n '' ']'
2022-09-06 15:56:18.448260: ++ date +%s
2022-09-06 15:56:18.448332: + echo -e '\e[0Ksection_end:1662479771:test_post_process\r\e[0K'
2022-09-06 15:56:18.448417: section_end:1662479771:test_post_process

2022-09-06 15:56:18.448613: + exit 1
2022-09-06 15:56:18.448653: + EXIT_CODE=1
2022-09-06 15:56:18.448723: + set -e
2022-09-06 15:56:18.448803: + mv -f /builds/enunes/mesa/results ./
2022-09-06 15:56:18.448870: + true
2022-09-06 15:56:18.448944: + [ 1 -ne 0 ]
2022-09-06 15:56:18.449017: + cleanup
2022-09-06 15:56:18.449140: + [ 170 169  =  ]
2022-09-06 15:56:18.449183: + set +x
2022-09-06 15:56:18.449301: Killing all child processes
2022-09-06 15:56:18.449384: + [ -n minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136 ]
2022-09-06 15:56:23.475080: + tar --zstd -cf results.tar.zst results/
2022-09-06 15:56:23.475837: + ci-fairy minio login --token-file /minio_jwt
2022-09-06 15:56:33.827738: + ci-fairy minio cp results.tar.zst minio://minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136/results.tar.zst
2022-09-06 15:56:39.003811: [   79.989506] <LAVA_SIGNAL_ENDTC mesa-ci_lima-mali450-deqp-egl:arm64>
2022-09-06 15:56:39.004163: [   80.016604] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=mesa-ci_lima-mali450-deqp-egl:arm64 RESULT=fail>
2022-09-06 15:56:39.004385: [   80.021840] <LAVA_SIGNAL_ENDRUN 0_mesa 25747_1.4.2.4.1>
2022-09-06 15:56:39.004790: + [ 1 -eq 0 ]
2022-09-06 15:56:39.004971: + set +x
2022-09-06 15:56:39.005121: hwci: mesa: fail
section_end:1662479799:post-mesa-ci_lima-mali450-deqp-egl-arm64

section_start:1662479799:job_data[collapsed=true]
LAVA job info
id	: 25747
description	: mesa: lima-mali450-deqp-egl:arm64: https://gitlab.freedesktop.org/enunes/mesa/-/pipelines/682113 on lima-ci-deqp-egl /1
device	: meson-gxl-s805x-libretech-ac-3
device_type	: meson-gxl-s805x-libretech-ac
health_check	: False
pipeline	: True
health	: Unknown
state	: Running
submitter	: mesa-ci
submit_time	: 20220906T15:53:25
start_time	: 20220906T15:53:39
end_time	: None
tags	: []
visibility	: Group (mesa-ci)
failure_comment	: None
section_end:1662479799:job_data

2022-09-06 15:56:39.030233: Finished executing LAVA job in the attempt #1
2022-09-06 15:56:39.030384: LAVA Job finished with status: fail
section_end:1662479799:step_script
section_start:1662479799:after_script
Running after_script
Running after script...
$ wget -q "https://${JOB_RESULTS_PATH}" -O- | tar --zstd -x
section_end:1662479800:after_script
section_start:1662479800:upload_artifacts_on_failure
Uploading artifacts for failed job
Uploading artifacts...
results/: found 19 matching files and directories  
Uploading artifacts as "archive" to coordinator... 201 Created  id=27956136 responseStatus=201 Created token=Rxjc4s97
Uploading artifacts...
results/junit.xml: found 1 matching files and directories 
Uploading artifacts as "junit" to coordinator... 201 Created  id=27956136 responseStatus=201 Created token=Rxjc4s97
section_end:1662479805:upload_artifacts_on_failure
section_start:1662479805:cleanup_file_variables
Cleaning up project directory and file based variables
section_end:1662479805:cleanup_file_variables
ERROR: Job failed: exit code 1