c9a9dbf2cbd1641af49bf081ca3bbe4101df3991 in ubuntu_btrfs_kernel_fixes call trace in Xenial / i386

[Sean Feole]

Series: Xenial
Arch: i386
Kernel: 4.4.0-180.210-lowlatency
SRU: 2020.05.18

Problem: ubuntu_btrfs_kernel_fix - c9a9dbf2cbd1641af49bf081ca3bbe4101df3991 produces a call trace

This was not originally found in the prior xenial release.

05/20 13:11:28 DEBUG| utils:0116| Running 'BINDIR=/home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes /home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/ubuntu_btrfs_kernel_fixes.sh /home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/fixes/c9a9dbf2cbd1641af49bf081ca3bbe4101df3991.sh 2>&1'
05/20 13:11:28 DEBUG| utils:0153| [stdout] Invoking test c9a9dbf2cbd1641af49bf081ca3bbe4101df3991
05/20 13:11:28 DEBUG| utils:0153| [stdout]
05/20 13:11:28 DEBUG| utils:0153| [stdout] fix c9a9dbf2cbd1641af49bf081ca3bbe4101df3991
05/20 13:11:28 DEBUG| utils:0153| [stdout]
05/20 13:11:28 DEBUG| utils:0153| [stdout] Btrfs: fix a warning when disabling quota
05/20 13:11:28 DEBUG| utils:0153| [stdout]
05/20 13:11:28 DEBUG| utils:0153| [stdout] Obviously, this warn_on() is unnecessary, and it will be easily triggered.
05/20 13:11:28 DEBUG| utils:0153| [stdout] Just remove it.
05/20 13:11:28 DEBUG| utils:0153| [stdout]
05/20 13:11:29 DEBUG| utils:0153| [stdout] Create subvolume '/tmp/mnt-c9a9dbf2cbd1641af49bf081ca3bbe4101df3991/subv'
05/20 13:11:40 DEBUG| utils:0153| [stdout] Found kernel WARN messages
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196077] ------------[ cut here ]------------
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196093] WARNING: CPU: 2 PID: 28190 at /build/linux-j7ad0X/linux-4.4.0/arch/x86/include/asm/fpu/internal.h:509 fpu__restore+0x10c/0x150()
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196097] Modules linked in: btrfs intel_powerclamp coretemp kvm_intel dcdbas gpio_ich kvm ipmi_devintf mac_hid ipmi_ssif i7core_edac ipmi_si edac_core irqbypass lpc_ich 8250_fintek shpchp ipmi_msghandler acpi_power_meter ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear mpt3sas pata_acpi raid_class bnx2 scsi_transport_sas fjes [last unloaded: btrfs]
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196136] CPU: 2 PID: 28190 Comm: c9a9dbf2cbd1641 Not tainted 4.4.0-180-lowlatency #210-Ubuntu
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196138] Hardware name: Dell Inc. PowerEdge R310/05XKKK, BIOS 1.8.2 08/17/2011
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196139] c1b25967 23f5b280 00000286 f3949e48 c13ca5b7 00000000 c1a0228c f3949e78
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196143] c10764c7 c1a14008 00000002 00006e1e c1a0228c 000001fd c103705c c103705c
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196147] ed2bb240 ed2babc0 bfb96800 f3949e88 c10765d2 00000009 00000000 f3949ea0
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196151] Call Trace:
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196156] [<c13ca5b7>] dump_stack+0x60/0x89
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196159] [<c10764c7>] warn_slowpath_common+0x87/0xc0
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196161] [<c103705c>] ? fpu__restore+0x10c/0x150
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196163] [<c103705c>] ? fpu__restore+0x10c/0x150
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196165] [<c10765d2>] warn_slowpath_null+0x22/0x30
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196167] [<c103705c>] fpu__restore+0x10c/0x150
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196169] [<c103828c>] __fpu__restore_sig+0x21c/0x470
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196171] [<c10386cf>] fpu__restore_sig+0x2f/0x50
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196174] [<c102b036>] restore_sigcontext+0x116/0x130
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196176] [<c102b889>] sys_sigreturn+0xa9/0xc0
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196179] [<c10038e1>] do_syscall_32_irqs_on+0x61/0xd0
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196183] [<c17f6eb5>] entry_INT80_32+0x31/0x31
05/20 13:11:40 DEBUG| utils:0153| [stdout] [ 1003.196185] ---[ end trace 0c3c7c437708bec2 ]---
05/20 13:11:51 DEBUG| utils:0153| [stdout]
05/20 13:11:51 DEBUG| utils:0153| [stdout] Found kernel issue:
05/20 13:11:51 DEBUG| utils:0153| [stdout]
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196077] ------------[ cut here ]------------
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196093] WARNING: CPU: 2 PID: 28190 at /build/linux-j7ad0X/linux-4.4.0/arch/x86/include/asm/fpu/internal.h:509 fpu__restore+0x10c/0x150()
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196097] Modules linked in: btrfs intel_powerclamp coretemp kvm_intel dcdbas gpio_ich kvm ipmi_devintf mac_hid ipmi_ssif i7core_edac ipmi_si edac_core irqbypass lpc_ich 8250_fintek shpchp ipmi_msghandler acpi_power_meter ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear mpt3sas pata_acpi raid_class bnx2 scsi_transport_sas fjes [last unloaded: btrfs]
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196136] CPU: 2 PID: 28190 Comm: c9a9dbf2cbd1641 Not tainted 4.4.0-180-lowlatency #210-Ubuntu
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196138] Hardware name: Dell Inc. PowerEdge R310/05XKKK, BIOS 1.8.2 08/17/2011
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196139] c1b25967 23f5b280 00000286 f3949e48 c13ca5b7 00000000 c1a0228c f3949e78
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196143] c10764c7 c1a14008 00000002 00006e1e c1a0228c 000001fd c103705c c103705c
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196147] ed2bb240 ed2babc0 bfb96800 f3949e88 c10765d2 00000009 00000000 f3949ea0
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196151] Call Trace:
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196156] [<c13ca5b7>] dump_stack+0x60/0x89
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196159] [<c10764c7>] warn_slowpath_common+0x87/0xc0
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196161] [<c103705c>] ? fpu__restore+0x10c/0x150
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196163] [<c103705c>] ? fpu__restore+0x10c/0x150
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196165] [<c10765d2>] warn_slowpath_null+0x22/0x30
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196167] [<c103705c>] fpu__restore+0x10c/0x150
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196169] [<c103828c>] __fpu__restore_sig+0x21c/0x470
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196171] [<c10386cf>] fpu__restore_sig+0x2f/0x50
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196174] [<c102b036>] restore_sigcontext+0x116/0x130
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196176] [<c102b889>] sys_sigreturn+0xa9/0xc0
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196179] [<c10038e1>] do_syscall_32_irqs_on+0x61/0xd0
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196183] [<c17f6eb5>] entry_INT80_32+0x31/0x31
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1003.196185] ---[ end trace 0c3c7c437708bec2 ]---
05/20 13:11:51 DEBUG| utils:0153| [stdout] [ 1013.092903] Test c9a9dbf2cbd1641af49bf081ca3bbe4101df3991 returned 0
05/20 13:11:51 DEBUG| utils:0153| [stdout]
05/20 13:11:51 DEBUG| utils:0153| [stdout] FAIL: c9a9dbf2cbd1641af49bf081ca3bbe4101df3991 (ret=1)
05/20 13:11:51 DEBUG| utils:0153| [stdout]
05/20 13:11:51 ERROR| test:0414| Exception escaping from test:
Traceback (most recent call last):
  File "/home/ubuntu/autotest/client/shared/test.py", line 411, in _exec
    _call_test_function(self.execute, *p_args, **p_dargs)
  File "/home/ubuntu/autotest/client/shared/test.py", line 823, in _call_test_function
    return func(*args, **dargs)
  File "/home/ubuntu/autotest/client/shared/test.py", line 291, in execute
    postprocess_profiled_run, args, dargs)
  File "/home/ubuntu/autotest/client/shared/test.py", line 212, in _call_run_once
    self.run_once(*args, **dargs)
  File "/home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/ubuntu_btrfs_kernel_fixes.py", line 77, in run_once
    self.results = utils.system_output(cmd, retain_output=True)
  File "/home/ubuntu/autotest/client/shared/utils.py", line 1267, in system_output
    verbose=verbose, args=args).stdout
  File "/home/ubuntu/autotest/client/shared/utils.py", line 918, in run
    "Command returned non-zero exit status")
CmdError: Command <BINDIR=/home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes /home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/ubuntu_btrfs_kernel_fixes.sh /home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/fixes/c9a9dbf2cbd1641af49bf081ca3bbe4101df3991.sh 2>&1> failed, rc=1, Command returned non-zero exit status
* Command:
    BINDIR=/home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes /home
    /ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/ubuntu_btrfs_kerne
    l_fixes.sh /home/ubuntu/autotest/client/tests/ubuntu_btrfs_kernel_fixes/fi
    xes/c9a9dbf2cbd1641af49bf081ca3bbe4101df3991.sh 2>&1
Exit status: 1
Duration: 22.3964750767

stdout:
Invoking test c9a9dbf2cbd1641af49bf081ca3bbe4101df3991

fix c9a9dbf2cbd1641af49bf081ca3bbe4101df3991

    Btrfs: fix a warning when disabling quota

    Obviously, this warn_on() is unnecessary, and it will be easily triggered.
    Just remove it.

Create subvolume '/tmp/mnt-c9a9dbf2cbd1641af49bf081ca3bbe4101df3991/subv'
Found kernel WARN messages
[ 1003.196077] ------------[ cut here ]------------
[ 1003.196093] WARNING: CPU: 2 PID: 28190 at /build/linux-j7ad0X/linux-4.4.0/arch/x86/include/asm/fpu/internal.h:509 fpu__restore+0x10c/0x150()
[ 1003.196097] Modules linked in: btrfs intel_powerclamp coretemp kvm_intel dcdbas gpio_ich kvm ipmi_devintf mac_hid ipmi_ssif i7core_edac ipmi_si edac_core irqbypass lpc_ich 8250_fintek shpchp ipmi_msghandler acpi_power_meter ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear mpt3sas pata_acpi raid_class bnx2 scsi_transport_sas fjes [last unloaded: btrfs]
[ 1003.196136] CPU: 2 PID: 28190 Comm: c9a9dbf2cbd1641 Not tainted 4.4.0-180-lowlatency #210-Ubuntu
[ 1003.196138] Hardware name: Dell Inc. PowerEdge R310/05XKKK, BIOS 1.8.2 08/17/2011
[ 1003.196139] c1b25967 23f5b280 00000286 f3949e48 c13ca5b7 00000000 c1a0228c f3949e78
[ 1003.196143] c10764c7 c1a14008 00000002 00006e1e c1a0228c 000001fd c103705c c103705c
[ 1003.196147] ed2bb240 ed2babc0 bfb96800 f3949e88 c10765d2 00000009 00000000 f3949ea0
[ 1003.196151] Call Trace:
[ 1003.196156] [<c13ca5b7>] dump_stack+0x60/0x89
[ 1003.196159] [<c10764c7>] warn_slowpath_common+0x87/0xc0
[ 1003.196161] [<c103705c>] ? fpu__restore+0x10c/0x150
[ 1003.196163] [<c103705c>] ? fpu__restore+0x10c/0x150
[ 1003.196165] [<c10765d2>] warn_slowpath_null+0x22/0x30
[ 1003.196167] [<c103705c>] fpu__restore+0x10c/0x150
[ 1003.196169] [<c103828c>] __fpu__restore_sig+0x21c/0x470
[ 1003.196171] [<c10386cf>] fpu__restore_sig+0x2f/0x50
[ 1003.196174] [<c102b036>] restore_sigcontext+0x116/0x130
[ 1003.196176] [<c102b889>] sys_sigreturn+0xa9/0xc0
[ 1003.196179] [<c10038e1>] do_syscall_32_irqs_on+0x61/0xd0
[ 1003.196183] [<c17f6eb5>] entry_INT80_32+0x31/0x31
[ 1003.196185] ---[ end trace 0c3c7c437708bec2 ]---

Found kernel issue:

[ 1003.196077] ------------[ cut here ]------------
[ 1003.196093] WARNING: CPU: 2 PID: 28190 at /build/linux-j7ad0X/linux-4.4.0/arch/x86/include/asm/fpu/internal.h:509 fpu__restore+0x10c/0x150()
[ 1003.196097] Modules linked in: btrfs intel_powerclamp coretemp kvm_intel dcdbas gpio_ich kvm ipmi_devintf mac_hid ipmi_ssif i7core_edac ipmi_si edac_core irqbypass lpc_ich 8250_fintek shpchp ipmi_msghandler acpi_power_meter ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear mpt3sas pata_acpi raid_class bnx2 scsi_transport_sas fjes [last unloaded: btrfs]
[ 1003.196136] CPU: 2 PID: 28190 Comm: c9a9dbf2cbd1641 Not tainted 4.4.0-180-lowlatency #210-Ubuntu
[ 1003.196138] Hardware name: Dell Inc. PowerEdge R310/05XKKK, BIOS 1.8.2 08/17/2011
[ 1003.196139] c1b25967 23f5b280 00000286 f3949e48 c13ca5b7 00000000 c1a0228c f3949e78
[ 1003.196143] c10764c7 c1a14008 00000002 00006e1e c1a0228c 000001fd c103705c c103705c
[ 1003.196147] ed2bb240 ed2babc0 bfb96800 f3949e88 c10765d2 00000009 00000000 f3949ea0
[ 1003.196151] Call Trace:
[ 1003.196156] [<c13ca5b7>] dump_stack+0x60/0x89
[ 1003.196159] [<c10764c7>] warn_slowpath_common+0x87/0xc0
[ 1003.196161] [<c103705c>] ? fpu__restore+0x10c/0x150
[ 1003.196163] [<c103705c>] ? fpu__restore+0x10c/0x150
[ 1003.196165] [<c10765d2>] warn_slowpath_null+0x22/0x30
[ 1003.196167] [<c103705c>] fpu__restore+0x10c/0x150
[ 1003.196169] [<c103828c>] __fpu__restore_sig+0x21c/0x470
[ 1003.196171] [<c10386cf>] fpu__restore_sig+0x2f/0x50
[ 1003.196174] [<c102b036>] restore_sigcontext+0x116/0x130
[ 1003.196176] [<c102b889>] sys_sigreturn+0xa9/0xc0
[ 1003.196179] [<c10038e1>] do_syscall_32_irqs_on+0x61/0xd0
[ 1003.196183] [<c17f6eb5>] entry_INT80_32+0x31/0x31
[ 1003.196185] ---[ end trace 0c3c7c437708bec2 ]---
[ 1013.092903] Test c9a9dbf2cbd1641af49bf081ca3bbe4101df3991 returned 0

FAIL: c9a9dbf2cbd1641af49bf081ca3bbe4101df3991 (ret=1)

[Po-Hsu Lin (cypressyew)]

This issue does not exist on X-i386 generic with the same node, thus I will retest both X-i386 generic / lowlatency.

[Po-Hsu Lin (cypressyew)]

Test passed on both generic / lowlatency Xenial i386 kernel. Maybe just a glitch?