[0KRunning with gitlab-runner 15.3.0 (bbcb5aba)[0;m [0K on mesa-lava-lima-meson-gxl-s805x-libretech-ac AN2HRxPy[0;m section_start:1662479594:prepare_executor [0K[0K[36;1mPreparing the "docker" executor[0;m[0;m [0KUsing Docker executor with image registry.freedesktop.org/enunes/mesa/debian/x86_build:2022-07-29-s390xxx--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd ...[0;m [0KAuthenticating with credentials from job payload (GitLab Registry)[0;m [0KPulling docker image registry.freedesktop.org/enunes/mesa/debian/x86_build:2022-07-29-s390xxx--2022-08-27-drop-wine32--290b79e0e78eab67a83766f4e9691be554fc4afd ...[0;m [0KUsing 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 ...[0;m section_end:1662479597:prepare_executor [0Ksection_start:1662479597:prepare_script [0K[0K[36;1mPreparing environment[0;m[0;m Running on runner-an2hrxpy-project-2991-concurrent-1 via mesa-lava-lima-meson-gxl-s805x-libretech-ac... section_end:1662479598:prepare_script [0Ksection_start:1662479598:get_sources [0K[0K[36;1mGetting source from Git repository[0;m[0;m [32;1mSkipping Git repository setup[0;m [32;1mSkipping Git checkout[0;m [32;1mSkipping Git submodules setup[0;m section_end:1662479598:get_sources [0Ksection_start:1662479598:download_artifacts [0K[0K[36;1mDownloading artifacts[0;m[0;m [32;1mDownloading artifacts for debian-arm64 (27956098)...[0;m Downloading artifacts from coordinator... ok [0;m id[0;m=27956098 responseStatus[0;m=200 OK token[0;m=Rxjc4s97 section_end:1662479601:download_artifacts [0Ksection_start:1662479601:step_script [0K[0K[36;1mExecuting "step_script" stage of the job script[0;m[0;m [0KUsing 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 ...[0;m [32;1m$ echo -e "\e[0Ksection_start:$(date +%s):unset_env_vars_section[collapsed=true]\r\e[0KUnsetting vulnerable environment variables"[0;m [0Ksection_start:1662479601:unset_env_vars_section[collapsed=true] [0KUnsetting vulnerable environment variables [32;1m$ echo -n "${CI_JOB_JWT}" > "${CI_JOB_JWT_FILE}"[0;m [32;1m$ unset CI_JOB_JWT[0;m [32;1m$ echo -e "\e[0Ksection_end:$(date +%s):unset_env_vars_section\r\e[0K"[0;m [0Ksection_end:1662479601:unset_env_vars_section [0K [32;1m$ ./artifacts/lava/lava-submit.sh[0;m + wget -q --method=HEAD https://minio-packet.freedesktop.org/mesa-lava/mesa/mesa/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 [0m2022-09-06 15:53:25.593298: Proxy for http://10.0.70.21 created. [0Ksection_start:1662479605:yaml_dump[collapsed=true] [0K[1m[1;32;5;197mLAVA job definition (YAML)[0m 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 [0Ksection_end:1662479605:yaml_dump [0K [0m2022-09-06 15:53:25.625381: LAVA job definition validated successfully [0m2022-09-06 15:53:25.652926: Waiting for job 25747 to start. [0m2022-09-06 15:53:45.727921: Job 25747 started. [0Ksection_start:1662479625:lava_boot[collapsed=true] [0K[1m[1;32;5;197mLAVA boot[0m [0m2022-09-06 15:53:50.757506: lava-dispatcher, installed at version: 2022.04 [0m2022-09-06 15:53:50.757559: start: 0 validate [0m2022-09-06 15:53:50.757567: Start time: 2022-09-06 15:53:39.525253+00:00 (UTC) [0m2022-09-06 15:53:50.757573: validate duration: 0.97 [0m2022-09-06 15:53:50.757578: start: 1 tftp-deploy (timeout 00:10:00) [common] [0m2022-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 [0m2022-09-06 15:53:55.798738: 32MB downloaded in 6.51s (4.95MB/s) [0m2022-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 [0m2022-09-06 15:53:55.798830: 0MB downloaded in 0.42s (0.06MB/s) [0m2022-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 [0m2022-09-06 15:53:55.798861: Using unzstd to decompress zstd [0m2022-09-06 15:54:46.903542: 391MB downloaded in 53.86s (7.27MB/s) [0m2022-09-06 15:55:02.506433: Loading test definitions [0m2022-09-06 15:55:02.506480: Creating lava-test-runner.conf files [0m2022-09-06 15:55:02.506488: [common] Applying overlay to NFS [0m2022-09-06 15:55:02.506493: Converting downloaded kernel to a uImage [0m2022-09-06 15:55:02.506498: end: 1 tftp-deploy (duration 00:01:13) [common] [0m2022-09-06 15:55:02.506503: start: 2 uboot-action (timeout 00:03:00) [common] [0m2022-09-06 15:55:02.506508: Using kernel file from prepare-kernel: 25747/tftp-deploy-4e37gkg_/kernel/uImage [0m2022-09-06 15:55:02.506513: Parsed boot commands: [0m2022-09-06 15:55:02.506517: - setenv autoload no [0m2022-09-06 15:55:02.506522: - setenv initrd_high 0xffffffff [0m2022-09-06 15:55:02.506527: - setenv fdt_high 0xffffffff [0m2022-09-06 15:55:02.506533: - dhcp [0m2022-09-06 15:55:02.506538: - setenv serverip 10.0.70.21 [0m2022-09-06 15:55:02.506543: - tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage [0m2022-09-06 15:55:02.506548: - setenv initrd_size ${filesize} [0m2022-09-06 15:55:02.506563: - tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb [0m2022-09-06 15:55:02.506642: - setenv bootargs console=ttyAML0,115200n8 [0m2022-09-06 15:55:02.506693: - setenv bootargs ${bootargs} root=/dev/nfs rw [0m2022-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 [0m2022-09-06 15:55:02.506776: - setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10 [0m2022-09-06 15:55:02.506782: - setenv bootargs ${bootargs} ip=dhcp [0m2022-09-06 15:55:02.506787: - bootm 0x08080000 - 0x08008000 [0m2022-09-06 15:55:02.506849: [common] connect-device Connecting to device using 'telnet 10.0.70.21 3022' [0m2022-09-06 15:55:07.535661: Not interrupting bootloader, waiting for bootloader prompt [0m2022-09-06 15:55:07.535725: Trying 10.0.70.21... [0m2022-09-06 15:55:07.535738: Connected to 10.0.70.21. [0m2022-09-06 15:55:07.535748: Escape character is '^]'. [0m2022-09-06 15:55:07.535757: port 3022 device /dev/ttyUSBh1p3,115200 [0m2022-09-06 15:55:07.535765: ��GXL:BL1:9ac50e:bb16dc;FEAT:ADFC318C:0;POC:1;RCY:0;SPI:0;0.0;CHK:0; [0m2022-09-06 15:55:07.535774: TE: 35047 [0m2022-09-06 15:55:07.535783: BL2 Built : 15:21:18, Aug 28 2019. gxl g1bf2b53 - luan.yuan@droid15-sz [0m2022-09-06 15:55:07.535792: set vcck to 1120 mv [0m2022-09-06 15:55:07.535802: set vddee to 1000 mv [0m2022-09-06 15:55:07.535811: Board ID = 11 [0m2022-09-06 15:55:07.536017: CPU clk: 1200MHz [0m2022-09-06 15:55:07.536042: DDR enable rdbi [0m2022-09-06 15:55:07.536050: DDR use ext vref [0m2022-09-06 15:55:07.536057: DDR ZQ power down [0m2022-09-06 15:55:07.536266: DQS-corr enabled [0m2022-09-06 15:55:07.536286: DDR scramble enabled [0m2022-09-06 15:55:07.536294: DDR4 chl: Rank0 16bit @ 1200MHz [0m2022-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 [0m2022-09-06 15:55:07.536482: Rank0: 1024MB(auto)-2T-18 [0m2022-09-06 15:55:07.536492: AddrBus test pass! [0m2022-09-06 15:55:07.536501: Set ddr ssc: ppm1000- [0m2022-09-06 15:55:07.536664: Load fip header from SPI, src: 0x0000c000, des: 0x01400000, size: 0x00004000, part: 0 [0m2022-09-06 15:55:07.536831: New fip structure! [0m2022-09-06 15:55:07.537001: Load bl30 from SPI, src: 0x00010000, des: 0x013c0000, size: 0x0000d600, part: 0 [0m2022-09-06 15:55:07.537182: Load bl31 from SPI, src: 0x00020000, des: 0x05100000, size: 0x0002b400, part: 0 [0m2022-09-06 15:55:07.537211: Load bl33 from SPI, src: 0x0004c000, des: 0x01000000, size: 0x00086600, part: 0 [0m2022-09-06 15:55:07.537219: NOTICE: BL3-1: v1.0(release):53f813e [0m2022-09-06 15:55:07.537225: NOTICE: BL3-1: Built : 15:51:23, May 22 2019 [0m2022-09-06 15:55:07.537232: [BL31]: GXL CPU setup! [0m2022-09-06 15:55:07.537240: NOTICE: BL3-1: GXL normal boot! [0m2022-09-06 15:55:07.537406: NOTICE: BL3-1: BL33 decompress pass [0m2022-09-06 15:55:07.537414: mpu_config_enable:system pre init ok [0m2022-09-06 15:55:07.537421: dmc sec lock [0m2022-09-06 15:55:07.537428: [Image: gxl_v1.1.3390-6ac5299 2019-09-26 14:09:24 luan.yuan@droid15-sz] [0m2022-09-06 15:55:07.537603: OPS=0x34 [0m2022-09-06 15:55:07.537614: 21 0d 34 00 13 b2 b1 52 5b 00 41 ac 21 83 54 02 [0m2022-09-06 15:55:07.537620: [0.762753 Inits done] [0m2022-09-06 15:55:07.537705: secure task start! [0m2022-09-06 15:55:07.537810: high task start! [0m2022-09-06 15:55:07.537819: low task start! [0m2022-09-06 15:55:07.537826: ERROR: Error initializing runtime service opteed_fast [0m2022-09-06 15:55:07.537977: U-Boot 2021.07+ (Jul 27 2021 - 19:48:21 +0000) Libre Computer AML-S805X-AC [0m2022-09-06 15:55:07.538002: DRAM: 1 GiB [0m2022-09-06 15:55:07.538011: MMC: mmc@74000: 0 [0m2022-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 [0m2022-09-06 15:55:07.538025: OK [0m2022-09-06 15:55:07.538196: ** No partition table - mmc 0 ** [0m2022-09-06 15:55:07.538211: Couldn't find partition mmc 0:1 [0m2022-09-06 15:55:07.538294: Error: could not access storage. [0m2022-09-06 15:55:07.538383: In: serial [0m2022-09-06 15:55:07.538391: Out: serial [0m2022-09-06 15:55:07.538398: Err: serial [0m2022-09-06 15:55:07.538405: [BL31]: tee size: 0 [0m2022-09-06 15:55:07.538563: Net: eth0: ethernet@c9410000 [0m2022-09-06 15:55:07.538581: starting USB... [0m2022-09-06 15:55:07.538587: Bus usb@c9000000: dwc3_meson_gxl_get_phys: usb2 ports: 2 [0m2022-09-06 15:55:07.538732: Register 2000140 NbrPorts 2 [0m2022-09-06 15:55:07.538876: Starting the controller [0m2022-09-06 15:55:07.538897: USB XHCI 1.00 [0m2022-09-06 15:55:07.538904: scanning bus usb@c9000000 for devices... 1 USB Device(s) found [0m2022-09-06 15:55:07.538909: scanning usb for storage devices... 0 Storage Device(s) found [0m2022-09-06 15:55:07.538915: $ setenv autoload no [0m2022-09-06 15:55:07.539134: => setenv autoload no [0m2022-09-06 15:55:07.539186: setenv autoload no [0m2022-09-06 15:55:07.539195: $ setenv initrd_high 0xffffffff [0m2022-09-06 15:55:07.539204: => setenv initrd_high 0xffffffff [0m2022-09-06 15:55:07.539211: setenv initrd_high 0xffffffff [0m2022-09-06 15:55:07.539357: $ setenv fdt_high 0xffffffff [0m2022-09-06 15:55:07.539508: => setenv fdt_high 0xffffffff [0m2022-09-06 15:55:07.539670: setenv fdt_high 0xffffffff [0m2022-09-06 15:55:07.539690: $ dhcp [0m2022-09-06 15:55:07.539699: => dhcp [0m2022-09-06 15:55:07.539706: dhcp [0m2022-09-06 15:55:07.539712: Speed: 100, full duplex [0m2022-09-06 15:55:07.539717: BOOTP broadcast 1 [0m2022-09-06 15:55:07.539872: DHCP client bound to address 10.0.70.83 (1 ms) [0m2022-09-06 15:55:07.539889: $ setenv serverip 10.0.70.21 [0m2022-09-06 15:55:07.540040: => setenv serverip 10.0.70.21 [0m2022-09-06 15:55:07.540052: setenv serverip 10.0.70.21 [0m2022-09-06 15:55:07.540211: $ tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage [0m2022-09-06 15:55:07.540228: => tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage [0m2022-09-06 15:55:07.540236: tftpboot 0x08080000 25747/tftp-deploy-4e37gkg_/kernel/uImage [0m2022-09-06 15:55:07.540401: Speed: 100, full duplex [0m2022-09-06 15:55:07.540410: Using ethernet@c9410000 device [0m2022-09-06 15:55:07.540416: TFTP from server 10.0.70.21; our IP address is 10.0.70.83 [0m2022-09-06 15:55:07.540422: Filename '25747/tftp-deploy-4e37gkg_/kernel/uImage'. [0m2022-09-06 15:55:07.540581: Load address: 0x8080000 [0m2022-09-06 15:55:07.540603: Loading: *################################################################# [0m2022-09-06 15:55:07.540618: ################################################################# [0m2022-09-06 15:55:07.540629: ################################################################# [0m2022-09-06 15:55:07.540792: ################################################################# [0m2022-09-06 15:55:07.540802: ################################################################# [0m2022-09-06 15:55:07.540809: ################################################################# [0m2022-09-06 15:55:07.540816: ################################################################# [0m2022-09-06 15:55:07.540968: ################################################################# [0m2022-09-06 15:55:12.564447: ################################################################# [0m2022-09-06 15:55:12.564754: ################################################################# [0m2022-09-06 15:55:12.564765: ################################################################# [0m2022-09-06 15:55:12.564771: ################################################################# [0m2022-09-06 15:55:12.564776: ################################################################# [0m2022-09-06 15:55:12.564781: ################################################################# [0m2022-09-06 15:55:12.564785: ################################################################# [0m2022-09-06 15:55:12.564790: ################################################################# [0m2022-09-06 15:55:12.564795: ################################################################# [0m2022-09-06 15:55:12.564799: ################################################################# [0m2022-09-06 15:55:12.564804: ################################################################# [0m2022-09-06 15:55:12.564808: ################################################################# [0m2022-09-06 15:55:12.564813: ################################################################# [0m2022-09-06 15:55:12.564817: ################################################################# [0m2022-09-06 15:55:12.564822: ################################################################# [0m2022-09-06 15:55:12.564827: ################################################################# [0m2022-09-06 15:55:12.564831: ################################################################# [0m2022-09-06 15:55:12.564836: ################################################################# [0m2022-09-06 15:55:12.564841: ################################################################# [0m2022-09-06 15:55:12.564846: ################################################################# [0m2022-09-06 15:55:12.564851: ################################################################# [0m2022-09-06 15:55:12.564857: ################################################################# [0m2022-09-06 15:55:12.564862: ################################################################# [0m2022-09-06 15:55:12.564936: ################################################################# [0m2022-09-06 15:55:12.564964: ################################################################# [0m2022-09-06 15:55:12.564971: ################################################################# [0m2022-09-06 15:55:12.564975: ################################################################# [0m2022-09-06 15:55:22.618554: [1m[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034][0m [0m2022-09-06 15:55:22.618653: [1m[ 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[0m [0m2022-09-06 15:55:22.618665: [1m[ 0.000000] Machine model: Libre Computer AML-S805X-AC[0m [0m2022-09-06 15:55:22.618677: [1m[ 0.000000] efi: UEFI not found.[0m [0m2022-09-06 15:55:22.618685: [1m[ 0.000000] [Firmware Bug]: Kernel image misaligned at boot, please fix your bootloader![0m [0m2022-09-06 15:55:22.618702: [1m[ 0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'hwrom@0': base 0x0000000000000000, size 16 MiB[0m [0m2022-09-06 15:55:22.618710: [1m[ 0.000000] OF: fdt: Reserved memory: failed to reserve memory for node 'secmon@5000000': base 0x0000000005000000, size 3 MiB[0m [0m2022-09-06 15:55:22.618720: [1m[ 0.000000] Reserved memory: created CMA memory pool at 0x000000002fc00000, size 256 MiB[0m [0m2022-09-06 15:55:22.618728: [1m[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool[0m [0m2022-09-06 15:55:22.618735: [1m[ 0.000000] NUMA: No NUMA configuration found[0m [0m2022-09-06 15:55:22.618742: [1m[ 0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x000000003fe5afff][0m [0m2022-09-06 15:55:22.618751: [1m[ 0.000000] NUMA: NODE_DATA [mem 0x3fc40b40-0x3fc42fff][0m [0m2022-09-06 15:55:22.618758: [1m[ 0.000000] Zone ranges:[0m [0m2022-09-06 15:55:22.618765: [1m[ 0.000000] DMA [mem 0x0000000000000000-0x000000003fe5afff][0m [0m2022-09-06 15:55:22.618772: [1m[ 0.000000] DMA32 empty[0m [0m2022-09-06 15:55:22.618781: [1m[ 0.000000] Normal empty[0m [0m2022-09-06 15:55:22.618789: [1m[ 0.000000] Movable zone start for each node[0m [0m2022-09-06 15:55:22.618796: [1m[ 0.000000] Early memory node ranges[0m [0m2022-09-06 15:55:22.618802: [1m[ 0.000000] node 0: [mem 0x0000000000000000-0x00000000052fffff][0m [0m2022-09-06 15:55:22.618809: [1m[ 0.000000] node 0: [mem 0x0000000005300000-0x00000000072fffff][0m [0m2022-09-06 15:55:22.618818: [1m[ 0.000000] node 0: [mem 0x0000000007300000-0x000000000fffffff][0m [0m2022-09-06 15:55:22.618825: [1m[ 0.000000] node 0: [mem 0x0000000010000000-0x00000000101fffff][0m [0m2022-09-06 15:55:22.618832: [1m[ 0.000000] node 0: [mem 0x0000000010200000-0x000000003fe5afff][0m [0m2022-09-06 15:55:22.618839: [1m[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003fe5afff][0m [0m2022-09-06 15:55:22.618848: [1m[ 0.000000] On node 0, zone DMA: 421 pages in unavailable ranges[0m [0m2022-09-06 15:55:22.618855: [1m[ 0.000000] psci: probing for conduit method from DT.[0m [0m2022-09-06 15:55:22.618862: [1m[ 0.000000] psci: PSCIv0.2 detected in firmware.[0m [0m2022-09-06 15:55:22.618869: [1m[ 0.000000] psci: Using standard PSCI v0.2 function IDs[0m [0m2022-09-06 15:55:22.618876: [1m[ 0.000000] psci: Trusted OS migration not required[0m [0m2022-09-06 15:55:22.618885: [1m[ 0.000000] percpu: Embedded 20 pages/cpu s41432 r8192 d32296 u81920[0m [0m2022-09-06 15:55:22.618892: [1m[ 0.000000] Detected VIPT I-cache on CPU0[0m [0m2022-09-06 15:55:22.618899: [1m[ 0.000000] CPU features: kernel page table isolation forced ON by KASLR[0m [0m2022-09-06 15:55:22.618906: [1m[ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)[0m [0m2022-09-06 15:55:22.618913: [1m[ 0.000000] CPU features: detected: ARM erratum 845719[0m [0m2022-09-06 15:55:22.618922: [1m[ 0.000000] Fallback order for Node 0: 0 [0m [0m2022-09-06 15:55:22.618929: [1m[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 257633[0m [0m2022-09-06 15:55:22.618935: [1m[ 0.000000] Policy zone: DMA[0m [0m2022-09-06 15:55:22.618976: [1m[ 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[0m [0m2022-09-06 15:55:22.618998: [1m[ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)[0m [0m2022-09-06 15:55:22.619007: [1m[ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)[0m [0m2022-09-06 15:55:22.619014: [1m[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off[0m [0m2022-09-06 15:55:22.619064: [1m[ 0.000000] Memory: 676520K/1046892K available (15936K kernel code, 3128K rwdata, 8236K rodata, 5568K init, 475K bss, 108228K reserved, 262144K cma-reserved)[0m [0m2022-09-06 15:55:22.619080: [1m[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1[0m [0m2022-09-06 15:55:22.619088: [1m[ 0.000000] rcu: Preemptible hierarchical RCU implementation.[0m [0m2022-09-06 15:55:22.619094: [1m[ 0.000000] rcu: RCU event tracing is enabled.[0m [0m2022-09-06 15:55:22.619101: [1m[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.[0m [0m2022-09-06 15:55:22.619310: [1m[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.[0m [0m2022-09-06 15:55:22.619366: [1m[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4[0m [0m2022-09-06 15:55:22.619383: [1m[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0[0m [0m2022-09-06 15:55:22.619390: [1m[ 0.000000] Root IRQ handler: gic_handle_irq[0m [0m2022-09-06 15:55:22.619397: [1m[ 0.000000] GIC: Using split EOI/Deactivate mode[0m [0m2022-09-06 15:55:22.619404: [1m[ 0.000000] random: get_random_bytes called from start_kernel+0x3fc/0x598 with crng_init=0[0m [0m2022-09-06 15:55:22.619540: [1m[ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).[0m [0m2022-09-06 15:55:22.619649: [1m[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns[0m [0m2022-09-06 15:55:22.619710: [1m[ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns[0m [0m2022-09-06 15:55:22.619719: [1m[ 0.000696] Console: colour dummy device 80x25[0m [0m2022-09-06 15:55:22.619726: [1m[ 0.000771] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)[0m [0m2022-09-06 15:55:22.619793: [1m[ 0.000784] pid_max: default: 32768 minimum: 301[0m [0m2022-09-06 15:55:22.620045: [1m[ 0.000840] LSM: Security Framework initializing[0m [0m2022-09-06 15:55:22.620068: [1m[ 0.000938] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m [0m2022-09-06 15:55:22.620077: [1m[ 0.000953] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)[0m [0m2022-09-06 15:55:22.620084: [1m[ 0.002194] cblist_init_generic: Setting adjustable number of callback queues.[0m [0m2022-09-06 15:55:22.620262: [1m[ 0.002218] cblist_init_generic: Setting shift to 2 and lim to 1.[0m [0m2022-09-06 15:55:22.620320: [1m[ 0.002347] rcu: Hierarchical SRCU implementation.[0m [0m2022-09-06 15:55:22.620333: [1m[ 0.003459] EFI services will not be available.[0m [0m2022-09-06 15:55:22.620340: [1m[ 0.003865] smp: Bringing up secondary CPUs ...[0m [0m2022-09-06 15:55:22.620347: [1m[ 0.004442] Detected VIPT I-cache on CPU1[0m [0m2022-09-06 15:55:22.620356: [1m[ 0.004517] CPU1: Booted secondary processor 0x0000000001 [0x410fd034][0m [0m2022-09-06 15:55:22.620362: [1m[ 0.005098] Detected VIPT I-cache on CPU2[0m [0m2022-09-06 15:55:22.620369: [1m[ 0.005157] CPU2: Booted secondary processor 0x0000000002 [0x410fd034][0m [0m2022-09-06 15:55:22.620512: [1m[ 0.005705] Detected VIPT I-cache on CPU3[0m [0m2022-09-06 15:55:22.620523: [1m[ 0.005761] CPU3: Booted secondary processor 0x0000000003 [0x410fd034][0m [0m2022-09-06 15:55:22.620531: [1m[ 0.005841] smp: Brought up 1 node, 4 CPUs[0m [0m2022-09-06 15:55:22.620591: [1m[ 0.005865] SMP: Total of 4 processors activated.[0m [0m2022-09-06 15:55:22.620611: [1m[ 0.005872] CPU features: detected: 32-bit EL0 Support[0m [0m2022-09-06 15:55:22.620619: [1m[ 0.005876] CPU features: detected: 32-bit EL1 Support[0m [0m2022-09-06 15:55:22.620625: [1m[ 0.005883] CPU features: detected: CRC32 instructions[0m [0m2022-09-06 15:55:22.620632: [1m[ 0.012446] CPU: All CPU(s) started at EL2[0m [0m2022-09-06 15:55:22.620684: [1m[ 0.012516] alternatives: patching kernel code[0m [0m2022-09-06 15:55:22.620713: [1m[ 0.013819] devtmpfs: initialized[0m [0m2022-09-06 15:55:22.620721: [1m[ 0.018338] KASLR disabled due to lack of seed[0m [0m2022-09-06 15:55:22.620845: [1m[ 0.018525] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns[0m [0m2022-09-06 15:55:22.620986: [1m[ 0.018545] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)[0m [0m2022-09-06 15:55:22.621007: [1m[ 0.026495] pinctrl core: initialized pinctrl subsystem[0m [0m2022-09-06 15:55:22.621073: [1m[ 0.027229] DMI not present or invalid.[0m [0m2022-09-06 15:55:22.621153: [1m[ 0.027668] NET: Registered PF_NETLINK/PF_ROUTE protocol family[0m [0m2022-09-06 15:55:22.621167: [1m[ 0.029115] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations[0m [0m2022-09-06 15:55:22.621245: [1m[ 0.029269] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations[0m [0m2022-09-06 15:55:22.621346: [1m[ 0.029362] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations[0m [0m2022-09-06 15:55:22.621405: [1m[ 0.029450] audit: initializing netlink subsys (disabled)[0m [0m2022-09-06 15:55:22.621415: [1m[ 0.029643] audit: type=2000 audit(0.028:1): state=initialized audit_enabled=0 res=1[0m [0m2022-09-06 15:55:22.621422: [1m[ 0.030601] thermal_sys: Registered thermal governor 'step_wise'[0m [0m2022-09-06 15:55:22.621432: [1m[ 0.030614] thermal_sys: Registered thermal governor 'power_allocator'[0m [0m2022-09-06 15:55:22.621488: [1m[ 0.030844] cpuidle: using governor menu[0m [0m2022-09-06 15:55:22.621504: [1m[ 0.031077] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.[0m [0m2022-09-06 15:55:22.621511: [1m[ 0.031194] ASID allocator initialised with 32768 entries[0m [0m2022-09-06 15:55:22.621518: [1m[ 0.032089] Serial: AMBA PL011 UART driver[0m [0m2022-09-06 15:55:22.621563: [1m[ 0.047057] platform c883a000.hdmi-tx: Fixing up cyclic dependency with d0100000.vpu[0m [0m2022-09-06 15:55:22.621641: [1m[ 0.049192] platform hdmi-connector: Fixing up cyclic dependency with c883a000.hdmi-tx[0m [0m2022-09-06 15:55:22.621688: [1m[ 0.059154] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages[0m [0m2022-09-06 15:55:22.621756: [1m[ 0.059176] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages[0m [0m2022-09-06 15:55:22.621839: [1m[ 0.059182] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages[0m [0m2022-09-06 15:55:22.621856: [1m[ 0.059188] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages[0m [0m2022-09-06 15:55:22.621863: [1m[ 0.060746] ACPI: Interpreter disabled.[0m [0m2022-09-06 15:55:22.621870: [1m[ 0.063553] iommu: Default domain type: Translated [0m [0m2022-09-06 15:55:22.621914: [1m[ 0.063574] iommu: DMA domain TLB invalidation policy: strict mode [0m [0m2022-09-06 15:55:22.621962: [1m[ 0.063891] vgaarb: loaded[0m [0m2022-09-06 15:55:22.622094: [1m[ 0.064185] SCSI subsystem initialized[0m [0m2022-09-06 15:55:22.622176: [1m[ 0.064552] usbcore: registered new interface driver usbfs[0m [0m2022-09-06 15:55:22.622195: [1m[ 0.064593] usbcore: registered new interface driver hub[0m [0m2022-09-06 15:55:22.622373: [1m[ 0.064624] usbcore: registered new device driver usb[0m [0m2022-09-06 15:55:22.622474: [1m[ 0.065164] pps_core: LinuxPPS API ver. 1 registered[0m [0m2022-09-06 15:55:22.622534: [1m[ 0.065172] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>[0m [0m2022-09-06 15:55:22.622546: [1m[ 0.065187] PTP clock support registered[0m [0m2022-09-06 15:55:22.622555: [1m[ 0.065223] EDAC MC: Ver: 3.0.0[0m [0m2022-09-06 15:55:22.622562: [1m[ 0.066769] FPGA manager framework[0m [0m2022-09-06 15:55:22.622614: [1m[ 0.066874] Advanced Linux Sound Architecture Driver Initialized.[0m [0m2022-09-06 15:55:22.622631: [1m[ 0.067873] clocksource: Switched to clocksource arch_sys_counter[0m [0m2022-09-06 15:55:22.622643: [1m[ 0.068090] VFS: Disk quotas dquot_6.6.0[0m [0m2022-09-06 15:55:22.622651: [1m[ 0.068135] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)[0m [0m2022-09-06 15:55:22.622718: [1m[ 0.068319] pnp: PnP ACPI: disabled[0m [0m2022-09-06 15:55:22.622730: [1m[ 0.073378] NET: Registered PF_INET protocol family[0m [0m2022-09-06 15:55:22.622739: [1m[ 0.073530] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)[0m [0m2022-09-06 15:55:22.622747: [1m[ 0.074205] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)[0m [0m2022-09-06 15:55:22.622800: [1m[ 0.074285] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)[0m [0m2022-09-06 15:55:22.622814: [1m[ 0.074349] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)[0m [0m2022-09-06 15:55:22.622823: [1m[ 0.074456] TCP: Hash tables configured (established 8192 bind 8192)[0m [0m2022-09-06 15:55:22.622831: [1m[ 0.074582] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)[0m [0m2022-09-06 15:55:22.622837: [1m[ 0.074613] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)[0m [0m2022-09-06 15:55:22.622923: [1m[ 0.074756] NET: Registered PF_UNIX/PF_LOCAL protocol family[0m [0m2022-09-06 15:55:22.622981: [1m[ 0.075138] RPC: Registered named UNIX socket transport module.[0m [0m2022-09-06 15:55:22.622999: [1m[ 0.075152] RPC: Registered udp transport module.[0m [0m2022-09-06 15:55:22.623006: [1m[ 0.075157] RPC: Registered tcp transport module.[0m [0m2022-09-06 15:55:22.623049: [1m[ 0.075161] RPC: Registered tcp NFSv4.1 backchannel transport module.[0m [0m2022-09-06 15:55:22.623073: [1m[ 0.075173] PCI: CLS 0 bytes, default 64[0m [0m2022-09-06 15:55:22.623081: [1m[ 0.076021] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available[0m [0m2022-09-06 15:55:22.623088: [1m[ 0.076520] kvm [1]: IPA Size Limit: 40 bits[0m [0m2022-09-06 15:55:22.623097: [1m[ 0.078006] kvm [1]: vgic interrupt IRQ9[0m [0m2022-09-06 15:55:22.623162: [1m[ 0.078130] kvm [1]: Hyp mode initialized successfully[0m [0m2022-09-06 15:55:22.623185: [1m[ 0.079576] workingset: timestamp_bits=42 max_order=18 bucket_order=0[0m [0m2022-09-06 15:55:22.623263: [1m[ 0.085259] squashfs: version 4.0 (2009/01/31) Phillip Lougher[0m [0m2022-09-06 15:55:22.623322: [1m[ 0.085886] NFS: Registering the id_resolver key type[0m [0m2022-09-06 15:55:22.623340: [1m[ 0.085925] Key type id_resolver registered[0m [0m2022-09-06 15:55:22.623348: [1m[ 0.085930] Key type id_legacy registered[0m [0m2022-09-06 15:55:22.623415: [1m[ 0.085999] nfs4filelayout_init: NFSv4 File Layout Driver Registering...[0m [0m2022-09-06 15:55:22.623427: [1m[ 0.086007] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...[0m [0m2022-09-06 15:55:22.623505: [1m[ 0.086176] 9p: Installing v9fs 9p2000 file system support[0m [0m2022-09-06 15:55:22.623567: [1m[ 0.122705] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)[0m [0m2022-09-06 15:55:22.623585: [1m[ 0.122720] io scheduler mq-deadline registered[0m [0m2022-09-06 15:55:22.623593: [1m[ 0.122726] io scheduler kyber registered[0m [0m2022-09-06 15:55:22.623600: [1m[ 0.123674] irq_meson_gpio: 110 to 8 gpio interrupt mux initialized[0m [0m2022-09-06 15:55:22.623641: [1m[ 0.133443] EINJ: ACPI disabled.[0m [0m2022-09-06 15:55:22.623662: [1m[ 0.145920] soc soc0: Amlogic Meson GXL (S805X) Revision 21:d (34:2) Detected[0m [0m2022-09-06 15:55:22.623740: [1m[ 0.151847] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled[0m [0m2022-09-06 15:55:22.623799: [1m[ 0.154587] c81004c0.serial: ttyAML0 at MMIO 0xc81004c0 (irq = 22, base_baud = 1500000) is a meson_uart[0m [0m2022-09-06 15:55:22.623809: [1m[ 1.089867] printk: console [ttyAML0] enabled[0m [0m2022-09-06 15:55:22.623818: [1m[ 1.094921] msm_serial: driver initialized[0m [0m2022-09-06 15:55:22.623867: [1m[ 1.110551] lima d00c0000.gpu: gp - mali450 version major 0 minor 0[0m [0m2022-09-06 15:55:22.623959: [1m[ 1.111226] lima d00c0000.gpu: pp0 - mali450 version major 0 minor 0[0m [0m2022-09-06 15:55:22.623983: [1m[ 1.117554] lima d00c0000.gpu: pp1 - mali450 version major 0 minor 0[0m [0m2022-09-06 15:55:22.623991: [1m[ 1.123821] lima d00c0000.gpu: pp2 - mali450 version major 0 minor 0[0m [0m2022-09-06 15:55:22.623998: [1m[ 1.130121] lima d00c0000.gpu: l2 cache 8K, 4-way, 64byte cache line, 128bit external bus[0m [0m2022-09-06 15:55:22.624007: [1m[ 1.138197] lima d00c0000.gpu: l2 cache 64K, 4-way, 64byte cache line, 128bit external bus[0m [0m2022-09-06 15:55:22.624183: [1m[ 1.146794] lima d00c0000.gpu: bus rate = 166666667[0m [0m2022-09-06 15:55:22.624260: [1m[ 1.151237] lima d00c0000.gpu: mod rate = 24000000[0m [0m2022-09-06 15:55:22.624375: [1m[ 1.156039] lima d00c0000.gpu: dev_pm_opp_set_regulators: no regulator (mali) found: -19[0m [0m2022-09-06 15:55:22.624455: [1m[ 1.164566] lima d00c0000.gpu: Failed to register cooling device[0m [0m2022-09-06 15:55:22.624522: [1m[ 1.170231] [drm] Initialized lima 1.1.0 20191231 for d00c0000.gpu on minor 0[0m [0m2022-09-06 15:55:22.624537: [1m[ 1.183008] loop: module loaded[0m [0m2022-09-06 15:55:22.624547: [1m[ 1.183832] megasas: 07.719.03.00-rc1[0m [0m2022-09-06 15:55:22.624554: [1m[ 1.189512] tun: Universal TUN/TAP device driver, 1.6[0m [0m2022-09-06 15:55:22.624621: [1m[ 1.190427] thunder_xcv, ver 1.0[0m [0m2022-09-06 15:55:22.624639: [1m[ 1.192419] thunder_bgx, ver 1.0[0m [0m2022-09-06 15:55:22.624710: [1m[ 1.195574] nicpf, ver 1.0[0m [0m2022-09-06 15:55:22.624805: [1m[ 1.199139] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version[0m [0m2022-09-06 15:55:22.624861: [1m[ 1.205389] hns3: Copyright (c) 2017 Huawei Corporation.[0m [0m2022-09-06 15:55:22.624993: [1m[ 1.210736] hclge is initializing[0m [0m2022-09-06 15:55:22.625119: [1m[ 1.213941] e1000: Intel(R) PRO/1000 Network Driver[0m [0m2022-09-06 15:55:22.625196: [1m[ 1.218749] e1000: Copyright (c) 1999-2006 Intel Corporation.[0m [0m2022-09-06 15:55:22.625270: [1m[ 1.224501] e1000e: Intel(R) PRO/1000 Network Driver[0m [0m2022-09-06 15:55:22.625290: [1m[ 1.229359] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.[0m [0m2022-09-06 15:55:22.625299: [1m[ 1.235259] igb: Intel(R) Gigabit Ethernet Network Driver[0m [0m2022-09-06 15:55:22.625305: [1m[ 1.240577] igb: Copyright (c) 2007-2014 Intel Corporation.[0m [0m2022-09-06 15:55:22.625312: [1m[ 1.246120] igbvf: Intel(R) Gigabit Virtual Function Network Driver[0m [0m2022-09-06 15:55:22.625370: [1m[ 1.252300] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.[0m [0m2022-09-06 15:55:22.625393: [1m[ 1.258192] sky2: driver version 1.30[0m [0m2022-09-06 15:55:22.625454: [1m[ 1.263588] usbcore: registered new interface driver r8152[0m [0m2022-09-06 15:55:22.625483: [1m[ 1.267273] usbcore: registered new interface driver asix[0m [0m2022-09-06 15:55:22.625567: [1m[ 1.272607] usbcore: registered new interface driver ax88179_178a[0m [0m2022-09-06 15:55:22.625625: [1m[ 1.278639] usbcore: registered new interface driver cdc_ether[0m [0m2022-09-06 15:55:22.625637: [1m[ 1.284418] usbcore: registered new interface driver smsc95xx[0m [0m2022-09-06 15:55:22.625644: [1m[ 1.290100] usbcore: registered new interface driver net1080[0m [0m2022-09-06 15:55:22.625654: [1m[ 1.295705] usbcore: registered new interface driver cdc_subset[0m [0m2022-09-06 15:55:22.625697: [1m[ 1.301578] usbcore: registered new interface driver zaurus[0m [0m2022-09-06 15:55:22.625721: [1m[ 1.307117] usbcore: registered new interface driver cdc_ncm[0m [0m2022-09-06 15:55:22.625730: [1m[ 1.312870] VFIO - User Level meta-driver version: 0.3[0m [0m2022-09-06 15:55:22.625737: [1m[ 1.320077] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver[0m [0m2022-09-06 15:55:22.625787: [1m[ 1.324243] ehci-pci: EHCI PCI platform driver[0m [0m2022-09-06 15:55:22.625884: [1m[ 1.328688] ehci-platform: EHCI generic platform driver[0m [0m2022-09-06 15:55:22.625898: [1m[ 1.334008] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver[0m [0m2022-09-06 15:55:22.625982: [1m[ 1.339964] ohci-pci: OHCI PCI platform driver[0m [0m2022-09-06 15:55:22.626036: [1m[ 1.344383] ohci-platform: OHCI generic platform driver[0m [0m2022-09-06 15:55:22.626120: [1m[ 1.350278] usbcore: registered new interface driver usb-storage[0m [0m2022-09-06 15:55:22.626200: [1m[ 1.357915] i2c_dev: i2c /dev entries driver[0m [0m2022-09-06 15:55:22.626248: [1m[ 1.365301] sdhci: Secure Digital Host Controller Interface driver[0m [0m2022-09-06 15:55:22.626306: [1m[ 1.365844] sdhci: Copyright(c) Pierre Ossman[0m [0m2022-09-06 15:55:22.626316: [1m[ 1.370829] Synopsys Designware Multimedia Card Interface Driver[0m [0m2022-09-06 15:55:22.626323: [1m[ 1.377263] sdhci-pltfm: SDHCI platform and OF driver helper[0m [0m2022-09-06 15:55:22.626332: [1m[ 1.377626] meson-gx-mmc d0074000.mmc: allocated mmc-pwrseq[0m [0m2022-09-06 15:55:22.626389: [1m[ 1.387345] ledtrig-cpu: registered to indicate activity on CPUs[0m [0m2022-09-06 15:55:22.626402: [1m[ 1.394696] meson-sm: secure-monitor enabled[0m [0m2022-09-06 15:55:22.626409: [1m[ 1.398611] usbcore: registered new interface driver usbhid[0m [0m2022-09-06 15:55:22.626418: [1m[ 1.402966] usbhid: USB HID core driver[0m [0m2022-09-06 15:55:22.626457: [1m[ 1.408011] platform-mhu c883c404.mailbox: Platform MHU Mailbox registered[0m [0m2022-09-06 15:55:22.626546: [1m[ 1.418208] gnss: GNSS driver registered with major 511[0m [0m2022-09-06 15:55:22.626565: [1m[ 1.422212] NET: Registered PF_PACKET protocol family[0m [0m2022-09-06 15:55:22.626573: [1m[ 1.423899] 9pnet: Installing 9P2000 support[0m [0m2022-09-06 15:55:22.626628: [1m[ 1.428020] Key type dns_resolver registered[0m [0m2022-09-06 15:55:22.626642: [1m[ 1.451735] meson-drm d0100000.vpu: Queued 1 outputs on vpu[0m [0m2022-09-06 15:55:22.626652: [1m[ 1.452397] meson-drm d0100000.vpu: CVBS Output connector not available[0m [0m2022-09-06 15:55:22.626660: [1m[ 1.483942] meson-dw-hdmi c883a000.hdmi-tx: Detected HDMI TX controller v2.01a with HDCP (meson_dw_hdmi_phy)[0m [0m2022-09-06 15:55:22.626744: [1m[ 1.488196] mmc1: new HS200 MMC card at address 0001[0m [0m2022-09-06 15:55:22.626803: [1m[ 1.493703] meson-dw-hdmi c883a000.hdmi-tx: registered DesignWare HDMI I2C bus driver[0m [0m2022-09-06 15:55:22.626818: [1m[ 1.493763] mmcblk1: mmc1:0001 H8G4a� 7.28 GiB [0m [0m2022-09-06 15:55:22.626830: [1m[ 1.502244] meson-drm d0100000.vpu: bound c883a000.hdmi-tx (ops meson_dw_hdmi_ops)[0m [0m2022-09-06 15:55:22.626883: [1m[ 1.508441] mmcblk1boot0: mmc1:0001 H8G4a� 4.00 MiB [0m [0m2022-09-06 15:55:22.626899: [1m[ 1.513473] [drm] Initialized meson 1.0.0 20161109 for d0100000.vpu on minor 1[0m [0m2022-09-06 15:55:22.626910: [1m[ 1.519310] mmcblk1boot1: mmc1:0001 H8G4a� 4.00 MiB [0m [0m2022-09-06 15:55:22.626918: [1m[ 1.525031] meson-drm d0100000.vpu: [drm] Cannot find any crtc or sizes[0m [0m2022-09-06 15:55:22.626978: [1m[ 1.531154] mmcblk1rpmb: mmc1:0001 H8G4a� 4.00 MiB, chardev (234:0)[0m [0m2022-09-06 15:55:22.626996: [1m[ 1.540865] meson8b-dwmac c9410000.ethernet: IRQ eth_wake_irq not found[0m [0m2022-09-06 15:55:22.627004: [1m[ 1.549140] meson8b-dwmac c9410000.ethernet: IRQ eth_lpi not found[0m [0m2022-09-06 15:55:22.627011: [1m[ 1.555328] meson8b-dwmac c9410000.ethernet: PTP uses main clock[0m [0m2022-09-06 15:55:22.627071: [1m[ 1.561931] meson8b-dwmac c9410000.ethernet: User ID: 0x11, Synopsys ID: 0x37[0m [0m2022-09-06 15:55:22.627155: [1m[ 1.568283] meson8b-dwmac c9410000.ethernet: DWMAC1000[0m [0m2022-09-06 15:55:22.627171: [1m[ 1.573430] meson8b-dwmac c9410000.ethernet: DMA HW capability register supported[0m [0m2022-09-06 15:55:22.627184: [1m[ 1.580846] meson8b-dwmac c9410000.ethernet: RX Checksum Offload Engine supported[0m [0m2022-09-06 15:55:22.627300: [1m[ 1.588263] meson8b-dwmac c9410000.ethernet: COE Type 2[0m [0m2022-09-06 15:55:22.627323: [1m[ 1.593437] meson8b-dwmac c9410000.ethernet: TX Checksum insertion supported[0m [0m2022-09-06 15:55:22.627332: [1m[ 1.600423] meson8b-dwmac c9410000.ethernet: Wake-Up On Lan supported[0m [0m2022-09-06 15:55:22.627339: [1m[ 1.606874] meson8b-dwmac c9410000.ethernet: Normal descriptors[0m [0m2022-09-06 15:55:22.627348: [1m[ 1.612673] meson8b-dwmac c9410000.ethernet: Ring mode enabled[0m [0m2022-09-06 15:55:22.627410: [1m[ 1.618456] meson8b-dwmac c9410000.ethernet: Enable RX Mitigation via HW Watchdog Timer[0m [0m2022-09-06 15:55:22.627420: [1m[ 1.629222] dwc3-meson-g12a d0078080.usb: USB2 ports: 2[0m [0m2022-09-06 15:55:22.627428: [1m[ 1.631555] dwc3-meson-g12a d0078080.usb: USB3 ports: 0[0m [0m2022-09-06 15:55:22.627434: [1m[ 2.149749] dwc2 c9100000.usb: supply vusb_d not found, using dummy regulator[0m [0m2022-09-06 15:55:22.627485: [1m[ 2.151401] dwc2 c9100000.usb: supply vusb_a not found, using dummy regulator[0m [0m2022-09-06 15:55:22.627571: [1m[ 2.158633] dwc2 c9100000.usb: EPs: 7, dedicated fifos, 712 entries in SPRAM[0m [0m2022-09-06 15:55:22.627592: [1m[ 2.168181] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller[0m [0m2022-09-06 15:55:22.627656: [1m[ 2.170761] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1[0m [0m2022-09-06 15:55:22.627729: [1m[ 2.178488] xhci-hcd xhci-hcd.2.auto: hcc params 0x0228f664 hci version 0x100 quirks 0x0000000002010010[0m [0m2022-09-06 15:55:22.627779: [1m[ 2.187707] xhci-hcd xhci-hcd.2.auto: irq 47, io mem 0xc9000000[0m [0m2022-09-06 15:55:22.627884: [1m[ 2.194254] hub 1-0:1.0: USB hub found[0m [0m2022-09-06 15:55:22.627960: [1m[ 2.197263] hub 1-0:1.0: 2 ports detected[0m [0m2022-09-06 15:55:22.628044: [1m[ 2.201508] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller[0m [0m2022-09-06 15:55:22.628106: [1m[ 2.206644] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2[0m [0m2022-09-06 15:55:22.628192: [1m[ 2.214233] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed[0m [0m2022-09-06 15:55:22.628210: [1m[ 2.220736] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.[0m [0m2022-09-06 15:55:22.628218: [1m[ 2.229297] hub 2-0:1.0: USB hub found[0m [0m2022-09-06 15:55:22.628225: [1m[ 2.232454] hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19)[0m [0m2022-09-06 15:55:22.628302: [1m[ 2.241822] scpi_protocol scpi: SCP Protocol legacy pre-1.0 firmware[0m [0m2022-09-06 15:55:22.628388: [1m[ 2.330883] meson8b-dwmac c9410000.ethernet eth0: PHY [0.e40908ff:08] driver [Meson GXL Internal PHY] (irq=48)[0m [0m2022-09-06 15:55:22.628449: [1m[ 2.336693] meson8b-dwmac c9410000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0[0m [0m2022-09-06 15:55:22.628463: [1m[ 2.354215] meson8b-dwmac c9410000.ethernet eth0: No Safety Features support found[0m [0m2022-09-06 15:55:22.628479: [1m[ 2.356195] meson8b-dwmac c9410000.ethernet eth0: PTP not supported by HW[0m [0m2022-09-06 15:55:22.628533: [1m[ 2.363582] meson8b-dwmac c9410000.ethernet eth0: configuring for phy/rmii link mode[0m [0m2022-09-06 15:55:22.628551: [1m[ 4.221266] meson8b-dwmac c9410000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off[0m [0m2022-09-06 15:55:22.628866: #######################T ###### [0m2022-09-06 15:55:22.628885: 3.2 MiB/s [0m2022-09-06 15:55:22.628891: done [0m2022-09-06 15:55:22.628896: Bytes transferred = 33808960 (203e240 hex) [0m2022-09-06 15:55:22.628900: $ setenv initrd_size ${filesize} [0m2022-09-06 15:55:22.628907: => setenv initrd_size ${filesize} [0m2022-09-06 15:55:22.628912: setenv initrd_size ${filesize} [0m2022-09-06 15:55:22.628923: $ tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb [0m2022-09-06 15:55:22.628930: => tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb [0m2022-09-06 15:55:22.628935: tftpboot 0x08008000 25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb [0m2022-09-06 15:55:22.628940: Speed: 100, full duplex [0m2022-09-06 15:55:22.628983: Using ethernet@c9410000 device [0m2022-09-06 15:55:22.628988: TFTP from server 10.0.70.21; our IP address is 10.0.70.83 [0m2022-09-06 15:55:22.628993: Filename '25747/tftp-deploy-4e37gkg_/dtb/meson-gxl-s805x-libretech-ac.dtb'. [0m2022-09-06 15:55:22.628997: Load address: 0x8008000 [0m2022-09-06 15:55:22.629002: Loading: *## [0m2022-09-06 15:55:22.629006: 3.8 MiB/s [0m2022-09-06 15:55:22.629010: done [0m2022-09-06 15:55:22.629015: Bytes transferred = 28084 (6db4 hex) [0m2022-09-06 15:55:22.629019: $ setenv bootargs console=ttyAML0,115200n8 [0m2022-09-06 15:55:22.629116: =>setenv bootargs console=ttyAML0,115200n8 [0m2022-09-06 15:55:22.629129: setenv bootargs console=ttyAML0,115200n8 [0m2022-09-06 15:55:22.629174: $ setenv bootargs ${bootargs} root=/dev/nfs rw [0m2022-09-06 15:55:22.629190: =>setenv bootargs ${bootargs} root=/dev/nfs rw [0m2022-09-06 15:55:22.629196: setenv bootargs ${bootargs} root=/dev/nfs rw [0m2022-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 [0m2022-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 [0m2022-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 [0m2022-09-06 15:55:22.629216: $ setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10 [0m2022-09-06 15:55:22.629264: =>setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10 [0m2022-09-06 15:55:22.629275: setenv bootargs ${bootargs} rootwait rootdelay=5 carrier_timeout=10 [0m2022-09-06 15:55:22.629280: $ setenv bootargs ${bootargs} ip=dhcp [0m2022-09-06 15:55:22.629286: =>setenv bootargs ${bootargs} ip=dhcp [0m2022-09-06 15:55:22.629291: setenv bootargs ${bootargs} ip=dhcp [0m2022-09-06 15:55:22.629297: $ bootm 0x08080000 - 0x08008000 [0m2022-09-06 15:55:22.629304: => bootm 0x08080000 - 0x08008000 [0m2022-09-06 15:55:22.629345: bootm 0x08080000 - 0x08008000 [0m2022-09-06 15:55:22.629385: ## Booting kernel from Legacy Image at 08080000 ... [0m2022-09-06 15:55:22.629454: Image Name: [0m2022-09-06 15:55:22.629514: Created: 2022-09-06 15:54:52 UTC [0m2022-09-06 15:55:22.629521: Image Type: AArch64 Linux Kernel Image (uncompressed) [0m2022-09-06 15:55:22.629528: Data Size: 33808896 Bytes = 32.2 MiB [0m2022-09-06 15:55:22.629533: Load Address: 08080000 [0m2022-09-06 15:55:22.629538: Entry Point: 08080000 [0m2022-09-06 15:55:22.629542: Verifying Checksum ... OK [0m2022-09-06 15:55:22.629596: ## Flattened Device Tree blob at 08008000 [0m2022-09-06 15:55:22.629604: Booting using the fdt blob at 0x8008000 [0m2022-09-06 15:55:22.629609: Loading Kernel Image [0m2022-09-06 15:55:22.629613: Loading Device Tree to 000000001fff6000, end 000000001ffffdb3 ... OK [0m2022-09-06 15:55:22.629618: Starting kernel ... [0m2022-09-06 15:55:22.629623: Parsing kernel messages [0m2022-09-06 15:55:22.629627: [ 0.000000] Trampoline variant of Tasks RCU enabled. [0m2022-09-06 15:55:22.629632: domain-0 init dvfs: 4 [0m2022-09-06 15:55:27.777477: [1m[ 4.248085] Sending DHCP requests .., OK[0m [0m2022-09-06 15:55:27.777531: [1m[ 6.688463] IP-Config: Got DHCP answer from 10.0.70.100, my address is 10.0.70.83[0m [0m2022-09-06 15:55:27.777551: [1m[ 6.693909] IP-Config: Complete:[0m [0m2022-09-06 15:55:27.777590: [1m[ 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[0m [0m2022-09-06 15:55:27.777605: [1m[ 6.706894] host=meson-gxl-s805x-libretech-ac-3, domain=enunes.eu, nis-domain=(none)[0m [0m2022-09-06 15:55:27.777617: [1m[ 6.715004] bootserver=0.0.0.0, rootserver=10.0.70.21, rootpath=[0m [0m2022-09-06 15:55:27.777644: [1m[ 6.715071] nameserver0=10.0.70.100[0m [0m2022-09-06 15:55:27.777678: [1m[ 6.728187] ALSA device list:[0m [0m2022-09-06 15:55:27.777708: [1m[ 6.728354] No soundcards found.[0m [0m2022-09-06 15:55:27.777738: [1m[ 6.737605] Waiting 5 sec before mounting root device...[0m [0m2022-09-06 15:55:32.829320: [1m[ 11.786422] VFS: Mounted root (nfs filesystem) on device 0:21.[0m [0m2022-09-06 15:55:32.829492: [1m[ 11.787214] devtmpfs: mounted[0m [0m2022-09-06 15:55:32.829682: [1m[ 11.791661] Freeing unused kernel memory: 5568K[0m [0m2022-09-06 15:55:32.829779: [1m[ 11.807990] Run /init as init process[0m [0m2022-09-06 15:55:32.829978: [1m[ 11.830847] random: fast init done[0m [0m2022-09-06 15:55:32.830609: [1m[ 13.190492] <LAVA_SIGNAL_STARTRUN 0_mesa 25747_1.4.2.4.1>[0m [0m2022-09-06 15:55:32.830935: [1m[ 14.257177] NET: Registered PF_INET6 protocol family[0m [0m2022-09-06 15:55:32.831098: [1m[ 14.258499] Segment Routing with IPv6[0m [0m2022-09-06 15:55:32.831232: [1m[ 14.260204] In-situ OAM (IOAM) with IPv6[0m [0m2022-09-06 15:55:32.831567: [1m[ 17.011896] random: crng init done[0m [0m2022-09-06 15:55:32.831774: sh: 0: can't access tty; job control turned off [0m2022-09-06 15:55:32.831887: $ [0m2022-09-06 15:55:32.832137: lava-shell: [0m2022-09-06 15:55:32.832284: $ export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq' [0m2022-09-06 15:55:32.832559: lava-shell:export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq' [0m2022-09-06 15:55:32.832696: export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/25747/extract-nfsrootfs-e751akoq' [0m2022-09-06 15:55:32.832836: $ export NFS_SERVER_IP='10.0.70.21' [0m2022-09-06 15:55:32.833109: lava-shell:export NFS_SERVER_IP='10.0.70.21' [0m2022-09-06 15:55:32.833229: export NFS_SERVER_IP='10.0.70.21' [0m2022-09-06 15:55:32.833369: end: 2 uboot-action (duration 00:00:33) [common] [0m2022-09-06 15:55:32.833511: start: 3 lava-test-retry (timeout 00:28:14) [common] [0m2022-09-06 15:55:32.833651: $ # [0m2022-09-06 15:55:32.833945: lava-shell:# [0m2022-09-06 15:55:32.834062: # [0m2022-09-06 15:55:32.834201: $ export SHELL=/bin/sh [0m2022-09-06 15:55:32.834453: lava-shell:export SHELL=/bin/sh [0m2022-09-06 15:55:32.834592: export SHELL=/bin/sh [0m2022-09-06 15:55:32.834733: $ . /lava-25747/environment [0m2022-09-06 15:55:32.834981: lava-shell:. /lava-25747/environment [0m2022-09-06 15:55:32.835125: . /lava-25747/environment [0m2022-09-06 15:55:32.835262: $ /lava-25747/bin/lava-test-runner /lava-25747/0 [0m2022-09-06 15:55:32.835519: lava-shell:/lava-25747/bin/lava-test-runner /lava-25747/0 [0m2022-09-06 15:55:32.835640: Test shell timeout: 10s (minimum of the action and connection timeout) [0m2022-09-06 15:55:32.835790: /lava-25747/bin/lava-test-runner /lava-25747/0 [0m2022-09-06 15:55:32.835927: + export TESTRUN_ID=0_mesa [0m2022-09-06 15:55:32.836067: + cd /lava-25747/0/tests/0_mesa [0m2022-09-06 15:55:32.836206: + cat uuid [0m2022-09-06 15:55:32.836344: + UUID=25747_1.4.2.4.1 [0m2022-09-06 15:55:32.836486: + set +x [0m2022-09-06 15:55:32.836625: [0Ksection_end:1662479732:lava_boot [0K [0m2022-09-06 15:55:32.836879: [0Ksection_start:1662479732:0_mesa-25747_1-4-2-4-1 [0K[1m[1;32;5;197mtest_suite 0_mesa 25747_1.4.2.4.1 - Timeout: 0:05:00[0m [0m2022-09-06 15:55:32.837136: Starting test lava.0_mesa (25747_1.4.2.4.1) [0m2022-09-06 15:55:32.837256: Skipping test definition patterns. [0m2022-09-06 15:55:32.837395: + set -ex [0m2022-09-06 15:55:32.837542: + cd / [0m2022-09-06 15:55:32.837684: + mount -t proc none /proc [0m2022-09-06 15:55:32.837830: + mount -t sysfs none /sys [0m2022-09-06 15:55:32.837971: + mount -t debugfs none /sys/kernel/debug [0m2022-09-06 15:55:32.838112: + mount -t devtmpfs none /dev [0m2022-09-06 15:55:32.838260: mount: /dev: none already mounted on /proc. [0m2022-09-06 15:55:32.838403: + echo possibly already mounted [0m2022-09-06 15:55:32.838549: possibly already mounted [0m2022-09-06 15:55:32.838691: + mkdir -p /dev/pts [0m2022-09-06 15:55:32.838834: + mount -t devpts devpts /dev/pts [0m2022-09-06 15:55:32.838981: + mount -t tmpfs tmpfs /tmp [0m2022-09-06 15:55:32.839123: + echo nameserver 8.8.8.8 [0m2022-09-06 15:55:32.839266: + [ -z 10.0.70.21 ] [0m2022-09-06 15:55:32.839410: + echo 10.0.70.21 caching-proxy [0m2022-09-06 15:55:32.839554: + sntp -sS pool.ntp.org [0m2022-09-06 15:55:32.839697: sntp 4.2.8p15@1.3728-o Wed Sep 23 11:46:38 UTC 2020 (1) [0m2022-09-06 15:55:32.839840: kod_init_kod_db(): Cannot open KoD db file /var/lib/sntp/kod: No such file or directory [0m2022-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 [0m2022-09-06 15:55:32.840148: + break [0m2022-09-06 15:55:32.840290: + set +x [0m2022-09-06 15:55:32.840436: + echo export CI_JOB_JWT_FILE=/minio_jwt [0m2022-09-06 15:55:32.840581: + mkdir -p /builds/enunes/mesa [0m2022-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 [0m2022-09-06 15:55:32.840866: + tar --zstd -x -C /builds/enunes/mesa [0m2022-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 [0m2022-09-06 15:55:32.841176: Resolving minio-packet.freedesktop.org (minio-packet.freedesktop.org)... 147.75.198.117 [0m2022-09-06 15:55:32.841327: Connecting to minio-packet.freedesktop.org (minio-packet.freedesktop.org)|147.75.198.117|:443... connected. [0m2022-09-06 15:55:38.171601: HTTP request sent, awaiting response... [0m2022-09-06 15:55:38.171736: HTTP/1.1 200 OK [0m2022-09-06 15:55:38.171776: Date: Tue, 06 Sep 2022 15:55:31 GMT [0m2022-09-06 15:55:38.171813: Content-Type: binary/octet-stream [0m2022-09-06 15:55:38.171850: Content-Length: 13549533 [0m2022-09-06 15:55:38.171887: Connection: keep-alive [0m2022-09-06 15:55:38.171924: Accept-Ranges: bytes [0m2022-09-06 15:55:38.171961: Content-Security-Policy: block-all-mixed-content [0m2022-09-06 15:55:38.172007: ETag: \"141b73ea0ae0cc1bd0f0ce50a433d407-2\" [0m2022-09-06 15:55:38.172045: Last-Modified: Tue, 06 Sep 2022 15:52:40 GMT [0m2022-09-06 15:55:38.172081: Vary: Origin [0m2022-09-06 15:55:38.172118: X-Amz-Request-Id: 17125081D68BB24E [0m2022-09-06 15:55:38.172154: X-Xss-Protection: 1; mode=block [0m2022-09-06 15:55:38.172190: x-amz-expiration: expiry-date=\"Fri, 07 Oct 2022 00:00:00 GMT\", rule-id=\"Artifacts - 4 weeks\" [0m2022-09-06 15:55:38.172226: Strict-Transport-Security: max-age=15724800; includeSubDomains [0m2022-09-06 15:55:38.172262: Access-Control-Allow-Origin: * [0m2022-09-06 15:55:38.172299: Length: 13549533 (13M) [binary/octet-stream] [0m2022-09-06 15:55:38.172342: Saving to: 'STDOUT' [0m2022-09-06 15:55:48.220621: 0K ........ .... 100% 1.14M=11s [0m2022-09-06 15:55:48.220808: 2022-09-06 15:55:42 (1.14 MB/s) - written to stdout [13549533/13549533] [0m2022-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 [0m2022-09-06 15:55:48.221125: + tar -xz -C / [0m2022-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 [0m2022-09-06 15:55:48.221382: Resolving minio-packet.freedesktop.org (minio-packet.freedesktop.org)... 147.75.198.117 [0m2022-09-06 15:55:48.221518: Connecting to minio-packet.freedesktop.org (minio-packet.freedesktop.org)|147.75.198.117|:443... connected. [0m2022-09-06 15:55:48.221667: HTTP request sent, awaiting response... [0m2022-09-06 15:55:53.248662: [1m[ 30.794467] <LAVA_SIGNAL_STARTTC mesa-ci_lima-mali450-deqp-egl:arm64>[0m [0m2022-09-06 15:55:53.249802: HTTP/1.1 200 OK [0m2022-09-06 15:55:53.249850: Date: Tue, 06 Sep 2022 15:55:43 GMT [0m2022-09-06 15:55:53.250013: Content-Type: binary/octet-stream [0m2022-09-06 15:55:53.250056: Content-Length: 8813 [0m2022-09-06 15:55:53.250163: Connection: keep-alive [0m2022-09-06 15:55:53.250253: Accept-Ranges: bytes [0m2022-09-06 15:55:53.250395: Content-Security-Policy: block-all-mixed-content [0m2022-09-06 15:55:53.250435: ETag: \"823ffb7e46f908d48e413e4c3860f730\" [0m2022-09-06 15:55:53.250527: Last-Modified: Tue, 06 Sep 2022 15:53:25 GMT [0m2022-09-06 15:55:53.250703: Vary: Origin [0m2022-09-06 15:55:53.250810: X-Amz-Request-Id: 17125084AFFBDC2B [0m2022-09-06 15:55:53.250848: X-Xss-Protection: 1; mode=block [0m2022-09-06 15:55:53.250936: x-amz-expiration: expiry-date=\"Fri, 07 Oct 2022 00:00:00 GMT\", rule-id=\"Artifacts - 4 weeks\" [0m2022-09-06 15:55:53.251128: Strict-Transport-Security: max-age=15724800; includeSubDomains [0m2022-09-06 15:55:53.251247: Access-Control-Allow-Origin: * [0m2022-09-06 15:55:53.251287: Length: 8813 (8.6K) [binary/octet-stream] [0m2022-09-06 15:55:53.251388: Saving to: 'STDOUT' [0m2022-09-06 15:55:53.251542: 0K 100% 90.5K=0.1s [0m2022-09-06 15:55:53.251583: 2022-09-06 15:55:43 (90.5 KB/s) - written to stdout [8813/8813] [0m2022-09-06 15:55:53.251672: + sleep 1 [0m2022-09-06 15:55:53.251842: + lava-test-case mesa-ci_lima-mali450-deqp-egl:arm64 --shell /init-stage2.sh [0m2022-09-06 15:55:53.251883: [0Ksection_end:1662479753:0_mesa-25747_1-4-2-4-1 [0K [0m2022-09-06 15:55:53.252002: [0Ksection_start:1662479753:mesa-ci_lima-mali450-deqp-egl-arm64 [0K[1m[1;32;5;197mtest_case mesa-ci_lima-mali450-deqp-egl:arm64 - Timeout: 0:30:00[0m [0m2022-09-06 15:55:53.252196: + [ -z ] [0m2022-09-06 15:55:53.252236: + [ = true ] [0m2022-09-06 15:55:53.252324: + ln -sf /builds/enunes/mesa/install /install [0m2022-09-06 15:55:53.252502: + export LD_LIBRARY_PATH=/install/lib [0m2022-09-06 15:55:53.252542: + export LIBGL_DRIVERS_PATH=/install/lib/dri [0m2022-09-06 15:55:53.252651: + export XDG_CACHE_HOME=/tmp [0m2022-09-06 15:55:53.252739: + python3 -c import sys;print(\":\".join(sys.path)) [0m2022-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 [0m2022-09-06 15:55:53.253022: + [ = true ] [0m2022-09-06 15:55:53.253063: + [ -n ] [0m2022-09-06 15:55:53.253174: + BACKGROUND_PIDS=169 [0m2022-09-06 15:55:53.253263: + [ -n+ 1 ] [0m2022-09-06 15:55:53.253360: /capture-devcoredump.sh [0m2022-09-06 15:55:53.253453: + echo touch /xorg-started; sleep 100000 [0m2022-09-06 15:55:53.253566: + BACKGROUND_PIDS=170 169 [0m2022-09-06 15:55:53.253656: + [ -e+ /xorg-started ] [0m2022-09-06 15:55:53.253763: env xinit /bin/sh /xorg-script -- /usr/bin/Xorg -noreset -s 0 -dpms -logfile /Xorg.0.log [0m2022-09-06 15:55:53.253870: + sleep 5 [0m2022-09-06 15:55:53.253959: X.Org X Server 1.20.11 [0m2022-09-06 15:55:53.254051: X Protocol Version 11, Revision 0 [0m2022-09-06 15:55:53.254188: Build Operating System: linux Debian [0m2022-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 [0m2022-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 [0m2022-09-06 15:55:53.254434: Build Date: 16 December 2021 05:08:23PM [0m2022-09-06 15:55:53.254590: xorg-server 2:1.20.11-1+deb11u1 (https://www.debian.org/support) [0m2022-09-06 15:55:53.254637: Current version of pixman: 0.40.0 [0m2022-09-06 15:55:53.254725: Before reporting problems, check http://wiki.x.org [0m2022-09-06 15:55:53.254916: to make sure that you have the latest version. [0m2022-09-06 15:55:53.255023: Markers: (--) probed, (**) from config file, (==) default setting, [0m2022-09-06 15:55:53.255063: (++) from command line, (!!) notice, (II) informational, [0m2022-09-06 15:55:53.255151: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. [0m2022-09-06 15:55:53.255324: (++) Log file: \"/Xorg.0.log\", Time: Tue Sep 6 15:55:45 2022 [0m2022-09-06 15:55:53.255376: (==) Using system config directory \"/usr/share/X11/xorg.conf.d\" [0m2022-09-06 15:55:53.255468: (EE) [0m2022-09-06 15:55:53.255620: Fatal server error: [0m2022-09-06 15:55:53.255661: (EE) no screens found(EE) [0m2022-09-06 15:55:53.255760: (EE) [0m2022-09-06 15:55:53.255871: Please consult the The X.Org Foundation support [0m2022-09-06 15:55:53.255978: at http://wiki.x.org [0m2022-09-06 15:55:53.256018: for help. [0m2022-09-06 15:55:53.256106: (EE) Please also check the log file at \"/Xorg.0.log\" for additional information. [0m2022-09-06 15:55:53.256283: (EE) [0m2022-09-06 15:55:53.256400: (EE) Server terminated with error (1). Closing log file. [0m2022-09-06 15:55:53.256441: + [ -e /xorg-started ] [0m2022-09-06 15:55:58.278200: + sleep 5 [0m2022-09-06 15:55:58.278295: + [ -e /xorg-started ] [0m2022-09-06 15:56:03.306770: + sleep 5 [0m2022-09-06 15:56:03.307173: + [ -e /xorg-started ] [0m2022-09-06 15:56:08.329296: + sleep 5 [0m2022-09-06 15:56:08.329654: xinit: giving up [0m2022-09-06 15:56:08.329788: xinit: unable to connect to X server: Invalid argument [0m2022-09-06 15:56:08.329832: xinit: server error [0m2022-09-06 15:56:08.329873: + [ -e /xorg-started ] [0m2022-09-06 15:56:13.352896: + sleep 5 [0m2022-09-06 15:56:13.353247: + export DISPLAY=:0 [0m2022-09-06 15:56:18.437989: + RESULT=fail [0m2022-09-06 15:56:18.438080: + set +e [0m2022-09-06 15:56:18.438121: + sh -c /install/deqp-runner.sh [0m2022-09-06 15:56:18.438219: [0Ksection_start:1662479770:test_setup[collapsed=true] [0Kpreparing test setup [0m2022-09-06 15:56:18.438348: + ln -sf /builds/enunes/mesa/install /install [0m2022-09-06 15:56:18.438422: + '[' -z lima ']' [0m2022-09-06 15:56:18.438495: ++ pwd [0m2022-09-06 15:56:18.438559: + INSTALL=//install [0m2022-09-06 15:56:18.438633: ++ pwd [0m2022-09-06 15:56:18.438720: + export LD_LIBRARY_PATH=//install/lib/ [0m2022-09-06 15:56:18.438792: + LD_LIBRARY_PATH=//install/lib/ [0m2022-09-06 15:56:18.438865: + export EGL_PLATFORM=surfaceless [0m2022-09-06 15:56:18.439036: + EGL_PLATFORM=surfaceless [0m2022-09-06 15:56:18.439152: ++ pwd [0m2022-09-06 15:56:18.439193: ++ uname -m [0m2022-09-06 15:56:18.439272: + export VK_ICD_FILENAMES=//install/share/vulkan/icd.d/_icd.aarch64.json [0m2022-09-06 15:56:18.439346: + VK_ICD_FILENAMES=//install/share/vulkan/icd.d/_icd.aarch64.json [0m2022-09-06 15:56:18.439416: ++ pwd [0m2022-09-06 15:56:18.439490: + RESULTS=//results [0m2022-09-06 15:56:18.439634: + mkdir -p //results [0m2022-09-06 15:56:18.439676: + SHADER_CACHE_HOME=/tmp [0m2022-09-06 15:56:18.439749: + SHADER_CACHE_DIR=/tmp/mesa_shader_cache [0m2022-09-06 15:56:18.439816: + findmnt -n tmpfs /tmp [0m2022-09-06 15:56:18.439885: /tmp tmpfs tmpfs rw,relatime [0m2022-09-06 15:56:18.439952: + HANG_DETECTION_CMD= [0m2022-09-06 15:56:18.440026: + '[' -z '' ']' [0m2022-09-06 15:56:18.440102: + '[' -z egl ']' [0m2022-09-06 15:56:18.440168: + DEQP_WIDTH=256 [0m2022-09-06 15:56:18.440247: + DEQP_HEIGHT=256 [0m2022-09-06 15:56:18.440318: + DEQP_CONFIG=rgba8888d24s8ms0 [0m2022-09-06 15:56:18.440388: + DEQP_VARIANT=master [0m2022-09-06 15:56:18.440460: + DEQP_OPTIONS=' --deqp-surface-width=256 --deqp-surface-height=256' [0m2022-09-06 15:56:18.440529: + DEQP_OPTIONS=' --deqp-surface-width=256 --deqp-surface-height=256 --deqp-surface-type=pbuffer' [0m2022-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' [0m2022-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' [0m2022-09-06 15:56:18.440766: + '[' egl = vk -a -z '' ']' [0m2022-09-06 15:56:18.440853: + '[' egl = vk ']' [0m2022-09-06 15:56:18.440918: + '[' egl = gles2 -o egl = gles3 -o egl = gles31 -o egl = egl ']' [0m2022-09-06 15:56:18.440992: + MUSTPASS=/deqp/mustpass/egl-master.txt [0m2022-09-06 15:56:18.441057: + DEQP=/deqp/modules/egl/deqp-egl [0m2022-09-06 15:56:18.441142: + cp /deqp/mustpass/egl-master.txt /tmp/case-list.txt [0m2022-09-06 15:56:18.441214: + '[' -n '' ']' [0m2022-09-06 15:56:18.441289: + '[' -n '' ']' [0m2022-09-06 15:56:18.441363: + '[' -n '' ']' [0m2022-09-06 15:56:18.441441: + '[' -n '' ']' [0m2022-09-06 15:56:18.441581: + '[' '!' -s /tmp/case-list.txt ']' [0m2022-09-06 15:56:18.441622: + '[' -e //install/lima-fails.txt ']' [0m2022-09-06 15:56:18.441695: + DEQP_RUNNER_OPTIONS=' --baseline //install/lima-fails.txt' [0m2022-09-06 15:56:18.441769: + touch //install/lima-flakes.txt [0m2022-09-06 15:56:18.441853: + '[' -n '' ']' [0m2022-09-06 15:56:18.441920: + '[' -n '' ']' [0m2022-09-06 15:56:18.441992: + '[' -n '' ']' [0m2022-09-06 15:56:18.442064: + '[' -e //install/lima-skips.txt ']' [0m2022-09-06 15:56:18.442132: + DEQP_SKIPS=' //install/lima-skips.txt' [0m2022-09-06 15:56:18.442206: + '[' '' = virpipe ']' [0m2022-09-06 15:56:18.442282: + '[' -z '' ']' [0m2022-09-06 15:56:18.442356: + '[' -n '' ']' [0m2022-09-06 15:56:18.442453: + '[' egl '!=' vk -a egl '!=' egl ']' [0m2022-09-06 15:56:18.442524: + set +x [0m2022-09-06 15:56:18.442596: [0Ksection_end:1662479770:test_setup [0K [0m2022-09-06 15:56:18.442749: [0Ksection_start:1662479770:deqp[collapsed=false] [0Kdeqp-runner [0m2022-09-06 15:56:18.442884: + set +e [0m2022-09-06 15:56:18.442977: + '[' -z '' ']' [0m2022-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 [0m2022-09-06 15:56:18.443129: Running dEQP on 4 threads in 500-test groups [0m2022-09-06 15:56:18.443200: Pass: 0, Duration: 0 [0m2022-09-06 15:56:18.443281: [0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.443322: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.443413: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.443482: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.443554: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c3.r1.log\") [0m2022-09-06 15:56:18.443625: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c1.r1.log\") [0m2022-09-06 15:56:18.443662: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c0.r1.log\") [0m2022-09-06 15:56:18.443758: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c2.r1.log\") [0m2022-09-06 15:56:18.443837: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.443912: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.443990: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c7.r1.log\") [0m2022-09-06 15:56:18.444131: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c5.r1.log\") [0m2022-09-06 15:56:18.444172: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.444245: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c6.r1.log\") [0m2022-09-06 15:56:18.444323: [0m[0m[31mERROR - dEQP error: FATAL ERROR: Failed to open display at tcuLnxX11.cpp:85 [0m2022-09-06 15:56:18.444408: [0m[0m[31mERROR - Failure getting run results: No results parsed. Is your caselist out of sync with your deqp binary? (See \"//results/c4.r1.log\") [0m2022-09-06 15:56:18.444481: [0mPass: 0, Skip: 186, Missing: 3574, Duration: 0, Remaining: 0 [0m2022-09-06 15:56:18.444566: Slowest tests: [0m2022-09-06 15:56:18.444633: dEQP-EGL.functional.negative_partial_update.not_current_surface (0.00s) [0m2022-09-06 15:56:18.444708: dEQP-EGL.functional.create_context_ext.robust_gl_33.rgb565_depth_stencil (0.00s) [0m2022-09-06 15:56:18.444795: dEQP-EGL.functional.sharing.gles2.multithread.random.images.copytexsubimage2d.2 (0.00s) [0m2022-09-06 15:56:18.444862: dEQP-EGL.functional.sharing.gles2.multithread.random.textures.copytexsubimage2d.1 (0.00s) [0m2022-09-06 15:56:18.444958: dEQP-EGL.functional.sharing.gles2.multithread.random_egl_sync.images.copyteximage2d.12 (0.00s) [0m2022-09-06 15:56:18.445037: Some failures found: [0m2022-09-06 15:56:18.445123: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_clear,Missing [0m2022-09-06 15:56:18.445192: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_clear_clear,Missing [0m2022-09-06 15:56:18.445272: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_none,Missing [0m2022-09-06 15:56:18.445309: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_clear_even_render,Missing [0m2022-09-06 15:56:18.445380: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_even_clear,Missing [0m2022-09-06 15:56:18.445454: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_even_none,Missing [0m2022-09-06 15:56:18.445516: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_clear,Missing [0m2022-09-06 15:56:18.445600: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_clear_clear,Missing [0m2022-09-06 15:56:18.445667: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_clear_render,Missing [0m2022-09-06 15:56:18.445741: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_none,Missing [0m2022-09-06 15:56:18.445811: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_render,Missing [0m2022-09-06 15:56:18.445885: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_clear_render_even_render_render,Missing [0m2022-09-06 15:56:18.446061: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_none_even_none,Missing [0m2022-09-06 15:56:18.446158: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_clear,Missing [0m2022-09-06 15:56:18.446195: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_clear_clear,Missing [0m2022-09-06 15:56:18.446275: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_clear_render,Missing [0m2022-09-06 15:56:18.446345: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_none,Missing [0m2022-09-06 15:56:18.446414: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_render,Missing [0m2022-09-06 15:56:18.446482: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_render_clear,Missing [0m2022-09-06 15:56:18.446562: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_clear_even_render_render,Missing [0m2022-09-06 15:56:18.446633: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_even_clear,Missing [0m2022-09-06 15:56:18.446707: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_even_none,Missing [0m2022-09-06 15:56:18.446781: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_even_render,Missing [0m2022-09-06 15:56:18.446864: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_render_even_clear,Missing [0m2022-09-06 15:56:18.446935: dEQP-EGL.functional.buffer_age.no_preserve.no_resize.odd_render_render_even_clear_clear,Missing [0m2022-09-06 15:56:18.447005: ... and more (see failures.csv) [0m2022-09-06 15:56:18.447068: + DEQP_EXITCODE=1 [0m2022-09-06 15:56:18.447141: + set +x [0m2022-09-06 15:56:18.447207: [0Ksection_end:1662479771:deqp [0K [0m2022-09-06 15:56:18.447343: System load: 0.25 0.09 0.03 [0m2022-09-06 15:56:18.447438: # of CPU cores: 4 [0m2022-09-06 15:56:18.447505: [0Ksection_start:1662479771:test_post_process[collapsed=true] [0Kpost-processing test results [0m2022-09-06 15:56:18.447654: + find //results -name '*.xml' [0m2022-09-06 15:56:18.447727: + sort -n [0m2022-09-06 15:56:18.447795: + sed -n '1,+49!p' [0m2022-09-06 15:56:18.447973: + xargs rm -f [0m2022-09-06 15:56:18.448069: + find //results -name '*.xml' -exec cp /deqp/testlog.css /deqp/testlog.xsl //results/ ';' -quit [0m2022-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' [0m2022-09-06 15:56:18.448182: + '[' -n '' ']' [0m2022-09-06 15:56:18.448260: ++ date +%s [0m2022-09-06 15:56:18.448332: + echo -e '\e[0Ksection_end:1662479771:test_post_process\r\e[0K' [0m2022-09-06 15:56:18.448417: [0Ksection_end:1662479771:test_post_process [0K [0m2022-09-06 15:56:18.448613: + exit 1 [0m2022-09-06 15:56:18.448653: + EXIT_CODE=1 [0m2022-09-06 15:56:18.448723: + set -e [0m2022-09-06 15:56:18.448803: + mv -f /builds/enunes/mesa/results ./ [0m2022-09-06 15:56:18.448870: + true [0m2022-09-06 15:56:18.448944: + [ 1 -ne 0 ] [0m2022-09-06 15:56:18.449017: + cleanup [0m2022-09-06 15:56:18.449140: + [ 170 169 = ] [0m2022-09-06 15:56:18.449183: + set +x [0m2022-09-06 15:56:18.449301: Killing all child processes [0m2022-09-06 15:56:18.449384: + [ -n minio-packet.freedesktop.org/artifacts/enunes/mesa/682113/27956136 ] [0m2022-09-06 15:56:23.475080: + tar --zstd -cf results.tar.zst results/ [0m2022-09-06 15:56:23.475837: + ci-fairy minio login --token-file /minio_jwt [0m2022-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 [0m2022-09-06 15:56:39.003811: [1m[ 79.989506] <LAVA_SIGNAL_ENDTC mesa-ci_lima-mali450-deqp-egl:arm64>[0m [0m2022-09-06 15:56:39.004163: [1m[ 80.016604] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=mesa-ci_lima-mali450-deqp-egl:arm64 RESULT=fail>[0m [0m2022-09-06 15:56:39.004385: [1m[ 80.021840] <LAVA_SIGNAL_ENDRUN 0_mesa 25747_1.4.2.4.1>[0m [0m2022-09-06 15:56:39.004790: + [ 1 -eq 0 ] [0m2022-09-06 15:56:39.004971: + set +x [0m2022-09-06 15:56:39.005121: hwci: mesa: fail [0Ksection_end:1662479799:post-mesa-ci_lima-mali450-deqp-egl-arm64 [0K [0Ksection_start:1662479799:job_data[collapsed=true] [0K[1m[1;32;5;197mLAVA job info[0m 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 [0Ksection_end:1662479799:job_data [0K [0m2022-09-06 15:56:39.030233: [1mFinished executing LAVA job in the attempt #1[0m [0m2022-09-06 15:56:39.030384: [1;38;5;197mLAVA Job finished with status: fail[0m section_end:1662479799:step_script [0Ksection_start:1662479799:after_script [0K[0K[36;1mRunning after_script[0;m[0;m [32;1mRunning after script...[0;m [32;1m$ wget -q "https://${JOB_RESULTS_PATH}" -O- | tar --zstd -x[0;m section_end:1662479800:after_script [0Ksection_start:1662479800:upload_artifacts_on_failure [0K[0K[36;1mUploading artifacts for failed job[0;m[0;m [32;1mUploading artifacts...[0;m results/: found 19 matching files and directories [0;m Uploading artifacts as "archive" to coordinator... 201 Created[0;m id[0;m=27956136 responseStatus[0;m=201 Created token[0;m=Rxjc4s97 [32;1mUploading artifacts...[0;m results/junit.xml: found 1 matching files and directories[0;m Uploading artifacts as "junit" to coordinator... 201 Created[0;m id[0;m=27956136 responseStatus[0;m=201 Created token[0;m=Rxjc4s97 section_end:1662479805:upload_artifacts_on_failure [0Ksection_start:1662479805:cleanup_file_variables [0K[0K[36;1mCleaning up project directory and file based variables[0;m[0;m section_end:1662479805:cleanup_file_variables [0K[31;1mERROR: Job failed: exit code 1 [0;m