[MDEV-15016] multiple page cleaner threads use a lot of CPU on idle server - Jira

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Blocker
Resolution: Fixed
Affects Version/s: 10.3.4
Fix Version/s: 10.2.13, 10.3.5
Component/s: Storage Engine - InnoDB
Labels:
None

Sprint:
10.1.31

Description

I cannot tell if previous 10.3 versions also did, as I don't have 10.3
running constantly in the backgorund. I just observed this now. This does
not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL
8.0 (all those I have starting with Windows on this box), where the
CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz
(this CPU model is a 4th generation Intel with the option to scale down CPU
frequency to around 40-50% of max. when there is no need for more) when
MariaDB 10.3.4 is running.

Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex
.
my.ini uses all defaults, with the exception of
innodb_buffer_pool_size=4091M

Setting innodb_page_cleaners = 1, according to reporter, fixed the issue.

Relevant callstack information :

. 15  Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen

      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)

      Priority: 0  Priority class: 32  Affinity: ff

Child-SP          RetAddr           Call Site

0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29

0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]

0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14

0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 16  Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen

      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)

      Priority: 0  Priority class: 32  Affinity: ff

Child-SP          RetAddr           Call Site

0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622]

0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]

0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]

0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14

0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 17  Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen

      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)

      Priority: 0  Priority class: 32  Affinity: ff

Child-SP          RetAddr           Call Site

0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635]

0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]

0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]

0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14

0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 18  Id: c10.1c30 Suspend: 0 Teb: 00000012`c5100000 Unfrozen

      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)

      Priority: 0  Priority class: 32  Affinity: ff

Child-SP          RetAddr           Call Site

00000012`c63ff970 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xe8 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]

00000012`c63ff9b0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14

00000012`c63ff9e0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 19  Id: c10.a04 Suspend: 0 Teb: 00000012`c5102000 Unfrozen

      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)

      Priority: 0  Priority class: 32  Affinity: ff

Child-SP          RetAddr           Call Site

00000012`c64ff6d0 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x13

(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]

00000012`c64ff700 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]

00000012`c64ff730 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]

00000012`c64ff770 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14

00000012`c64ff7a0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 20  Id: c10.161c Suspend: 0 Teb: 00000012`c5104000 Unfrozen

      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)

      Priority: 0  Priority class: 32  Affinity: ff

Child-SP          RetAddr           Call Site

00000012`c65ff9e8 00007ffa`6feec17f ntdll!NtWaitForAlertByThreadId+0x14

00000012`c65ff9f0 00007ffa`6feebf4b ntdll!RtlpWaitOnAddressWithTimeout+0x43

00000012`c65ffa20 00007ffa`6fec0c49 ntdll!RtlpWaitOnCriticalSection+0x15f

00000012`c65ffad0 00007ffa`6fec0b60 ntdll!RtlpEnterCriticalSectionContended+0xd9

00000012`c65ffb00 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x40

(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]

00000012`c65ffb30 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]

00000012`c65ffb60 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]

00000012`c65ffba0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14

00000012`c65ffbd0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

Attachments

- Sort By Name
- Sort By Date
- Ascending
- Descending
- Thumbnails
- List
- Download All

mysqld.DMP.bz2.001
10.00 MB
2018-01-20 23:44
mysqld.DMP.bz2.002
10.00 MB
2018-01-20 23:44
mysqld.DMP.bz2.003
1.68 MB
2018-01-20 23:44
mysqld0.DMP.xz.001
10.00 MB
2018-01-20 23:47
mysqld0.DMP.xz.002
6.22 MB
2018-01-20 23:47
stacks1.txt
30 kB
2018-01-20 23:12
stacks2.txt
30 kB
2018-01-20 22:58

Issue Links

is caused by

MDEV-11025 Make number of page cleaner threads variable dynamic

Closed

relates to

MDEV-15053 Reduce buf_pool_t::mutex contention

Closed

MDEV-15058 Remove multiple InnoDB buffer pool instances

Closed

MDEV-9784 High CPU Usage When Absolutely Idle on OSX

Closed

MDEV-11738 Mariadb uses 100% of several of my 8 cpus doing nothing

Closed

Activity

Ascending order - Click to sort in descending order

Vladislav Vaintroub created issue - 2018-01-20 23:14

Vladislav Vaintroub made changes - 2018-01-20 23:17

Field	Original Value	New Value
Description	Issue described in https://lists.launchpad.net/maria-discuss/msg04975.html as follows " On a 4-core (4 physical, 8 logical cores), 3.5 Ghz box, MariaDB 10.3.4 consumes typically 35-40% of available CPU even when completely idle according to Task Manager. Please see image. I cannot tell if previous 10.3 versions also did, as I don't have 10.3 running constantly in the backgorund. I just observed this now. This does not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL 8.0 (all those I have starting with Windows on this box), where the CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz (this CPU model is a 4th generation Intel with the option to scale down CPU frequency to around 40-50% of max. when there is no need for more) when MariaDB 10.3.4 is running. " Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex {code} . 15 Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29 0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 . 16 Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622] 0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841] 0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21 . 17 Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635] 0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841] 0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 {code}	Issue described in https://lists.launchpad.net/maria-discuss/msg04975.html as follows " On a 4-core (4 physical, 8 logical cores), 3.5 Ghz box, MariaDB 10.3.4 consumes typically 35-40% of available CPU even when completely idle according to Task Manager. Please see image. I cannot tell if previous 10.3 versions also did, as I don't have 10.3 running constantly in the backgorund. I just observed this now. This does not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL 8.0 (all those I have starting with Windows on this box), where the CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz (this CPU model is a 4th generation Intel with the option to scale down CPU frequency to around 40-50% of max. when there is no need for more) when MariaDB 10.3.4 is running. " Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex {code} . 15 Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29 0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 . 16 Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622] 0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841] 0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21 . 17 Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635] 0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841] 0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 {code} {code} . 18 Id: c10.1c30 Suspend: 0 Teb: 00000012`c5100000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 00000012`c63ff970 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xe8 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 00000012`c63ff9b0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 00000012`c63ff9e0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 . 19 Id: c10.a04 Suspend: 0 Teb: 00000012`c5102000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 00000012`c64ff6d0 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x13 (Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468] 00000012`c64ff700 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330] 00000012`c64ff730 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 00000012`c64ff770 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 00000012`c64ff7a0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 . 20 Id: c10.161c Suspend: 0 Teb: 00000012`c5104000 Unfrozen Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370) Priority: 0 Priority class: 32 Affinity: ff Child-SP RetAddr Call Site 00000012`c65ff9e8 00007ffa`6feec17f ntdll!NtWaitForAlertByThreadId+0x14 00000012`c65ff9f0 00007ffa`6feebf4b ntdll!RtlpWaitOnAddressWithTimeout+0x43 00000012`c65ffa20 00007ffa`6fec0c49 ntdll!RtlpWaitOnCriticalSection+0x15f 00000012`c65ffad0 00007ffa`6fec0b60 ntdll!RtlpEnterCriticalSectionContended+0xd9 00000012`c65ffb00 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x40 (Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468] 00000012`c65ffb30 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330] 00000012`c65ffb60 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574] 00000012`c65ffba0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14 00000012`c65ffbd0 00000000`00000000 ntdll!RtlUserThreadStart+0x21 [code}

Vladislav Vaintroub made changes - 2018-01-20 23:19

Description

Issue described in https://lists.launchpad.net/maria-discuss/msg04975.html as follows
"
On a 4-core (4 physical, 8 logical cores), 3.5 Ghz box, MariaDB 10.3.4
consumes typically 35-40% of available CPU even when completely idle
according to Task Manager. Please see image.

I cannot tell if previous 10.3 versions also did, as I don't have 10.3
running constantly in the backgorund. I just observed this now. This does
not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL
8.0 (all those I have starting with Windows on this box), where the
CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz
(this CPU model is a 4th generation Intel with the option to scale down CPU
frequency to around 40-50% of max. when there is no need for more) when
MariaDB 10.3.4 is running.

"

Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex

{code}

. 15 Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29
0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 16 Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622]
0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 17 Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635]
0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

{code}
. 18 Id: c10.1c30 Suspend: 0 Teb: 00000012`c5100000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c63ff970 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xe8 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c63ff9b0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c63ff9e0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 19 Id: c10.a04 Suspend: 0 Teb: 00000012`c5102000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c64ff6d0 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x13
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c64ff700 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c64ff730 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c64ff770 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c64ff7a0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 20 Id: c10.161c Suspend: 0 Teb: 00000012`c5104000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c65ff9e8 00007ffa`6feec17f ntdll!NtWaitForAlertByThreadId+0x14
00000012`c65ff9f0 00007ffa`6feebf4b ntdll!RtlpWaitOnAddressWithTimeout+0x43
00000012`c65ffa20 00007ffa`6fec0c49 ntdll!RtlpWaitOnCriticalSection+0x15f
00000012`c65ffad0 00007ffa`6fec0b60 ntdll!RtlpEnterCriticalSectionContended+0xd9
00000012`c65ffb00 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x40
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c65ffb30 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c65ffb60 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c65ffba0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c65ffbd0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
[code}

Issue described in https://lists.launchpad.net/maria-discuss/msg04975.html as follows
"
On a 4-core (4 physical, 8 logical cores), 3.5 Ghz box, MariaDB 10.3.4
consumes typically 35-40% of available CPU even when completely idle
according to Task Manager. Please see image.

I cannot tell if previous 10.3 versions also did, as I don't have 10.3
running constantly in the backgorund. I just observed this now. This does
not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL
8.0 (all those I have starting with Windows on this box), where the
CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz
(this CPU model is a 4th generation Intel with the option to scale down CPU
frequency to around 40-50% of max. when there is no need for more) when
MariaDB 10.3.4 is running.

"

Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex

Setting innodb_page_cleaners = 1, according to reporter, fixed the issue.

Relevant callstack information :
{code}

. 15 Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29
0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 16 Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622]
0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 17 Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635]
0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

{code}
. 18 Id: c10.1c30 Suspend: 0 Teb: 00000012`c5100000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c63ff970 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xe8 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c63ff9b0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c63ff9e0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 19 Id: c10.a04 Suspend: 0 Teb: 00000012`c5102000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c64ff6d0 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x13
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c64ff700 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c64ff730 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c64ff770 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c64ff7a0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 20 Id: c10.161c Suspend: 0 Teb: 00000012`c5104000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c65ff9e8 00007ffa`6feec17f ntdll!NtWaitForAlertByThreadId+0x14
00000012`c65ff9f0 00007ffa`6feebf4b ntdll!RtlpWaitOnAddressWithTimeout+0x43
00000012`c65ffa20 00007ffa`6fec0c49 ntdll!RtlpWaitOnCriticalSection+0x15f
00000012`c65ffad0 00007ffa`6fec0b60 ntdll!RtlpEnterCriticalSectionContended+0xd9
00000012`c65ffb00 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x40
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c65ffb30 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c65ffb60 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c65ffba0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c65ffbd0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

Vladislav Vaintroub made changes - 2018-01-20 23:44

Attachment		mysqld.DMP.bz2.001 [ 44929 ]
Attachment		mysqld.DMP.bz2.002 [ 44930 ]
Attachment		mysqld.DMP.bz2.003 [ 44931 ]

Vladislav Vaintroub made changes - 2018-01-20 23:47

Attachment		mysqld0.DMP.xz.001 [ 44932 ]
Attachment		mysqld0.DMP.xz.002 [ 44933 ]

Vladislav Vaintroub made changes - 2018-01-21 00:01

Link

This issue relates to ~~MDEV-11738~~ [ ~~MDEV-11738~~ ]

Vladislav Vaintroub made changes - 2018-01-21 00:06

Link

This issue relates to ~~MDEV-9784~~ [ ~~MDEV-9784~~ ]

Vladislav Vaintroub made changes - 2018-01-21 00:33

Description

Issue described in https://lists.launchpad.net/maria-discuss/msg04975.html as follows
"
On a 4-core (4 physical, 8 logical cores), 3.5 Ghz box, MariaDB 10.3.4
consumes typically 35-40% of available CPU even when completely idle
according to Task Manager. Please see image.

I cannot tell if previous 10.3 versions also did, as I don't have 10.3
running constantly in the backgorund. I just observed this now. This does
not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL
8.0 (all those I have starting with Windows on this box), where the
CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz
(this CPU model is a 4th generation Intel with the option to scale down CPU
frequency to around 40-50% of max. when there is no need for more) when
MariaDB 10.3.4 is running.

"

Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex

Setting innodb_page_cleaners = 1, according to reporter, fixed the issue.

Relevant callstack information :
{code}

. 15 Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29
0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 16 Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622]
0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 17 Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635]
0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

{code}
. 18 Id: c10.1c30 Suspend: 0 Teb: 00000012`c5100000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c63ff970 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xe8 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c63ff9b0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c63ff9e0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 19 Id: c10.a04 Suspend: 0 Teb: 00000012`c5102000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c64ff6d0 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x13
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c64ff700 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c64ff730 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c64ff770 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c64ff7a0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 20 Id: c10.161c Suspend: 0 Teb: 00000012`c5104000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c65ff9e8 00007ffa`6feec17f ntdll!NtWaitForAlertByThreadId+0x14
00000012`c65ff9f0 00007ffa`6feebf4b ntdll!RtlpWaitOnAddressWithTimeout+0x43
00000012`c65ffa20 00007ffa`6fec0c49 ntdll!RtlpWaitOnCriticalSection+0x15f
00000012`c65ffad0 00007ffa`6fec0b60 ntdll!RtlpEnterCriticalSectionContended+0xd9
00000012`c65ffb00 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x40
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c65ffb30 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c65ffb60 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c65ffba0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c65ffbd0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

Issue described in https://lists.launchpad.net/maria-discuss/msg04975.html as follows
"
On a 4-core (4 physical, 8 logical cores), 3.5 Ghz box, MariaDB 10.3.4
consumes typically 35-40% of available CPU even when completely idle
according to Task Manager. Please see image.

I cannot tell if previous 10.3 versions also did, as I don't have 10.3
running constantly in the backgorund. I just observed this now. This does
not happen with MariaDB 10.1, MariaDB 10.2, MySQL 5.6, MySQL 5.7 and MySQL
8.0 (all those I have starting with Windows on this box), where the
CPU-load is neglible unless the server is "doing real work". As a result, the CPU clock frequency is always highest possible 3.5 Ghz
(this CPU model is a 4th generation Intel with the option to scale down CPU
frequency to around 40-50% of max. when there is no need for more) when
MariaDB 10.3.4 is running.

"

Examination of the attached stacktraces taken from the process when it in this "idle" state, shows 3 CPUs being occupied by something, that for most time seems to be some busy-look acquiring innodb mutex
.
my.ini uses all defaults, with the exception of
innodb_buffer_pool_size=4091M

Setting innodb_page_cleaners = 1, according to reporter, fixed the issue.

Relevant callstack information :
{code}

. 15 Id: 2ce8.2d38 Suspend: 0 Teb: 0000005f`55863000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56cffd40 00007ff7`6aec945d ntdll!RtlLeaveCriticalSection+0x29
0000005f`56cffd70 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56cffdb0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56cffde0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 16 Id: 2ce8.2d3c Suspend: 0 Teb: 0000005f`55865000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56dff6c0 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x26 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 622]
0000005f`56dff740 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56dff7b0 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56dff7f0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56dff820 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 17 Id: 2ce8.2d40 Suspend: 0 Teb: 0000005f`55867000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
0000005f`56eff750 00007ff7`6aec8503 mysqld!PolicyMutex<TTASEventMutex<GenericPolicy> >::enter+0x57 [d:\winx64-packages\build\src\storage\innobase\include\ib0mutex.h @ 635]
0000005f`56eff7d0 00007ff7`6aec944b mysqld!pc_flush_slot+0x43 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 2841]
0000005f`56eff840 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xdb [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
0000005f`56eff880 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
0000005f`56eff8b0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

{code}
. 18 Id: c10.1c30 Suspend: 0 Teb: 00000012`c5100000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c63ff970 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xe8 [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c63ff9b0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c63ff9e0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 19 Id: c10.a04 Suspend: 0 Teb: 00000012`c5102000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c64ff6d0 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x13
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c64ff700 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c64ff730 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c64ff770 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c64ff7a0 00000000`00000000 ntdll!RtlUserThreadStart+0x21

. 20 Id: c10.161c Suspend: 0 Teb: 00000012`c5104000 Unfrozen
      Start: mysqld!buf_flush_page_cleaner_worker (00007ff7`6aec9370)
      Priority: 0 Priority class: 32 Affinity: ff
Child-SP RetAddr Call Site
00000012`c65ff9e8 00007ffa`6feec17f ntdll!NtWaitForAlertByThreadId+0x14
00000012`c65ff9f0 00007ffa`6feebf4b ntdll!RtlpWaitOnAddressWithTimeout+0x43
00000012`c65ffa20 00007ffa`6fec0c49 ntdll!RtlpWaitOnCriticalSection+0x15f
00000012`c65ffad0 00007ffa`6fec0b60 ntdll!RtlpEnterCriticalSectionContended+0xd9
00000012`c65ffb00 00007ff7`6ae0ef0f ntdll!RtlEnterCriticalSection+0x40
(Inline Function) --------`-------- mysqld!OSMutex::enter+0xa [d:\winx64-packages\build\src\storage\innobase\include\sync0types.h @ 468]
00000012`c65ffb30 00007ff7`6aec945d mysqld!os_event::wait_low+0x1f [d:\winx64-packages\build\src\storage\innobase\os\os0event.cc @ 330]
00000012`c65ffb60 00007ffa`6d831fe4 mysqld!buf_flush_page_cleaner_worker+0xed [d:\winx64-packages\build\src\storage\innobase\buf\buf0flu.cc @ 3574]
00000012`c65ffba0 00007ffa`6ff0efb1 kernel32!BaseThreadInitThunk+0x14
00000012`c65ffbd0 00000000`00000000 ntdll!RtlUserThreadStart+0x21
{code}

Vladislav Vaintroub made changes - 2018-01-21 13:38

Summary

page cleaner threads use a lot of CPU on idle server

multiple page cleaner threads use a lot of CPU on idle server

Vladislav Vaintroub made changes - 2018-01-21 13:38

Priority

Major [ 3 ]

Blocker [ 1 ]

Vladislav Vaintroub made changes - 2018-01-21 13:41

Priority

Blocker [ 1 ]

Critical [ 2 ]

Elena Stepanova made changes - 2018-01-21 17:21

Status

Open [ 1 ]

Confirmed [ 10101 ]

Elena Stepanova made changes - 2018-01-21 18:50

Assignee

Jan Lindström [ jplindst ]

Elena Stepanova made changes - 2018-01-21 18:50

Fix Version/s

10.3 [ 22126 ]

Marko Mäkelä made changes - 2018-01-21 21:06

Link

This issue is caused by ~~MDEV-11025~~ [ ~~MDEV-11025~~ ]

Jan Lindström (Inactive) made changes - 2018-01-22 09:17

Status

Confirmed [ 10101 ]

In Progress [ 3 ]

Jan Lindström (Inactive) made changes - 2018-01-22 09:33

Assignee	Jan Lindström [ jplindst ]	Marko Mäkelä [ marko ]
Status	In Progress [ 3 ]	In Review [ 10002 ]

Marko Mäkelä made changes - 2018-01-22 10:55

Assignee	Marko Mäkelä [ marko ]	Jan Lindström [ jplindst ]
Status	In Review [ 10002 ]	Stalled [ 10000 ]

Sergei Golubchik made changes - 2018-01-23 14:26

Priority

Critical [ 2 ]

Blocker [ 1 ]

Jan Lindström (Inactive) made changes - 2018-01-23 17:16

Status

Stalled [ 10000 ]

In Progress [ 3 ]

Jan Lindström (Inactive) made changes - 2018-01-23 17:17

Assignee	Jan Lindström [ jplindst ]	Marko Mäkelä [ marko ]
Status	In Progress [ 3 ]	In Review [ 10002 ]

Marko Mäkelä made changes - 2018-01-24 10:49

Link

This issue relates to ~~MDEV-15053~~ [ ~~MDEV-15053~~ ]

Sergei Golubchik made changes - 2018-01-24 12:22

Sprint

10.1.31 [ 225 ]

Marko Mäkelä made changes - 2018-01-24 13:55

Link

This issue relates to ~~MDEV-15058~~ [ ~~MDEV-15058~~ ]

Marko Mäkelä made changes - 2018-01-25 21:49

Fix Version/s		10.3.5 [ 22905 ]
Fix Version/s		10.2.13 [ 22910 ]
Fix Version/s	10.3 [ 22126 ]
Resolution		Fixed [ 1 ]
Status	In Review [ 10002 ]	Closed [ 6 ]

Sergei Golubchik made changes - 2021-12-06 21:46

Workflow

MariaDB v3 [ 84981 ]

MariaDB v4 [ 153610 ]

People

Assignee:: Marko Mäkelä

Reporter:: Vladislav Vaintroub

Votes:: 0 Vote for this issue

Watchers:: 5 Start watching this issue

Dates

Created:: 2018-01-20 23:14

Updated:: 2018-01-25 21:49

Resolved:: 2018-01-25 21:49

Git Integration

Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.

MariaDB Server

Details

Description

Attachments

Attachments

Issue Links

Activity

People

Dates

Git Integration