[linux-next:master] [asm] 5e5ff73c2e: Initramfs_unpacking_failed

From: kernel test robot
Date: Wed Nov 23 2022 - 04:32:25 EST


Hi Sai,

We encountered an "Initramfs unpacking failed" issue when doing boot
test on this patch. The patch is for printing accurate debug info under
compiler optimization, looks totally unrelated with initramfs, but this
issue is 100% reproducible in our tests. We confirmed that our rootfs
image, kernel config and other environments were consistent during the
testing. Could you please help check if this is a real problem or a
false alarm? Thanks.


Greeting,

FYI, we noticed Initramfs_unpacking_failed due to commit (built with gcc-11):

commit: 5e5ff73c2e5863f93fc5fd78d178cd8f2af12464 ("asm-generic/io: Add _RET_IP_ to MMIO trace for more accurate debug info")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master

in testcase: boot

on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 16G

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):


[ 3.518862][ T1] initcall pci_apply_final_quirks+0x0/0x32c returned 0 after 10472 usecs
[ 3.520299][ T1] calling acpi_reserve_resources+0x0/0x273 @ 1
[ 3.521395][ T1] initcall acpi_reserve_resources+0x0/0x273 returned 0 after 61 usecs
[ 3.522853][ T1] calling populate_rootfs+0x0/0x3c @ 1
[ 3.524393][ T25] Trying to unpack rootfs image as initramfs...
[ 30.185500][ T25] Initramfs unpacking failed: uncompression error <--
[ 30.931793][ T25] Freeing initrd memory: 607016K
[ 30.932869][ T1] initcall populate_rootfs+0x0/0x3c returned 0 after 27409026 usecs
[ 30.934238][ T1] calling pci_iommu_init+0x0/0x55 @ 1
[ 30.935230][ T1] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 30.936475][ T1] software IO TLB: mapped [mem 0x0000000096f16000-0x000000009af16000] (64MB)


If you fix the issue, kindly add following tag
| Reported-by: kernel test robot <yujie.liu@xxxxxxxxx>
| Link: https://lore.kernel.org/oe-lkp/202211231652.f67208c7-yujie.liu@xxxxxxxxx


To reproduce:

# build kernel
cd linux
cp config-6.1.0-rc3-00002-g5e5ff73c2e58.old .config
make HOSTCC=gcc-11 CC=gcc-11 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-11 CC=gcc-11 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz


git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email

# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.


--
0-DAY CI Kernel Test Service
https://01.org/lkp

Attachment: config-6.1.0-rc3-00002-g5e5ff73c2e58.old
Description: application/trash

#!/bin/sh

export_top_env()
{
export suite='boot'
export testcase='boot'
export category='functional'
export timeout='10m'
export job_origin='boot.yaml'
export queue_cmdline_keys='branch
commit
kbuild_queue_analysis'
export queue='validate'
export testbox='vm-snb'
export tbox_group='vm-snb'
export branch='linux-next/master'
export commit='5e5ff73c2e5863f93fc5fd78d178cd8f2af12464'
export kconfig='x86_64-rhel-8.3-kunit'
export repeat_to=6
export nr_vm=300
export submit_id='637c8cbec6f8df4b7e01d925'
export job_file='/lkp/jobs/scheduled/vm-meta-286/boot-1-debian-11.1-x86_64-20220510.cgz-5e5ff73c2e5863f93fc5fd78d178cd8f2af12464-20221122-84862-w86gdf-2.yaml'
export id='5a5582bb2616217f8e20c20b9ea5756b8d0e9588'
export queuer_version='/zday/lkp'
export model='qemu-system-x86_64 -enable-kvm -cpu SandyBridge'
export nr_cpu=2
export memory='16G'
export need_kconfig=\{\"KVM_GUEST\"\=\>\"y\"\}
export ssh_base_port=23032
export kernel_cmdline='vmalloc=256M initramfs_async=0 page_owner=on'
export rootfs='debian-11.1-x86_64-20220510.cgz'
export compiler='gcc-11'
export enqueue_time='2022-11-22 16:47:58 +0800'
export _id='637c8cbec6f8df4b7e01d925'
export _rt='/result/boot/1/vm-snb/debian-11.1-x86_64-20220510.cgz/x86_64-rhel-8.3-kunit/gcc-11/5e5ff73c2e5863f93fc5fd78d178cd8f2af12464'
export user='lkp'
export LKP_SERVER='internal-lkp-server'
export result_root='/result/boot/1/vm-snb/debian-11.1-x86_64-20220510.cgz/x86_64-rhel-8.3-kunit/gcc-11/5e5ff73c2e5863f93fc5fd78d178cd8f2af12464/3'
export scheduler_version='/lkp/lkp/.src-20221121-213314'
export arch='x86_64'
export max_uptime=600
export initrd='/osimage/debian/debian-11.1-x86_64-20220510.cgz'
export bootloader_append='root=/dev/ram0
RESULT_ROOT=/result/boot/1/vm-snb/debian-11.1-x86_64-20220510.cgz/x86_64-rhel-8.3-kunit/gcc-11/5e5ff73c2e5863f93fc5fd78d178cd8f2af12464/3
BOOT_IMAGE=/pkg/linux/x86_64-rhel-8.3-kunit/gcc-11/5e5ff73c2e5863f93fc5fd78d178cd8f2af12464/vmlinuz-6.1.0-rc3-00002-g5e5ff73c2e58
branch=linux-next/master
job=/lkp/jobs/scheduled/vm-meta-286/boot-1-debian-11.1-x86_64-20220510.cgz-5e5ff73c2e5863f93fc5fd78d178cd8f2af12464-20221122-84862-w86gdf-2.yaml
user=lkp
ARCH=x86_64
kconfig=x86_64-rhel-8.3-kunit
commit=5e5ff73c2e5863f93fc5fd78d178cd8f2af12464
vmalloc=256M initramfs_async=0 page_owner=on
initcall_debug
max_uptime=600
LKP_SERVER=internal-lkp-server
selinux=0
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
console=tty0
earlyprintk=ttyS0,115200
console=ttyS0,115200
vga=normal
rw'
export modules_initrd='/pkg/linux/x86_64-rhel-8.3-kunit/gcc-11/5e5ff73c2e5863f93fc5fd78d178cd8f2af12464/modules.cgz'
export bm_initrd='/osimage/deps/debian-11.1-x86_64-20220510.cgz/run-ipconfig_20220515.cgz,/osimage/deps/debian-11.1-x86_64-20220510.cgz/lkp_20220513.cgz,/osimage/deps/debian-11.1-x86_64-20220510.cgz/rsync-rootfs_20220515.cgz'
export lkp_initrd='/osimage/user/lkp/lkp-x86_64.cgz'
export site='inn'
export LKP_CGI_PORT=80
export LKP_CIFS_PORT=139
export schedule_notify_address=
export stop_repeat_if_found='dmesg.Initramfs_unpacking_failed'
export kbuild_queue_analysis=1
export meta_host='vm-meta-286'
export kernel='/pkg/linux/x86_64-rhel-8.3-kunit/gcc-11/5e5ff73c2e5863f93fc5fd78d178cd8f2af12464/vmlinuz-6.1.0-rc3-00002-g5e5ff73c2e58'
export dequeue_time='2022-11-22 16:48:45 +0800'
export job_initrd='/lkp/jobs/scheduled/vm-meta-286/boot-1-debian-11.1-x86_64-20220510.cgz-5e5ff73c2e5863f93fc5fd78d178cd8f2af12464-20221122-84862-w86gdf-2.cgz'

[ -n "$LKP_SRC" ] ||
export LKP_SRC=/lkp/${user:-lkp}/src
}

run_job()
{
echo $$ > $TMP/run-job.pid

. $LKP_SRC/lib/http.sh
. $LKP_SRC/lib/job.sh
. $LKP_SRC/lib/env.sh

export_top_env

run_monitor $LKP_SRC/monitors/one-shot/wrapper boot-slabinfo
run_monitor $LKP_SRC/monitors/one-shot/wrapper boot-meminfo
run_monitor $LKP_SRC/monitors/one-shot/wrapper memmap
run_monitor $LKP_SRC/monitors/no-stdout/wrapper boot-time
run_monitor $LKP_SRC/monitors/wrapper kmsg
run_monitor $LKP_SRC/monitors/wrapper heartbeat
run_monitor $LKP_SRC/monitors/wrapper meminfo
run_monitor $LKP_SRC/monitors/wrapper oom-killer
run_monitor $LKP_SRC/monitors/plain/watchdog

run_test $LKP_SRC/tests/wrapper sleep 1
}

extract_stats()
{
export stats_part_begin=
export stats_part_end=

$LKP_SRC/stats/wrapper boot-slabinfo
$LKP_SRC/stats/wrapper boot-meminfo
$LKP_SRC/stats/wrapper memmap
$LKP_SRC/stats/wrapper boot-memory
$LKP_SRC/stats/wrapper boot-time
$LKP_SRC/stats/wrapper kernel-size
$LKP_SRC/stats/wrapper kmsg
$LKP_SRC/stats/wrapper sleep
$LKP_SRC/stats/wrapper meminfo

$LKP_SRC/stats/wrapper time sleep.time
$LKP_SRC/stats/wrapper dmesg
$LKP_SRC/stats/wrapper kmsg
$LKP_SRC/stats/wrapper last_state
$LKP_SRC/stats/wrapper stderr
$LKP_SRC/stats/wrapper time
}

"$@"

Attachment: dmesg.xz
Description: application/xz