2022-11-06 14:39:42

by kernel test robot

[permalink] [raw]
Subject: [linux-next:master] [mm] 901c96c363: BUG:Bad_rss-counter_state_mm:#type:MM_FILEPAGES_val


Greeting,

FYI, we noticed BUG:Bad_rss-counter_state_mm:#type:MM_FILEPAGES_val due to commit (built with gcc-11):

commit: 901c96c363e951f43ed8eea79363ce1ad383c28d ("mm: convert mm's rss stats into percpu_counter")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master

[test failed on linux-next/master 0cdb3579f1ee4c1e55acf8dfb0697b660067b1f8]

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):


+-----------------------------------------------------+------------+------------+
| | 67bea3cc72 | 901c96c363 |
+-----------------------------------------------------+------------+------------+
| BUG:Bad_rss-counter_state_mm:#type:MM_FILEPAGES_val | 0 | 69 |
| BUG:Bad_rss-counter_state_mm:#type:MM_ANONPAGES_val | 0 | 67 |
+-----------------------------------------------------+------------+------------+


If you fix the issue, kindly add following tag
| Reported-by: kernel test robot <[email protected]>
| Link: https://lore.kernel.org/oe-lkp/[email protected]


[ 18.896734][ T1] BUG: Bad rss-counter state mm:(____ptrval____) type:MM_FILEPAGES val:9
[ 18.898986][ T1] BUG: Bad rss-counter state mm:(____ptrval____) type:MM_ANONPAGES val:16
[ 18.904991][ T1] DEBUG_HOTPLUG_CPU0: CPU 0 is now offline
[ 18.906428][ T1] initcall debug_hotplug_cpu+0x0/0x15 returned 0 after 23439 usecs
[ 18.907924][ T1] calling trace_eval_sync+0x0/0x24 @ 1
[ 18.909184][ T1] initcall trace_eval_sync+0x0/0x24 returned 0 after 369 usecs
[ 18.910306][ T1] calling late_trace_init+0x0/0x9e @ 1
[ 18.911129][ T1] initcall late_trace_init+0x0/0x9e returned 0 after 1 usecs
[ 18.912228][ T1] calling acpi_gpio_handle_deferred_request_irqs+0x0/0xbc @ 1
[ 18.915731][ T1] initcall acpi_gpio_handle_deferred_request_irqs+0x0/0xbc returned 0 after 2364 usecs
[ 18.917131][ T1] calling fb_logo_late_init+0x0/0xe @ 1
[ 18.917691][ T1] initcall fb_logo_late_init+0x0/0xe returned 0 after 0 usecs
[ 18.918468][ T1] calling regulator_init_complete+0x0/0x86 @ 1
[ 18.919098][ T1] initcall regulator_init_complete+0x0/0x86 returned 0 after 8 usecs
[ 18.919900][ T1] calling of_platform_sync_state_init+0x0/0x11 @ 1
[ 18.920982][ T1] initcall of_platform_sync_state_init+0x0/0x11 returned 0 after 420 usecs
[ 18.928850][ T1] Freeing unused kernel image (initmem) memory: 5152K
[ 18.948277][ T1] Write protecting the kernel read-only data: 88064k
[ 18.957113][ T1] Freeing unused kernel image (text/rodata gap) memory: 2028K
[ 18.961277][ T1] Freeing unused kernel image (rodata/data gap) memory: 1704K
[ 19.073388][ T1] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[ 19.074100][ T1] x86/mm: Checking user space page tables
[ 19.177593][ T1] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[ 19.218321][ T1] Run /init as init process
[ 19.219043][ T1] with arguments:
[ 19.219607][ T1] /init
[ 19.220112][ T1] with environment:
[ 19.220732][ T1] HOME=/
[ 19.221259][ T1] TERM=linux
[ 19.221838][ T1] RESULT_ROOT=/result/boot/1/vm-snb/debian-11.1-x86_64-20220510.cgz/x86_64-randconfig-r003-20220516/gcc-11/901c96c363e951f43ed8eea79363ce1ad383c28d/21
[ 19.224285][ T1] BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r003-20220516/gcc-11/901c96c363e951f43ed8eea79363ce1ad383c28d/vmlinuz-6.1.0-rc3-00174-g901c96c363e9
[ 19.226598][ T1] branch=linux-next/master
[ 19.227368][ T1] job=/lkp/jobs/scheduled/vm-meta-310/boot-1-debian-11.1-x86_64-20220510.cgz-901c96c363e951f43ed8eea79363ce1ad383c28d-20221106-17348-drwvxa-11.yaml
[ 19.229799][ T1] user=lkp
[ 19.230351][ T1] ARCH=x86_64
[ 19.230777][ T1] kconfig=x86_64-randconfig-r003-20220516
[ 19.231381][ T1] commit=901c96c363e951f43ed8eea79363ce1ad383c28d
[ 19.232057][ T1] vmalloc=256M
[ 19.232435][ T1] max_uptime=600
[ 19.232829][ T1] LKP_SERVER=internal-lkp-server
[ 19.233356][ T1] selinux=0
[ 19.233710][ T1] softlockup_panic=1
[ 19.234136][ T1] prompt_ramdisk=0
[ 19.234547][ T1] vga=normal
[ 19.274954][ T1] systemd[1]: RTC configured in localtime, applying delta of 0 minutes to system time.

Welcome to Debian GNU/Linux 11 (bullseye)!

[ 22.478273][ C1] random: crng init done
[ OK ] Created slice system-getty.slice.
[ OK ] Created slice system-modprobe.slice.
[ OK ] Created slice User and Session Slice.
[ OK ] Reached target Local Encrypted Volumes.
[ OK ] Reached target Paths.
[ OK ] Reached target Slices.
[ OK ] Reached target Swap.
[ OK ] Listening on RPCbind Server Activation Socket.
[ OK ] Listening on Syslog Socket.
[ OK ] Listening on initctl Compatibility Named Pipe.
[ OK ] Listening on Journal Socket (/dev/log).
[ OK ] Listening on Journal Socket.
[ OK ] Listening on udev Control Socket.
[ OK ] Listening on udev Kernel Socket.
Mounting RPC Pipe File System...
Mounting Kernel Debug File System...
Mounting Kernel Trace File System...
Starting Load Kernel Module configfs...
Starting Load Kernel Module drm...
Starting Load Kernel Module fuse...
Starting Journal Service...
Starting Load Kernel Modules...
Starting Remount Root and Kernel File Systems...
Starting Coldplug All udev Devices...
[ OK ] Mounted RPC Pipe File System.
[ OK ] Mounted Kernel Debug File System.
[ OK ] Mounted Kernel Trace File System.
[ OK ] Finished Load Kernel Module configfs.
[ OK ] Finished Load Kernel Module drm.
[ OK ] Finished Load Kernel Module fuse.
[ OK ] Finished Load Kernel Modules.
[ OK ] Finished Remount Root and Kernel File Systems.
Mounting FUSE Control File System...
Mounting Kernel Configuration File System...
Starting Load/Save Random Seed...
Starting Apply Kernel Variables...
Starting Create System Users...
[ OK ] Mounted FUSE Control File System.
[ OK ] Mounted Kernel Configuration File System.
[ OK ] Finished Load/Save Random Seed.
[FAILED] Failed to start Apply Kernel Variables.
See 'systemctl status systemd-sysctl.service' for details.
[ OK ] Finished Create System Users.
Starting Create Static Device Nodes in /dev...
[ OK ] Finished Create Static Device Nodes in /dev.
[ OK ] Reached target Local File Systems (Pre).
[ OK ] Reached target Local File Systems.
Starting Preprocess NFS configuration...
[ OK ] Finished Preprocess NFS configuration.
[ OK ] Reached target NFS client services.
[ OK ] Started Rule-based Manager for Device Events and Files.
[ OK ] Started Journal Service.
Starting Flush Journal to Persistent Storage...


To reproduce:

# build kernel
cd linux
cp config-6.1.0-rc3-00174-g901c96c363e9 .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



Attachments:
(No filename) (7.51 kB)
config-6.1.0-rc3-00174-g901c96c363e9 (150.78 kB)
job-script (4.94 kB)
dmesg.xz (47.21 kB)
Download all attachments