DEBUG: Executing python function extend_recipe_sysroot
NOTE: Direct dependencies are ['TOPDIR/sources/poky/meta/recipes-devtools/qemu/qemu-helper-native_1.0.bb:do_populate_sysroot', 'TOPDIR/sources/poky/meta/recipes-devtools/qemu/qemu-native_8.0.0.bb:do_populate_sysroot', 'TOPDIR/sources/poky/meta/recipes-devtools/qemu/qemu-system-native_8.0.0.bb:do_populate_sysroot', 'virtual:native:TOPDIR/sources/poky/meta/recipes-devtools/opkg-utils/opkg-utils_0.5.0.bb:do_populate_sysroot', 'virtual:native:TOPDIR/sources/poky/meta/recipes-devtools/opkg/opkg_0.6.1.bb:do_populate_sysroot', 'virtual:native:TOPDIR/sources/poky/meta/recipes-devtools/pseudo/pseudo_git.bb:do_populate_sysroot', 'virtual:native:TOPDIR/sources/poky/meta/recipes-devtools/unfs3/unfs3_git.bb:do_populate_sysroot']
NOTE: Installed into sysroot: []
NOTE: Skipping as already exists in sysroot: ['qemu-helper-native', 'qemu-native', 'qemu-system-native', 'opkg-utils-native', 'opkg-native', 'pseudo-native', 'unfs3-native', 'glib-2.0-native', 'xz-native', 'zlib-native', 'libsdl2-native', 'zstd-native', 'libarchive-native', 'libtool-native', 'libsolv-native', 'perl-native', 'alsa-lib-native', 'libepoxy-native', 'libslirp-native', 'dtc-native', 'virglrenderer-native', 'pixman-native', 'gptfdisk-native', 'pigz-native', 'bmap-tools-native', 'btrfs-tools-native', 'mtools-native', 'parted-native', 'erofs-utils-native', 'opensbi', 'depmodwrapper-cross', 'cross-localedef-native', 'u-boot', 'qemuwrapper-cross', 'ldconfig-native', 'dosfstools-native', 'syslinux-native', 'makedevs-native', 'binutils-cross-riscv64', 'e2fsprogs-native', 'cdrtools-native', 'squashfs-tools-native', 'update-rc.d-native', 'flex-native', 'libtirpc-native', 'gettext-native', 'gettext-minimal-native', 'libpcre2-native', 'python3-native', 'libffi-native', 'util-linux-native', 'libxrandr-native', 'cmake-native', 'mesa-native', 'libxrender-native', 'libxext-native', 'libx11-native', 'attr-native', 'bzip2-native', 'texinfo-dummy-native', 'expat-native', 'make-native', 'gdbm-native', 'libdrm-native', 'popt-native', 'ncurses-native', 'kmod-native', 'python3-build-native', 'coreutils-native', 'python3-installer-native', 'python3-setuptools-native', 'python3-six-native', 'python3-wheel-native', 'systemd-systemctl-native', 'shadow-native', 'readline-native', 'util-linux-libuuid-native', 'lz4-native', 'glibc', 'libcxx', 'compiler-rt', 'gcc-runtime', 'nasm-native', 'gnu-config-native', 'debianutils-native', 'openssl-native', 'shared-mime-info-native', 'lzo-native', 'm4-native', 'libxcrypt-native', 'libedit-native', 'sqlite3-native', 'libnsl2-native', 'libcap-ng-native', 'util-macros-native', 'xorgproto-native', 'libxml2-native', 'wayland-native', 'makedepend-native', 'libxfixes-native', 'libxxf86vm-native', 'xrandr-native', 'clang-native', 'wayland-protocols-native', 'libxshmfence-native', 'python3-mako-native', 'chrpath-native', 'libxdamage-native', 'elfutils-native', 'xtrans-native', 'libxcb-native', 'libpciaccess-native', 'libpthread-stubs-native', 'python3-flit-core-native', 'python3-packaging-native', 'python3-pyproject-hooks-native', 'linux-libc-headers', 'libgcc', 'groff-native', 'itstool-native', 'swig-native', 'binutils-native', 'python3-pygments-native', 'python3-markupsafe-native', 'libmicrohttpd-native', 'curl-native', 'libxau-native', 'xcb-proto-native', 'libxdmcp-native', 'unzip-native', 'libgcrypt-native', 'gnutls-native', 'libgpg-error-native', 'libcap-native', 'libtasn1-native', 'gmp-native', 'libidn2-native', 'nettle-native', 'libunistring-native']
DEBUG: Python function extend_recipe_sysroot finished
DEBUG: Executing python function do_testimage
DEBUG: test suites:
test_ping (ping.PingTest.test_ping)
test_ssh (ssh.SSHTest.test_ssh)
test_parselogs (parselogs.ParseLogsTest.test_parselogs)
test_ptestrunner_expectfail (ptest.PtestRunnerTest.test_ptestrunner_expectfail)
test_ptestrunner_expectsuccess (ptest.PtestRunnerTest.test_ptestrunner_expectsuccess)
DEBUG: Not using kvm for runqemu
QMP Available for connection at TOPDIR/build/tmp/.wtob42xt
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:41561
DEBUG: Created listening socket for qemu serial console on: 127.0.0.1:33553
DEBUG: launchcmd=runqemu snapshot slirp egl-headless qemuriscv64 TOPDIR/build/tmp/deploy/images/qemuriscv64/core-image-ptest-slang-qemuriscv64.ext4 tcpserial=41561:33553 bootparams=" printk.time=1" qemuparams="-pidfile TOPDIR/pidfile_3631437 -S -qmp unix:./.p_nlxx89,server,wait -qmp unix:./.wtob42xt,server,nowait"
DEBUG: runqemu started, pid is 3631453
DEBUG: waiting at most 300 seconds for qemu pid (05/29/23 06:46:03)
DEBUG: QMP Initializing to TOPDIR/build/tmp/.p_nlxx89
DEBUG: QMP Connecting to TOPDIR/build/tmp/.p_nlxx89
QMP connected to QEMU at 05/29/23 06:46:03 and took 0.65 seconds
QMP released QEMU at 05/29/23 06:46:04 and took 1.08 seconds from connect
DEBUG: qemu started in 1.74 seconds - qemu procces pid is 3631455 (05/29/23 06:46:04)
DEBUG: qemu cmdline used:
TOPDIR/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-riscv64 -device virtio-net-device,netdev=net0,mac=52:54:00:12:35:06 -netdev user,id=net0,hostfwd=tcp:127.0.0.1:2224-:22,hostfwd=tcp:127.0.0.1:2325-:23,tftp=TOPDIR/build/tmp/deploy/images/qemuriscv64 -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive id=disk0,file=TOPDIR/build/tmp/deploy/images/qemuriscv64/core-image-ptest-slang-qemuriscv64.ext4,if=none,format=raw -device virtio-blk-device,drive=disk0 -device virtio-tablet-pci -device virtio-keyboard-pci -machine virt -smp 4 -m 1024 -device virtio-serial-device -chardev socket,id=virtcon,port=41561,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:33553 -pidfile TOPDIR/pidfile_3631437 -S -qmp unix:./.p_nlxx89,server,wait -qmp unix:./.wtob42xt,server,nowait -snapshot -serial mon:vc -display egl-headless,show-cursor=on -device bochs-display -bios TOPDIR/build/tmp/deploy/images/qemuriscv64/fw_jump.elf -kernel TOPDIR/build/tmp/deploy/images/qemuriscv64/Image -append root=/dev/vda rw mem=1024M ip=dhcp console=ttyS0 console=hvc0 earlycon=sbi printk.time=1
DEBUG: Target IP: 127.0.0.1:2224
DEBUG: Server IP: None
DEBUG: Starting logging thread
DEBUG: Starting thread event loop
DEBUG: Connection request received
DEBUG: Setting connection established event
DEBUG: Output from runqemu:
runqemu - INFO - Continuing with the following parameters:
KERNEL: [TOPDIR/build/tmp/deploy/images/qemuriscv64/Image]
BIOS: [TOPDIR/build/tmp/deploy/images/qemuriscv64/fw_jump.elf]
MACHINE: [qemuriscv64]
FSTYPE: [ext4]
ROOTFS: [TOPDIR/build/tmp/deploy/images/qemuriscv64/core-image-ptest-slang-qemuriscv64.ext4]
CONFFILE: [TOPDIR/build/tmp/deploy/images/qemuriscv64/core-image-ptest-slang-qemuriscv64.qemuboot.conf]
runqemu - INFO - Network configuration: ip=dhcp
runqemu - INFO - Acquiring lockfile /tmp/qemu-port-locks/2222.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-port-locks/2223.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Port forward changed: 2222 -> 2224
runqemu - INFO - Acquiring lockfile /tmp/qemu-port-locks/2323.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Acquiring lockfile /tmp/qemu-port-locks/2324.lock failed: [Errno 11] Resource temporarily unavailable
runqemu - INFO - Port forward changed: 2323 -> 2325
runqemu - INFO - Port forward: hostfwd=tcp:127.0.0.1:2224-:22 hostfwd=tcp:127.0.0.1:2325-:23
runqemu - INFO - Running TOPDIR/build/tmp/work/x86_64-linux/qemu-helper-native/1.0-r1/recipe-sysroot-native/usr/bin/qemu-system-riscv64 -device virtio-net-device,netdev=net0,mac=52:54:00:12:35:06 -netdev user,id=net0,hostfwd=tcp:127.0.0.1:2224-:22,hostfwd=tcp:127.0.0.1:2325-:23,tftp=TOPDIR/build/tmp/deploy/images/qemuriscv64 -object rng-random,filename=/dev/urandom,id=rng0 -device virtio-rng-pci,rng=rng0 -drive id=disk0,file=TOPDIR/build/tmp/deploy/images/qemuriscv64/core-image-ptest-slang-qemuriscv64.ext4,if=none,format=raw -device virtio-blk-device,drive=disk0 -device virtio-tablet-pci -device virtio-keyboard-pci -machine virt -smp 4 -m 1024 -device virtio-serial-device -chardev socket,id=virtcon,port=41561,host=127.0.0.1 -device virtconsole,chardev=virtcon -serial tcp:127.0.0.1:33553 -pidfile TOPDIR/pidfile_3631437 -S -qmp unix:./.p_nlxx89,server,wait -qmp unix:./.wtob42xt,server,nowait -snapshot -serial mon:vc -display egl-headless,show-cursor=on -device bochs-display -bios TOPDIR/build/tmp/deploy/images/qemuriscv64/fw_jump.elf -kernel TOPDIR/build/tmp/deploy/images/qemuriscv64/Image -append 'root=/dev/vda rw mem=1024M ip=dhcp console=ttyS0 console=hvc0 earlycon=sbi printk.time=1'
runqemu - INFO - Host uptime: 317248.76
DEBUG: Waiting at most 1000 seconds for login banner (05/29/23 06:46:04)
DEBUG: Connection from 127.0.0.1:39648
WARNING: Target didn't reach login banner in 1000 seconds (05/29/23 07:02:45)
WARNING: Last 25 lines of text (0):
WARNING: Check full boot log: TOPDIR/build/tmp/work/qemuriscv64-yoe-linux/core-image-ptest-slang/1.0-r0/testimage/qemu_boot_log.20230529133945
DEBUG: Stopping logging thread
DEBUG: Stop event received
DEBUG: Tearing down logging thread
DEBUG: Sending SIGTERM to runqemu
Output from runqemu:
runqemu - INFO - Received signal: 15
runqemu - INFO - Cleaning up
runqemu - INFO - Host uptime: 318256.27
tput: No value for $TERM and no -T specified
TOPDIR/sources/poky/meta/lib/oeqa/utils/qemurunner.py:183: ResourceWarning: unclosed <socket.socket fd=24, family=2, type=1, proto=0, laddr=('127.0.0.1', 33553), raddr=('127.0.0.1', 39648)>
return self.launch(launch_cmd, qemuparams=qemuparams, get_ip=get_ip, extra_bootparams=extra_bootparams, env=env)
ResourceWarning: Enable tracemalloc to get the object allocation traceback
DEBUG: Sending SIGTERM to runqemu
Output from runqemu:
runqemu - INFO - Received signal: 15
runqemu - INFO - Cleaning up
runqemu - INFO - Host uptime: 318256.27
tput: No value for $TERM and no -T specified
DEBUG: Sending SIGTERM to runqemu
Output from runqemu:
runqemu - INFO - Received signal: 15
runqemu - INFO - Cleaning up
runqemu - INFO - Host uptime: 318256.27
tput: No value for $TERM and no -T specified
ERROR: Error executing a python function in exec_func_python() autogenerated:
The stack trace of python calls that resulted in this exception/failure was:
File: 'exec_func_python() autogenerated', lineno: 2, function: <module>
0001:
*** 0002:do_testimage(d)
0003:
File: 'TOPDIR/sources/poky/meta/classes-recipe/testimage.bbclass', lineno: 125, function: do_testimage
0121: dump-guest-memory {"paging":false,"protocol":"file:%s.img"}
0122:}
0123:
0124:python do_testimage() {
*** 0125: testimage_main(d)
0126:}
0127:
0128:addtask testimage
0129:do_testimage[nostamp] = "1"
File: 'TOPDIR/sources/poky/meta/classes-recipe/testimage.bbclass', lineno: 360, function: testimage_main
0356: orig_sigterm_handler = signal.signal(signal.SIGTERM, sigterm_exception)
0357: try:
0358: # We need to check if runqemu ends unexpectedly
0359: # or if the worker send us a SIGTERM
*** 0360: tc.target.start(params=d.getVar("TEST_QEMUPARAMS"), runqemuparams=d.getVar("TEST_RUNQEMUPARAMS"))
0361: import threading
0362: try:
0363: threading.Timer(int(d.getVar("TEST_OVERALL_TIMEOUT")), handle_test_timeout, (int(d.getVar("TEST_OVERALL_TIMEOUT")),)).start()
0364: except ValueError:
File: 'TOPDIR/sources/poky/meta/lib/oeqa/core/target/qemu.py', lineno: 101, function: start
0097: except (subprocess.CalledProcessError, subprocess.TimeoutExpired, FileNotFoundError) as err:
0098: msg += "Error running command: %s\n%s\n" % (blcmd, err)
0099: msg += "\n\n===== end: snippet =====\n"
0100:
*** 0101: raise RuntimeError("FAILED to start qemu - check the task log and the boot log %s" % (msg))
0102:
0103: def stop(self):
0104: self.runner.stop()
Exception: RuntimeError: FAILED to start qemu - check the task log and the boot log
===== start: snippet =====
===== end: snippet =====
===== start: snippet =====
Error running command: ['tail', '-20', 'TOPDIR/build/tmp/work/qemuriscv64-yoe-linux/core-image-ptest-slang/1.0-r0/testimage/qemu_boot_log.20230529133945']
Command '['tail', '-20', 'TOPDIR/build/tmp/work/qemuriscv64-yoe-linux/core-image-ptest-slang/1.0-r0/testimage/qemu_boot_log.20230529133945']' returned non-zero exit status 1.
===== end: snippet =====
DEBUG: Python function do_testimage finished