Re: Kernel Trace in recent 6.1.8n kernels

From: Randy Dunlap
Date: Thu Apr 04 2024 - 13:33:59 EST


[+ stable & scsi]

On 4/3/24 3:49 PM, Tim Tassonis wrote:
> Hi all
>
> Maybe this is the wrong list, as it probably only affects the 6.1.8n LTS kernel releases.
>
>
> I noticed that since 6.1.80 or so, all my boxes print a trace when rebooting or halting, right at the end. It starts with drivers/scsi/scsi_lib.c
>
> As everything seems already done by then, there is no "real" problem occuring, but maybe someone knows why this suddenly started to happen.
>
>
> With qemu and the serial options, I managed to get the actual trace in text:
>
>      Unmounting all other currently mounted file systems...[ 58.632670] EXT4-fs (sda1): re-mounted. Quota mode: none.
>   * [  OK  ]
> [   58.684029] EXT4-fs (sda1): re-mounted. Quota mode: none.
>   *   Bringing down the loopback interface... [  OK  ]
> [   58.809326] ------------[ cut here ]------------
> [   58.813524] WARNING: CPU: 0 PID: 2755 at drivers/scsi/scsi_lib.c:214 scsi_execute_cmd+0x3b/0x2b0
> [   58.828052] Modules linked in: cfg80211 8021q garp mrp stp ipv6 crc_ccitt joydev hid_generic usbhid snd_seq_midi snd_seq_midi_event psmouse ppdev serio_raw atkbd libps2 vivaldi_fmap uhci_hcd ehci_pci ehci_hcd snd_ens1370 bochs drm_vram_helper snd_rawmidi usbcore drm_ttm_helper sr_mod usb_common snd_pcm cdrom e1000 i2c_piix4 ttm pcspkr gameport pata_acpi parport_pc parport i8042 qemu_fw_cfg serio rtc_cmos floppy snd_seq snd_seq_device snd_timer snd soundcore fuse
> [   58.873677] CPU: 0 PID: 2755 Comm: halt Not tainted 6.1.84 #1
> [   58.876424] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
> [   58.884106] RIP: 0010:scsi_execute_cmd+0x3b/0x2b0
> [   58.885558] Code: 89 cc 55 44 89 c5 53 48 83 ec 10 4c 8b 74 24 50 48 89 0c 24 4d 85 f6 0f 84 44 02 00 00 49 83 3e 00 74 21 41 83 7e 08 60 74 1a <0f> 0b b8 ea ff ff ff 48 83 c4 10 5b 5d 41 5c 41 5d 41 5e 41 5f c3
> [   58.891998] RSP: 0018:ffffc90000153d98 EFLAGS: 00010287
> [   58.893500] RAX: ffffc90000153df8 RBX: ffff888003d22000 RCX: 0000000000000000
> [   58.895480] RDX: 0000000000000022 RSI: 0000000000000022 RDI: ffff888003d22000
> [   58.897583] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000002710
> [   58.900276] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000002710
> [   58.902209] R13: ffff888003d22000 R14: ffffc90000153df8 R15: ffffc90000153e28
> [   58.904084] FS:  00007f097a95b680(0000) GS:ffff88807dc00000(0000) knlGS:0000000000000000
> [   58.906285] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [   58.907451] CR2: 00007f097a8f5431 CR3: 000000000406e000 CR4: 00000000000006f0
> [   58.908928] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [   58.910326] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
> [   58.911770] Call Trace:
> [   58.912438]  <TASK>
> [   58.912977]  ? __warn+0x78/0xd0
> [   58.913751]  ? scsi_execute_cmd+0x3b/0x2b0
> [   58.914757]  ? report_bug+0xe6/0x170
> [   58.916267]  ? handle_bug+0x3c/0x70
> [   58.917020]  ? exc_invalid_op+0x13/0x60
> [   58.917807]  ? asm_exc_invalid_op+0x16/0x20
> [   58.918675]  ? scsi_execute_cmd+0x3b/0x2b0
> [   58.919524]  ata_cmd_ioctl+0x112/0x2b0
> [   58.920435]  blkdev_ioctl+0x12e/0x260
> [   58.921322]  __x64_sys_ioctl+0x8b/0xc0
> [   58.922115]  do_syscall_64+0x42/0x90
> [   58.922953]  entry_SYSCALL_64_after_hwframe+0x64/0xce
> [   58.924002] RIP: 0033:0x7f097a87616b
> [   58.924748] Code: 00 48 89 44 24 18 31 c0 48 8d 44 24 60 c7 04 24 10 00 00 00 48 89 44 24 08 48 8d 44 24 20 48 89 44 24 10 b8 10 00 00 00 0f 05 <89> c2 3d 00 f0 ff ff 77 1c 48 8b 44 24 18 64 48 2b 04 25 28 00 00
> [   58.928687] RSP: 002b:00007fff1e70b5b0 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
> [   58.930465] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f097a87616b
> [   58.932545] RDX: 00007fff1e70b614 RSI: 000000000000031f RDI: 0000000000000004
> [   58.933964] RBP: 0000000000000000 R08: 0000000000000073 R09: 0000558c7857a343
> [   58.935349] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000020
> [   58.936727] R13: 0000558c77faf088 R14: 0000000000000001 R15: 0000000000000000
> [   58.938165]  </TASK>
> [   58.938617] ---[ end trace 0000000000000000 ]---
> [   58.940450] sd 0:0:0:0: [sda] Synchronizing SCSI cache
> [   58.941662] sd 0:0:0:0: [sda] Stopping disk
> [   58.971754] ACPI: PM: Preparing to enter system sleep state S5
> [   58.973005] reboot: Power down
>
>
> Bye
> Tim
>

--
#Randy