[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Bug#1071590: linux-image-6.1.0-21-amd64: Kernel werning in unmap_page_range()



Package: src:linux
Version: 6.1.90-1
Severity: normal

Dear Maintainer,

   I recently upgraded a machine from bullseye to bookworm. While this was
   successful, I started noticing a kernel warning a few hours after every boot.
   The kernel warning happens only once per-boot. Seems to be an issue with a bind9
   process (isc-net-0000). This was the first 6.1 kernel on this machine so I don't
   know which versions have the problem or not.

   I have another, very similar machine, which also runs bind and the same kernel,
   but has never hit the warning.

-- Package-specific info:
** Version:
Linux version 6.1.0-21-amd64 (debian-kernel@lists.debian.org) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03)

** Command line:
root=UUID=744e1ad4-2d7a-4ee8-a078-a34a255a8a51 ro quiet

** Tainted: W (512)
 * kernel issued warning

** Kernel log:
[    7.498136] systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
[    7.499099] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
[    7.499502] systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
[    7.503590] systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
[    7.509953] loop: module loaded
[    7.513703] systemd[1]: modprobe@loop.service: Deactivated successfully.
[    7.514201] systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
[    7.514887] systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
[    7.524965] fuse: init (API version 7.37)
[    7.527159] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[    7.527524] systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
[    7.532762] systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
[    7.537138] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    7.537217] device-mapper: uevent: version 1.0.3
[    7.537332] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com
[    7.538973] systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
[    7.539317] systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
[    7.540631] systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
[    7.540962] systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
[    7.582060] systemd[1]: Started systemd-journald.service - Journal Service.
[    7.612989] EXT4-fs (xvda): re-mounted. Quota mode: none.
[    7.732271] systemd-journald[232]: Received client request to flush runtime journal.
[    7.746542] lp: driver loaded but no devices found
[    7.755019] ppdev: user-space parallel port driver
[    9.620575] input: PC Speaker as /devices/platform/pcspkr/input/input0
[   10.450818] Adding 8388604k swap on /dev/xvdb.  Priority:-2 extents:1 across:8388604k SSFS
[   12.883148] EXT4-fs (xvdc): barriers disabled
[   12.903173] EXT4-fs (xvdc): mounted filesystem with ordered data mode. Quota mode: none.
[   14.672597] audit: type=1400 audit(1715718337.874:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="tcpdump" pid=326 comm="apparmor_parser"
[   14.709567] audit: type=1400 audit(1715718337.910:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=325 comm="apparmor_parser"
[   14.709581] audit: type=1400 audit(1715718337.910:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=325 comm="apparmor_parser"
[   14.709587] audit: type=1400 audit(1715718337.910:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=325 comm="apparmor_parser"
[   14.766342] audit: type=1400 audit(1715718337.966:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/freshclam" pid=324 comm="apparmor_parser"
[   14.783948] audit: type=1400 audit(1715718337.982:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=323 comm="apparmor_parser"
[   14.783960] audit: type=1400 audit(1715718337.982:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=323 comm="apparmor_parser"
[   14.787738] audit: type=1400 audit(1715718337.986:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lsb_release" pid=322 comm="apparmor_parser"
[   14.805238] audit: type=1400 audit(1715718338.006:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="named" pid=331 comm="apparmor_parser"
[   14.810506] audit: type=1400 audit(1715718338.010:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/clamd" pid=327 comm="apparmor_parser"
[   27.204462] kauditd_printk_skb: 4 callbacks suppressed
[   27.204467] audit: type=1400 audit(1715718350.406:16): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=540 comm="cupsd" capability=12  capname="net_admin"
[  147.081478] audit: type=1400 audit(1715718470.276:17): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=3193 comm="cupsd" capability=12  capname="net_admin"
[21483.605290] ------------[ cut here ]------------
[21483.605297] WARNING: CPU: 4 PID: 33050 at include/linux/mm_inline.h:564 unmap_page_range+0xd7f/0x1150
[21483.605315] Modules linked in: ipt_REJECT nf_reject_ipv4 xt_multiport nft_compat nf_tables libcrc32c nfnetlink binfmt_misc sha512_ssse3 sha512_generic sha256_ssse3 sha1_ssse3 evdev pcspkr parport_pc ppdev lp drm parport dm_mod fuse loop efi_pstore configfs ip_tables x_tables autofs4 ext4 crc16 mbcache jbd2 crc32c_generic xen_netfront xen_blkfront crc32c_intel
[21483.605374] CPU: 4 PID: 33050 Comm: isc-net-0000 Not tainted 6.1.0-21-amd64 #1  Debian 6.1.90-1
[21483.605381] RIP: e030:unmap_page_range+0xd7f/0x1150
[21483.605389] Code: 24 08 e9 0d fd ff ff 48 89 f7 e8 0c e4 ff ff e9 17 fd ff ff 48 b8 ff ff ff ff 03 00 00 00 e9 b0 fe ff ff 0f 0b e9 c8 f9 ff ff <0f> 0b e9 e1 fb ff ff 41 bf 01 00 00 00 e9 09 ff ff ff 31 f6 48 c7
[21483.605395] RSP: e02b:ffffc90047a5b9e0 EFLAGS: 00010206
[21483.605400] RAX: ffff88808eb60200 RBX: ffff88800eb65038 RCX: 0000000000000000
[21483.605404] RDX: 0000000000000000 RSI: ffff8880fad1e2d0 RDI: 0000000000000001
[21483.605407] RBP: 07ffffffd3f5700a R08: ffff8880fad00000 R09: 0000000000000011
[21483.605411] R10: 00007ff5ad407fff R11: 0000000000000000 R12: 00007ff5ad407000
[21483.605414] R13: ffffc90047a5bb08 R14: 0000000000000000 R15: 0000000000000000
[21483.605438] FS:  00007ff5b97ca6c0(0000) GS:ffff8880fad00000(0000) knlGS:0000000000000000
[21483.605443] CS:  e030 DS: 0000 ES: 0000 CR0: 0000000080050033
[21483.605447] CR2: 00007fc4ea5ef9d8 CR3: 00000000bb9a4000 CR4: 0000000000000660
[21483.605459] Call Trace:
[21483.605464]  <TASK>
[21483.605470]  ? __warn+0x7d/0xc0
[21483.605477]  ? unmap_page_range+0xd7f/0x1150
[21483.605484]  ? report_bug+0xe2/0x150
[21483.605495]  ? handle_bug+0x41/0x70
[21483.605502]  ? exc_invalid_op+0x13/0x60
[21483.605507]  ? asm_exc_invalid_op+0x16/0x20
[21483.605516]  ? unmap_page_range+0xd7f/0x1150
[21483.605526]  zap_page_range_single+0x105/0x1a0
[21483.605536]  do_madvise+0xa3a/0xef0
[21483.605547]  ? eventfd_read+0x220/0x2f0
[21483.605556]  __x64_sys_madvise+0x28/0x30
[21483.605563]  do_syscall_64+0x55/0xb0
[21483.605569]  ? vfs_read+0x23c/0x310
[21483.605579]  ? ksys_read+0xd4/0xf0
[21483.605585]  ? exit_to_user_mode_prepare+0x44/0x1f0
[21483.605593]  ? syscall_exit_to_user_mode+0x1e/0x40
[21483.605601]  ? do_syscall_64+0x61/0xb0
[21483.605605]  ? _raw_write_lock_irq+0xa/0x30
[21483.605614]  ? ep_done_scan+0xc8/0x110
[21483.605620]  ? _raw_write_unlock_irq+0xd/0x30
[21483.605627]  ? do_epoll_wait+0xb2/0x7d0
[21483.605633]  ? __rseq_handle_notify_resume+0xa9/0x4a0
[21483.605641]  ? switch_fpu_return+0x4c/0xd0
[21483.605649]  ? exit_to_user_mode_prepare+0x156/0x1f0
[21483.605655]  ? syscall_exit_to_user_mode+0x1e/0x40
[21483.605661]  ? do_syscall_64+0x61/0xb0
[21483.605666]  ? do_syscall_64+0x61/0xb0
[21483.605670]  ? exit_to_user_mode_prepare+0x44/0x1f0
[21483.605676]  entry_SYSCALL_64_after_hwframe+0x6e/0xd8
[21483.605683] RIP: 0033:0x7ff5bcb749f7
[21483.605689] Code: ff ff ff ff c3 66 0f 1f 44 00 00 48 8b 15 01 04 0d 00 f7 d8 64 89 02 b8 ff ff ff ff eb bc 0f 1f 44 00 00 b8 1c 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d d9 03 0d 00 f7 d8 64 89 01 48
[21483.605693] RSP: 002b:00007ff5b97c5548 EFLAGS: 00000206 ORIG_RAX: 000000000000001c
[21483.605699] RAX: ffffffffffffffda RBX: 00007ff5ad616680 RCX: 00007ff5bcb749f7
[21483.605703] RDX: 0000000000000004 RSI: 0000000000002000 RDI: 00007ff5ad406000
[21483.605706] RBP: 00007ff5ad6000c0 R08: 0000000000002000 R09: 0000000000008000
[21483.605710] R10: 00007ff5bc6ccca0 R11: 0000000000000206 R12: 00007ff5b97c9a28
[21483.605713] R13: 00007ff5ad6039f0 R14: 00007ff5bc6ccca0 R15: 00007ff5bc8d74a6
[21483.605719]  </TASK>
[21483.605721] ---
[ end trace 0000000000000000 ]---
[199505.733059] systemd-journald[232]: Data hash table of /var/log/journal/3659205804c98e1cb6382cbc00000c2b/system.journal has a fill level at 75.0 (174763 of 233016 items, 58720256 file size, 335 bytes per hash table item), suggesting rotation.
[199505.733083] systemd-journald[232]: /var/log/journal/3659205804c98e1cb6382cbc00000c2b/system.journal: Journal header limits reached or header out-of-date, rotating.
[560252.976911] audit: type=1400 audit(1716278566.978:18): apparmor="DENIED" operation="open" profile="/usr/sbin/clamd" name="/proc/448/cgroup" pid=448 comm="clamd" requested_mask="r" denied_mask="r" fsuid=109 ouid=0
** Model information

** Loaded modules:
ipt_REJECT
nf_reject_ipv4
xt_multiport
nft_compat
nf_tables
libcrc32c
nfnetlink
binfmt_misc
sha512_ssse3
sha512_generic
sha256_ssse3
sha1_ssse3
evdev
pcspkr
parport_pc
ppdev
lp
drm
parport
dm_mod
fuse
loop
efi_pstore
configfs
ip_tables
x_tables
autofs4
ext4
crc16
mbcache
jbd2
crc32c_generic
xen_netfront
xen_blkfront
crc32c_intel

** Network interface configuration:
*** /etc/network/interfaces:

auto lo
iface lo inet loopback

auto enX0
iface enX0 inet static
    address 172.16.1.2
    netmask 255.255.255.0
    gateway 172.16.1.1
    mtu 1460

iface enX0 inet6 static
    address 2002:cf36:7441:1::2
    netmask 64
    mtu 1460

auto enX0:1
iface enX0:1 inet static
    address 207.54.116.67
    netmask 255.255.255.255

auto enX0:2
iface enX0:2 inet static
    address 204.191.154.188
    netmask 255.255.255.255



** Network status:
*** IP interfaces and addresses:
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
       valid_lft forever preferred_lft forever
    inet6 ::1/128 scope host noprefixroute
       valid_lft forever preferred_lft forever
2: enX0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1460 qdisc mq state UP group default qlen 1000
    link/ether 00:16:3e:25:2d:fb brd ff:ff:ff:ff:ff:ff
    inet 172.16.1.2/24 brd 172.16.1.255 scope global enX0
       valid_lft forever preferred_lft forever
    inet 207.54.116.67/32 brd 207.54.116.67 scope global enX0:1
       valid_lft forever preferred_lft forever
    inet 204.191.154.188/32 brd 204.191.154.188 scope global enX0:2
       valid_lft forever preferred_lft forever
    inet6 2002:cf36:7441:1::2/64 scope global
       valid_lft forever preferred_lft forever
    inet6 fe80::216:3eff:fe25:2dfb/64 scope link
       valid_lft forever preferred_lft forever

*** Device statistics:
Inter-|   Receive                                                |  Transmit
 face |bytes    packets errs drop fifo frame compressed multicast|bytes    packets errs drop fifo colls carrier compressed
    lo: 778735926 1584300    0    0    0     0          0         0 778735926 1584300    0    0    0     0       0          0
  enX0: 1463356750 7341543    0 50096    0     0          0         0 38654108456 4826156    0    0    0     0       0          0

*** Protocol statistics:
Ip:
    Forwarding: 2
    7448150 total packets received
    12 with invalid addresses
    0 forwarded
    0 incoming packets discarded
    6534816 incoming packets delivered
    5653993 requests sent out
Icmp:
    18311 ICMP messages received
    58 input ICMP message failed
    InCsumErrors: 2
    ICMP input histogram:
        destination unreachable: 14552
        timeout in transit: 19
        echo requests: 3732
        echo replies: 6
    113106 ICMP messages sent
    0 ICMP messages failed
    ICMP output histogram:
        destination unreachable: 109319
        echo requests: 55
        echo replies: 3732
IcmpMsg:
        InType0: 6
        InType3: 14552
        InType8: 3732
        InType11: 19
        OutType0: 3732
        OutType3: 109319
        OutType8: 55
Tcp:
    33549 active connection openings
    91444 passive connection openings
    1940 failed connection attempts
    40423 connection resets received
    30 connections established
    4942243 segments received
    30356828 segments sent out
    50137 segments retransmitted
    563 bad segments received
    41291 resets sent
    InCsumErrors: 560
Udp:
    2072137 packets received
    32708 packets to unknown port received
    2 packet receive errors
    1739932 packets sent
    0 receive buffer errors
    0 send buffer errors
    InCsumErrors: 2
    IgnoredMulti: 48176
UdpLite:
TcpExt:
    1909 resets received for embryonic SYN_RECV sockets
    1026 packets pruned from receive queue because of socket buffer overrun
    9 ICMP packets dropped because they were out-of-window
    18625 TCP sockets finished time wait in fast timer
    1 time wait sockets recycled by time stamp
    900 packets rejected in established connections because of timestamp
    68888 delayed acks sent
    81 delayed acks further delayed because of locked socket
    Quick ack mode was activated 21447 times
    1 SYNs to LISTEN sockets dropped
    324457 packet headers predicted
    820735 acknowledgments not containing data payload received
    2153605 predicted acknowledgments
    TCPSackRecovery: 716
    TCPSACKReneging: 1
    Detected reordering 1716 times using SACK
    Detected reordering 50 times using reno fast retransmit
    Detected reordering 2 times using time stamp
    207 congestion windows fully recovered without slow start
    2 congestion windows partially recovered using Hoe heuristic
    TCPDSACKUndo: 183
    594 congestion windows recovered without slow start after partial ack
    TCPLostRetransmit: 28520
    2 timeouts after reno fast retransmit
    TCPSackFailures: 55
    91 timeouts in loss state
    1600 fast retransmits
    114 retransmits in slow start
    TCPTimeouts: 41874
    TCPLossProbes: 9890
    TCPLossProbeRecovery: 871
    TCPSackRecoveryFail: 70
    TCPBacklogCoalesce: 22046
    TCPDSACKOldSent: 21427
    TCPDSACKOfoSent: 4
    TCPDSACKRecv: 4708
    TCPDSACKOfoRecv: 13
    25732 connections reset due to unexpected data
    192 connections reset due to early user close
    2813 connections aborted due to timeout
    TCPSACKDiscard: 7
    TCPDSACKIgnoredOld: 3
    TCPDSACKIgnoredNoUndo: 1661
    TCPSpuriousRTOs: 2
    TCPSackShifted: 1070
    TCPSackMerged: 731
    TCPSackShiftFallback: 4008
    TCPDeferAcceptDrop: 12605
    TCPRcvCoalesce: 38013
    TCPOFOQueue: 4095
    TCPOFOMerge: 4
    TCPChallengeACK: 546
    TCPSYNChallenge: 3
    TCPFastOpenActiveFail: 3
    TCPFastOpenCookieReqd: 237
    TCPSpuriousRtxHostQueues: 1
    TCPAutoCorking: 657909
    TCPFromZeroWindowAdv: 1517
    TCPToZeroWindowAdv: 1517
    TCPWantZeroWindowAdv: 2085
    TCPSynRetrans: 5977
    TCPOrigDataSent: 29163679
    TCPHystartTrainDetect: 542
    TCPHystartTrainCwnd: 37254
    TCPHystartDelayDetect: 90
    TCPHystartDelayCwnd: 5971
    TCPACKSkippedSynRecv: 52
    TCPACKSkippedPAWS: 401
    TCPACKSkippedSeq: 962
    TCPACKSkippedTimeWait: 1
    TCPACKSkippedChallenge: 20
    TCPWinProbe: 39
    TCPKeepAlive: 27
    TCPDelivered: 29125105
    TCPAckCompressed: 1739
    TCPZeroWindowDrop: 6
    TcpTimeoutRehash: 33199
    TcpDuplicateDataRehash: 5129
    TCPDSACKRecvSegs: 4713
    TCPDSACKIgnoredDubious: 10
IpExt:
    InMcastPkts: 271536
    OutMcastPkts: 9604
    InBcastPkts: 109260
    OutBcastPkts: 1777
    InOctets: 1967687678
    OutOctets: 39243779230
    InMcastOctets: 53424556
    OutMcastOctets: 5498168
    InBcastOctets: 11117099
    OutBcastOctets: 413183
    InNoECTPkts: 7442497
    InECT1Pkts: 899
    InECT0Pkts: 10360
MPTcpExt:


** PCI devices:
not available

** USB devices:
not available


-- System Information:
Debian Release: 12.5
  APT prefers stable-updates
  APT policy: (500, 'stable-updates'), (500, 'stable-security'), (500, 'stable')
Architecture: amd64 (x86_64)

Kernel: Linux 6.1.0-21-amd64 (SMP w/6 CPU threads; PREEMPT)
Kernel taint flags: TAINT_WARN
Locale: LANG=en_CA.UTF-8, LC_CTYPE=en_CA.UTF-8 (charmap=UTF-8), LANGUAGE not set
Shell: /bin/sh linked to /usr/bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled

Versions of packages linux-image-6.1.0-21-amd64 depends on:
ii  initramfs-tools [linux-initramfs-tool]  0.142
ii  kmod                                    30+20221128-1
ii  linux-base                              4.9

Versions of packages linux-image-6.1.0-21-amd64 recommends:
ii  apparmor             3.0.8-3
ii  firmware-linux-free  20200122-1

Versions of packages linux-image-6.1.0-21-amd64 suggests:
pn  debian-kernel-handbook               <none>
pn  grub-pc | grub-efi-amd64 | extlinux  <none>
pn  linux-doc-6.1                        <none>

Versions of packages linux-image-6.1.0-21-amd64 is related to:
pn  firmware-amd-graphics     <none>
pn  firmware-atheros          <none>
pn  firmware-bnx2             <none>
pn  firmware-bnx2x            <none>
pn  firmware-brcm80211        <none>
pn  firmware-cavium           <none>
pn  firmware-intel-sound      <none>
pn  firmware-intelwimax       <none>
pn  firmware-ipw2x00          <none>
pn  firmware-ivtv             <none>
pn  firmware-iwlwifi          <none>
pn  firmware-libertas         <none>
pn  firmware-linux-nonfree    <none>
pn  firmware-misc-nonfree     <none>
pn  firmware-myricom          <none>
pn  firmware-netxen           <none>
pn  firmware-qlogic           <none>
pn  firmware-realtek          <none>
pn  firmware-samsung          <none>
pn  firmware-siano            <none>
pn  firmware-ti-connectivity  <none>
pn  xen-hypervisor            <none>

-- no debconf information


Reply to: