lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite for Android: free password hash cracker in your pocket
[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Date:   Wed, 7 Jul 2021 17:22:30 +0800
From:   kernel test robot <rong.a.chen@...el.com>
To:     Guru Das Srinagesh <gurus@...eaurora.org>
Cc:     0day robot <lkp@...el.com>, LKML <linux-kernel@...r.kernel.org>,
        lkp@...ts.01.org, ying.huang@...el.com, feng.tang@...el.com,
        zhengjun.xing@...ux.intel.com,
        Peter Zijlstra <peterz@...radead.org>,
        Ingo Molnar <mingo@...hat.com>, Will Deacon <will@...nel.org>,
        Waiman Long <longman@...hat.com>,
        Boqun Feng <boqun.feng@...il.com>,
        Guru Das Srinagesh <gurus@...eaurora.org>
Subject: [spinlock_debug] 0263428b26:
 kernel-selftests.dma.dma_map_benchmark.average_unmap_latency 87.5%
 regression

Greeting,

FYI, we noticed a 87.5% regression of kernel-selftests.dma.dma_map_benchmark.average_unmap_latency due to commit:


commit: 0263428b26d26ba8f2edc28f65181c4ab3aefc8b ("[RFC PATCH] spinlock_debug: Save stacktrace at lock acquisition")
url: https://github.com/0day-ci/linux/commits/Guru-Das-Srinagesh/spinlock_debug-Save-stacktrace-at-lock-acquisition/20210630-092833
base: https://git.kernel.org/cgit/linux/kernel/git/tip/tip.git 0e8a89d49d45197770f2e57fb15f1bc9ded96eb0

in testcase: kernel-selftests
on test machine: 4 threads Intel(R) Core(TM) i5-6500 CPU @ 3.20GHz with 32G memory
with following parameters:

	group: group-01
	ucode: 0xe2

test-description: The kernel contains a set of "self tests" under the tools/testing/selftests/ directory. These are intended to be small unit tests to exercise individual code paths in the kernel.
test-url: https://www.kernel.org/doc/Documentation/kselftest.txt



If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen@...el.com>


Details are as below:
-------------------------------------------------------------------------------------------------->


To reproduce:

        git clone https://github.com/intel/lkp-tests.git
        cd lkp-tests
        bin/lkp install                job.yaml  # job file is attached in this email
        bin/lkp split-job --compatible job.yaml  # generate the yaml file for lkp run
        bin/lkp run                    generated-yaml-file

=========================================================================================
compiler/group/kconfig/rootfs/tbox_group/testcase/ucode:
  gcc-9/group-01/x86_64-rhel-8.3-kselftests/debian-10.4-x86_64-20200603.cgz/lkp-skl-d05/kernel-selftests/0xe2

commit: 
  0e8a89d49d ("locking/lockdep: Correct the description error for check_redundant()")
  0263428b26 ("spinlock_debug: Save stacktrace at lock acquisition")

0e8a89d49d451977 0263428b26d26ba8f2edc28f651 
---------------- --------------------------- 
         %stddev     %change         %stddev
             \          |                \  
      0.70           +85.7%       1.30        kernel-selftests.dma.dma_map_benchmark.average_map_latency
      1.02 ± 10%     +57.4%       1.60        kernel-selftests.dma.dma_map_benchmark.average_map_latency_stddev
      0.53 ±  8%     +87.5%       1.00        kernel-selftests.dma.dma_map_benchmark.average_unmap_latency
      0.93 ±  5%     +54.6%       1.44 ±  3%  kernel-selftests.dma.dma_map_benchmark.average_unmap_latency_stddev
    629.81           +51.8%     956.18        kernel-selftests.time.elapsed_time
    629.81           +51.8%     956.18        kernel-selftests.time.elapsed_time.max
     19380 ± 11%     +19.5%      23151 ± 10%  kernel-selftests.time.involuntary_context_switches
     10274           +16.0%      11916 ±  5%  kernel-selftests.time.maximum_resident_set_size
   2165658            -1.7%    2129319        kernel-selftests.time.minor_page_faults
     41.83           +34.2%      56.14        kernel-selftests.time.percent_of_cpu_this_job_got
    253.76          +108.0%     527.81        kernel-selftests.time.system_time
     64175          +104.3%     131100 ±  5%  kernel-selftests.time.voluntary_context_switches
      1510           -15.6%       1275 ±  2%  meminfo.Active(anon)
    420666 ±  2%     -20.7%     333726 ±  2%  meminfo.DirectMap4k
    136525           +50.3%     205189        meminfo.KReclaimable
    136525           +50.3%     205189        meminfo.SReclaimable
     42313           +14.2%      48332        meminfo.SUnreclaim
     13834           -12.5%      12106        meminfo.Shmem
    178839           +41.8%     253522        meminfo.Slab
    290239 ±  4%     -18.9%     235393 ±  6%  meminfo.VmallocUsed


                                                                                
                         kernel-selftests.time.system_time                      
                                                                                
  600 +---------------------------------------------------------------------+   
      |   O            O             O        O                             |   
  500 |-OO  O O OOOOOOO OO O OOO OOO   OO OOO                               |   
      |                                                                     |   
      |                                                                     |   
  400 |-+                                                                   |   
      |                                                                     |   
  300 |-+                                                                   |   
      |++   +  +++ ++ +++ + + ++++++++++++++++++++ ++++++ +   ++ ++ ++++ ++ |   
  200 |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |:::  :: :: ::::: :::::::                   :::    :::  : ::::: : ::::|   
  100 |-:: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
    0 +---------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
                kernel-selftests.time.percent_of_cpu_this_job_got               
                                                                                
  60 +----------------------------------------------------------------------+   
     | OOO O O OOOO OOO O O OOOOOO OO OOOOO OO                              |   
  50 |-+                                                                    |   
     |                                                                      |   
     |++   +  +++ + +++ ++  +++++++++++++++++++++++ ++++++   +++ ++ ++++ ++ |   
  40 |::   :  ::: : ::: ::  ::                   :: :   ::   : : :: :::: :: |   
     |::   :  ::: : ::: ::  ::                   :: :   ::   : : :: :::: :: |   
  30 |::   :  ::: : ::: ::  ::                   :: :   ::   : : :: :::: :: |   
     |::   :  ::: : ::: ::  ::                   :: :   ::   : : :: :::: :: |   
  20 |:::  :: :: ::::: :::: ::                   ::::    ::  :  ::::: : ::::|   
     | :: : :: : : ::  :: :::                    : :     ::  :  : ::  : : ::|   
     | :: : :: : : ::  :: :::                    : :     ::  :  : ::  : : ::|   
  10 |-:: : :: : : ::  :: :::                    : :     ::  :  : ::  : : ::|   
     | :: : :: : : ::  :: :::                    : :     ::  :  : ::  : : ::|   
   0 +----------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
                        kernel-selftests.time.elapsed_time                      
                                                                                
  1000 +--------------------------------------------------------------------+   
   900 |-OOOO  OO OOOOOO OOO  OOO O O OOOOOO OO                             |   
       |                                                                    |   
   800 |-+                                                                  |   
   700 |-+                                                                  |   
       |++  ++  +++ ++ + + + + +++++++++++++++++++ ++++++ +   ++ + +++ + ++ |   
   600 |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
   500 |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
   400 |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
       |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
   300 |-:: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
   200 |-:: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
       | :: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
   100 |-:: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
     0 +--------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
                      kernel-selftests.time.elapsed_time.max                    
                                                                                
  1000 +--------------------------------------------------------------------+   
   900 |-OOOO  OO OOOOOO OOO  OOO O O OOOOOO OO                             |   
       |                                                                    |   
   800 |-+                                                                  |   
   700 |-+                                                                  |   
       |++  ++  +++ ++ + + + + +++++++++++++++++++ ++++++ +   ++ + +++ + ++ |   
   600 |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
   500 |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
   400 |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
       |::  ::  ::: :: : : : : :                 : ::   : :   :: : ::: : :: |   
   300 |-:: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
   200 |-:: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
       | :: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
   100 |-:: : :::  :: : : ::: :                   : :    : : :  : ::  : : ::|   
     0 +--------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
                  kernel-selftests.time.voluntary_context_switches              
                                                                                
  160000 +------------------------------------------------------------------+   
         |                       O        O                                 |   
  140000 |-OOOO  OO OO O O O    O   O  OO   O  O                            |   
  120000 |-+            O    O O O O O   O O  O                             |   
         |                                                                  |   
  100000 |-+                                                                |   
         |                                                                  |   
   80000 |-+                                                                |   
         |++  ++ + +++ +++  + + +++++++++++++++++++ +++++++   ++ + +++ + ++ |   
   60000 |::  :: : ::: ::: +: : :                 : ::   ::   : +: ::: : :: |   
   40000 |::  :: : ::: ::: :: : :                 : ::   ::   : :: ::: : :: |   
         |::: : ::::::::: :::::::                  :::    ::  : ::::: ::::::|   
   20000 |-:: : ::: : ::  :: :::                   ::     ::  :  :: : : : ::|   
         | :: : ::: : ::  :: :::                   ::     ::  :  :: : : : ::|   
       0 +------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
            kernel-selftests.dma.dma_map_benchmark.average_map_latency          
                                                                                
  1.4 +---------------------------------------------------------------------+   
      | OOO O O OOOOOOOOOO O OOO OOO O OO OOO O                             |   
  1.2 |-+                                                                   |   
      |                                                                     |   
    1 |-+                                                                   |   
      |                                                                     |   
  0.8 |-+                                                                   |   
      |++   +  +++ ++ +++ + + ++++++++++++++++++++ ++++++ +   ++ ++ ++++ ++ |   
  0.6 |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
  0.4 |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
  0.2 |-:: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
    0 +---------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
         kernel-selftests.dma.dma_map_benchmark.average_map_latency_stddev      
                                                                                
  1.6 +---------------------------------------------------------------------+   
      |   O       OO O   O                                                  |   
  1.4 |-+                                                                   |   
  1.2 |-+   +    + +          ++   +++ ++    +++ +  +++           +  +      |   
      | :   +  + : :+ +++   + ::   :  +: :   : + :  :  +  +   +  +:  : +    |   
    1 |+:   :  :+: :: :::   : :+++ +   + ++++   +: +:   + :   ++ :: +:+: +  |   
      |::   :  ::: :: ::: + : :   +        +     : ::   : :   :: :: :::: :+ |   
  0.8 |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
  0.6 |::: : : :: : ::: ::: ::                    ::    : ::  :: :::: : ::: |   
  0.4 |-:: : :: : : ::  :::: :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
  0.2 |-:: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
    0 +---------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
           kernel-selftests.dma.dma_map_benchmark.average_unmap_latency         
                                                                                
    1 +---------------------------------------------------------------------+   
      |                                                                     |   
      |                                                                     |   
  0.8 |-+                                                                   |   
      |                                                                     |   
      |                                                                     |   
  0.6 |-+   +    + +          ++   + ++++    + + +  +++          ++  + +    |   
      |+:   :  ++: :+ +++ + + :++++++  + ++++ + +: +:  ++ +   ++ :: +:+: ++ |   
  0.4 |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |:::  :: :: ::: ::: : : :                   :::   : :   :: :::: : ::: |   
  0.2 |-:: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
    0 +---------------------------------------------------------------------+   
                                                                                
                                                                                                                                                                
        kernel-selftests.dma.dma_map_benchmark.average_unmap_latency_stddev     
                                                                                
  1.6 +---------------------------------------------------------------------+   
      |     O              O  O  OO    O  OO                                |   
  1.4 |-OOO   O OOOOOOOOO    O O   O O OO   O O                             |   
  1.2 |-+                O                                                  |   
      |        +   ++       + +      + +       +     +                      |   
    1 |-+   +  : + :: +++   : :+   ++ +:+    +++ +  + ++  +   +  ++  + +    |   
      |+:   :  :+: :: ::: + : :+++++   + ++++   +: +:   + :   ++ :: +:+: ++ |   
  0.8 |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
      |::   :  ::: :: ::: : : :                  : ::   : :   :: :: :::: :: |   
  0.6 |::   :  ::: :::::: : :::                  : ::   : :   :: :: :::: :: |   
  0.4 |::: : :: : : ::  :::: :                    ::     : : :  : ::: : ::::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
  0.2 |-:: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
      | :: : :: : : ::  :: : :                    ::     : : :  : ::  : : ::|   
    0 +---------------------------------------------------------------------+   
                                                                                
                                                                                
[*] bisect-good sample
[O] bisect-bad  sample



Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.


---
0DAY/LKP+ Test Infrastructure                   Open Source Technology Center
https://lists.01.org/hyperkitty/list/lkp@lists.01.org       Intel Corporation

Thanks,
Rong Chen


View attachment "config-5.13.0-rc1-00129-g0263428b26d2" of type "text/plain" (175421 bytes)

View attachment "job-script" of type "text/plain" (7267 bytes)

View attachment "job.yaml" of type "text/plain" (6324 bytes)

View attachment "reproduce" of type "text/plain" (838 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ