首页 » ORACLE [C]系列, ORACLE 9i-23c » Troubleshooting ASM allocation is failed due to ORA-4030 though OS has enough free memories.

Troubleshooting ASM allocation is failed due to ORA-4030 though OS has enough free memories.

某客户一套Oracle 11.2.0.4 4-node RAC ON RHEL 7.6 环境 ,ASM High冗余Diskgroup 有600TB存储(没错是个超级大库), 其中有1个1TB的ACFS DG. 一日突然节点1个节点ASM和DB实例crash, 重启后正常, 分析当时的日志是ASM 实例的VDBG后台进程出现的ora-4030错误,目前需要分析一下原因。 简单记录。

DB alert log

Thu Aug 04 19:45:05 2022
WARNING: ASM communication error: op 18 state 0x50 (3113)
ERROR: slave communication error with ASM
NOTE: Deferred communication with ASM instance
Errors in file /u01/app/oracle/diag/rdbms/newanbob/anbob4/trace/anbob4_ora_47083.trc:
ORA-03113: end-of-file on communication channel
...

NOTE: Deferred communication with ASM instance
Thu Aug 04 19:45:05 2022
NOTE: ASMB terminating
Thu Aug 04 19:45:05 2022
WARNING: ASM communication error: op 17 state 0x50 (3113)NOTE: deferred map free for map id 7572857

Errors in file /u01/app/oracle/diag/rdbms/newanbob/anbob4/trace/anbob4_ora_65011.trc:
ORA-03113: end-of-file on communication channel
Process ID:
Session ID: 2380 Serial number: 31
ERROR: slave communication error with ASM
...
Session ID: 1404 Serial number: 149
ASMB (ospid: 56359): terminating the instance due to error 15064
NOTE: Deferred communication with ASM instance
Errors in file /u01/app/oracle/diag/rdbms/newanbob/anbob4/trace/anbob4_ora_65017.trc:
ORA-15077: could not locate ASM instance serving a required diskgroup
ORA-03113: end-of-file on communication channel
Session ID: 2014 Serial number: 2539
NOTE: deferred map free for map id 7572856
Errors in file /u01/app/oracle/diag/rdbms/newanbob/anbob4/trace/anbob4_ora_47096.trc  (incident=198037):
ORA-00600: internal error code, arguments: [2618], [8], [], [], [], [], [], [], [], [], [], []
NOTE: Deferred communication with ASM instance
Errors in file /u01/app/oracle/diag/rdbms/newanbob/anbob4/trace/anbob4_ora_65011.trc:
ORA-15077: could not locate ASM instance serving a required diskgroup
ORA-15077: could not locate ASM instance serving a required diskgroup
NOTE: deferred map free for map id 7572859

Note:
db instance crash是因为ASM intance请求失败.

ASM alert log

Thu Aug 04 19:44:52 2022
Errors in file /u01/app/grid/diag/asm/+asm/+ASM4/trace/+ASM4_vdbg_9668.trc  (incident=2638776):
ORA-04030: out of process memory when trying to allocate 118584 bytes (pga heap,kfvsuIOStatOp)
Incident details in: /u01/app/grid/diag/asm/+asm/+ASM4/incident/incdir_2638776/+ASM4_vdbg_9668_i2638776.trc
Use ADRCI or Support Workbench to package the incident.
See Note 411.1 at My Oracle Support for error and packaging details.
Thu Aug 04 19:45:03 2022
Dumping diagnostic data in directory=[cdmp_20220804194503], requested by (instance=4, osid=9668 (VDBG)), summary=[incident=2638776].
Thu Aug 04 19:45:05 2022
Errors in file /u01/app/grid/diag/asm/+asm/+ASM4/trace/+ASM4_vdbg_9668.trc:
ORA-04030: out of process memory when trying to allocate 118584 bytes (pga heap,kfvsuIOStatOp)
System state dump requested by (instance=4, osid=9668 (VDBG)), summary=[abnormal instance termination].
VDBG (ospid: 9668): terminating the instance due to error 4030
System State dumped to trace file /u01/app/grid/diag/asm/+asm/+ASM4/trace/+ASM4_diag_3689_20220804194505.trc
Thu Aug 04 19:45:06 2022
ORA-1092 : opitsk aborting process
Thu Aug 04 19:45:06 2022
ORA-1092 : opitsk aborting process
Thu Aug 04 19:45:06 2022
License high water mark = 45

vdbg trace file

Dump file /u01/app/grid/diag/asm/+asm/+ASM4/trace/+asm4_vdbg_9668.trc
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
..
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
DDE: Flood control is not active
========= Dump for critical error (no incident) (DIA 48002) ========
Starting a Diag Context default dump (level=3)
DIA-48002: thread terminated by fatal error
DIA-48003: out of process memory when trying to allocate 56 bytes (diag pga,dbgr entry)
----- Call Stack Trace -----
calling              call     entry                argument values in hex      
location             type     point                (? means dubious value)     
-------------------- -------- -------------------- ----------------------------
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
..
..
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
kgdsdstsg()+53       call     kgdsdst()            000000000 ? 000000000 ?
                                                   7FFC14E0A000 ? 7FFC14E0A0D8 ?
                                                   7FFC14E0EB80 ? 000000003 ?
00000000077CEDDD     call     kgdsdstsg()          7F067C909878 ? 7FFC14E0EE80 ?
                                                   000000003 ? 7FFC14E0A0D8 ?
                                                   7FFC14E0EB80 ? 000000003 ?
000000000770FF80     call     00000000077CEDDD     7F067C909878 ? 000000003 ?
                                                   000000003 ? 7FFC14E0A0D8 ?
                                                   7FFC14E0EB80 ? 000000003 ?
dbgexProcessError()  call     000000000770FF80     7F067C8FE728 ? 7F067C938E58 ?
+2680                                              7FFC14E12FF8 ? 7FFC14E0A0D8 ?
                                                   7FFC14E0EB80 ? 000000003 ?
00000000076CF596     call     dbgexProcessError()  7F067C8FE728 ? 7F067C938E58 ?
                                                   000000001 ? 000000000 ?
                                                   7FFC14E0EB80 ? 000000003 ?
dbgePostErrorKGE()+  call     00000000076CF596     7F067C8FE728 ? 7F067C938E58 ?
2136                                               000000001 ? 000000001 ?
                                                   000000000 ? 000000003 ?
00000000077CEF95     call     dbgePostErrorKGE()   7F067C909878 ? 7F067C92CA98 ?
                                                   00000BB82 ? 000000001 ?
                                                   000000000 ? 000000003 ?
0000000002A28E05     call     00000000077CEF95     7F067C909878 ? 7F067C92CA98 ?
                                                   00000BB82 ? 000000001 ?
                                                   000000000 ? 000000003 ?
kgefec()+176         call     0000000002A28E05     7F067C909878 ? 7F067C909A38 ?
                                                   7F067C92CA98 ? 00000BB82 ?
                                                   000000000 ? 000000003 ?
0000000002A19192     call     kgefec()             7F067C909878 ? 7F067C92CA98 ?
                                                   000000000 ? 000000001 ?
                                                   000000000 ? 000000003 ?
kghalf()+1139        call     0000000002A19192     7F067C909878 ? 7F067C8FE7F8 ?
                                                   000000038 ? 000000001 ?
                                                   000000000 ? 000000003 ?
dbgric_init_cache()  call     kghalf()             7F067C909878 ? 7F067C8FE7F8 ?
+527                                               000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
dbgriap_init_adr_pg  call     dbgric_init_cache()  7F067C8FE728 ? 7FFC14E144F0 ?
a()+198                                            000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
dbgc_init_all()+848  call     dbgriap_init_adr_pg  7F067C8FE728 ? 000000003 ?
                              a()                  000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
0000000002D5FFCE     call     dbgc_init_all()      000000003 ? 7F067C8FE728 ?
                                                   000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
0000000002D5FB37     call     0000000002D5FFCE     7F067C8FE728 ? 7F067C8FE728 ?
                                                   000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
dbgc_rls_diagctx()+  call     0000000002D5FB37     000000002 ? 00AC3BA20 ?
58                                                 000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
0000000001F9A23F     call     dbgc_rls_diagctx()   000000002 ? 00AC3BA20 ?
                                                   000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
dbkc_sga_unmap()+75  call     0000000001F9A23F     000000002 ? 00AC3BA20 ?
                                                   000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
ksmsdes()+111        call     dbkc_sga_unmap()     000000000 ? 00AC3BA20 ?
                                                   000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
ksuitm()+2165        call     ksmsdes()            7FFC14E14AF0 ? 00AC3BA20 ?
                                                   000000020 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
ksbrdp()+3507        call     ksuitm()             000000000 ? 000000FBE ?
                                                   154484DD0 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
opirip()+623         call     ksbrdp()             000000000 ? 000000FBE ?
                                                   154484DD0 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
opidrv()+603         call     opirip()             000000032 ? 000000004 ?
                                                   7FFC14E15FA8 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
sou2o()+103          call     opidrv()             000000032 ? 000000004 ?
                                                   7FFC14E15FA8 ? 000000001 ?
                                                   000000000 ? 0098F08C8 ?
mmap(offset=210198528, len=4096) failed with errno=12 for the file asm_vdbg_+ASM4
opimai_real()+250    call     sou2o()              7FFC14E15F80 ? 000000032 ?
                                                   000000004 ? 7FFC14E15FA8 ?
                                                   000000000 ? 0098F08C8 ?
ssthrdmain()+265     call     opimai_real()        000000000 ? 7FFC14E16170 ?
                                                   000000004 ? 7FFC14E15FA8 ?
                                                   000000000 ? 0098F08C8 ?
main()+201           call     ssthrdmain()         000000003 ? 7FFC14E16170 ?
                                                   000000001 ? 000000000 ?
                                                   000000000 ? 0098F08C8 ?
__libc_start_main()  call     main()               000000003 ? 7FFC14E16310 ?
+245                                               000000001 ? 000000000 ?
                                                   000000000 ? 0098F08C8 ?
00000000008489F9     call     __libc_start_main()  000848AC0 ? 000000001 ?
                                                   7FFC14E16308 ? 000000000 ?
                                                   000000000 ? 0098F08C8 ?
												   
...


----- Process Map Dump -----
******************* Dumping process map ****************
00400000-0a8ac000 r-xp 00000000 fd:03 67451950                           /u01/app/11.2.0/grid/bin/oracle
0aaac000-0aaad000 r--p 0a4ac000 fd:03 67451950                           /u01/app/11.2.0/grid/bin/oracle
0aaad000-0ac15000 rw-p 0a4ad000 fd:03 67451950                           /u01/app/11.2.0/grid/bin/oracle
0ac15000-0ac5e000 rw-p 00000000 00:00 0 
0bd44000-0bdea000 rw-p 00000000 00:00 0                                  [heap]
60000000-60001000 r--s 00000000 00:13 450040                             /dev/shm/ora_+ASM4_131075_0
60001000-61000000 rw-s 00001000 00:13 450040                             /dev/shm/ora_+ASM4_131075_0
61000000-62000000 rw-s 00000000 00:13 450041                             /dev/shm/ora_+ASM4_131075_1
...
7f04bd20c000-7f04bd21c000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd21c000-7f04bd23c000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd23c000-7f04bd25c000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd25c000-7f04bd27c000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd27c000-7f04bd29c000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd29c000-7f04bd2ac000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd2ac000-7f04bd2cc000 rw-p 00000000 00:05 1030                       /dev/zero
7f04bd2cc000-7f04bd2ec000 rw-p 00000000 00:05 1030                       /dev/zero
...
7ffc14f8e000-7ffc14f90000 r-xp 00000000 00:00 0                          [vdso]
ffffffffff600000-ffffffffff601000 r-xp 00000000 00:00 0                  [vsyscall]
                                                                        #######  ---- total number of 65532 lines 
******************* End of process map dump ************
----- Process Resource Limits -----
***************** Dumping Resource Limits(s/h) *****************
core file size                    UNLIMITED/UNLIMITED         
data seg size                     UNLIMITED/UNLIMITED         
file size                         UNLIMITED/UNLIMITED         
pending signals                     2010 KB/2010 KB           
max locked memory                 UNLIMITED/UNLIMITED         
max memory size                   UNLIMITED/UNLIMITED         
open files                            64 KB/64 KB             
POSIX message queues                 800 KB/800 KB            
stack size                            32 MB/UNLIMITED         
cpu time                          UNLIMITED/UNLIMITED         
max user processes                    16 KB/16 KB             
virtual memory                    UNLIMITED/UNLIMITED         
file locks                        UNLIMITED/UNLIMITED         
***************** End of Resource Limits Dump ******************


Note:
call stack中有mmap(offset=210198528, len=4096) failed with errno=12报错, 当时的data和stack 在当前用户已经是unlimited, MAX memory size和virtual memory 也是unlimited . 因为mmap失败导致asm intance的VDBG进程出现的ora-4030错误 。

也可以手动收集maps

$ps -ef|grep vdbg
$ pmap -p <vdbg进程号>

mmap failed with errno=12

[root@oel7db1 ~]# perror 12
OS error code  12:  Cannot allocate memory

Note:
that 12 is exactly ENOMEM error code. Investigation for mmap() syscall shows that ENOMEM stands for “No memory is available, or the process’s maximum number of mappings would have been exceeded.”

VDBG Process

Volume Driver Process
Forwards Oracle ASM requests to perform various volume-related tasks
VDBG handles requests to lock or unlock an extent for rebalancing, volume resize, disk offline, add or drop a disk, force and dismount disk group to the Dynamic Volume Manager driver.

Note:
判断与ASM ACFS相关。

OSW vmstat

zzz ***Thu Aug 4 19:41:05 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
25  2 436116 118542424  81556 42647252    0    0  2605   406    0    0 10  3 85  1  0
19  4 436116 118563664  81556 42648388    0    0 684500  2338 478437 189366 17  7 74  2  0
21  4 436116 118491496  81556 42648276    0    0 570272  2741 459318 203020 13  6 79  2  0
zzz ***Thu Aug 4 19:41:36 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
24  7 436116 118174656  81556 42654560    0    0  2605   406    0    0 10  3 85  1  0
17  7 436116 118107056  81556 42654244    0    0 960114  4220 488187 295850 16  7 73  3  0
23  5 436116 118005568  81556 42654516    0    0 1095528  9780 476740 248006 15  7 75  4  0
zzz ***Thu Aug 4 19:42:07 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
36  1 436116 118660784  81556 42661968    0    0  2605   406    0    0 10  3 85  1  0
29  2 436116 118633664  81556 42662728    0    0 621297  8233 565649 258656 17  9 73  1  0
29  3 436116 118634256  81556 42663212    0    0 707392  2908 590466 315953 15  9 75  1  0
zzz ***Thu Aug 4 19:42:38 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
25  4 436116 118511632  81556 42665980    0    0  2605   406    0    0 10  3 85  1  0
21  3 436116 118320824  81556 42666804    0    0 758968 11114 420537 277405 16  5 75  4  0
19  3 436116 118262856  81556 42667052    0    0 774818 13257 439119 296825 13  7 77  3  0
zzz ***Thu Aug 4 19:43:09 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
23  0 436116 118024848  81556 42677192    0    0  2605   406    0    0 10  3 85  1  0
23  1 436116 117908224  81556 42678480    0    0 828708  7031 528760 157467 15  8 76  1  0
21  0 436116 117911840  81556 42678396    0    0 829886  3924 544309 201965 16  7 76  1  0
zzz ***Thu Aug 4 19:43:40 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
24  6 436116 118217664  81556 42685468    0    0  2605   406    0    0 10  3 85  1  0
16  6 436116 118186256  81556 42685844    0    0 1247490  1470 472355 210836 14  7 76  3  0
20  4 436116 118188568  81556 42686296    0    0 1252882  9776 467208 207419 13  6 78  3  0
zzz ***Thu Aug 4 19:44:11 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
23  3 436116 117897696  81556 42694212    0    0  2605   406    0    0 10  3 85  1  0
26  0 436116 117777872  81552 42695332    0    0 665390 11447 539412 167680 17 10 72  1  0
25  1 436116 117754168  81500 42695716    0    0 715547  4452 549530 185636 17  8 73  2  0
zzz ***Thu Aug 4 19:44:43 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
27  4 436116 117627000  81556 42701572    0    0  2605   406    0    0 10  3 85  1  0
21  7 436116 118095872  81556 42702520    0    0 1182373  2582 493360 193122 19  8 69  4  0
23  5 436116 118133888  81556 42702364    0    0 1095942  2632 473514 196193 16  6 74  4  0
zzz ***Thu Aug 4 19:45:14 CST 2022
procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu-----
 r  b   swpd   free   buff  cache   si   so    bi    bo   in   cs us sy id wa st
 6  0 436116 125675744  44588 45139048    0    0  2605   406    0    0 10  3 85  1  0
 8  0 436116 125555808  44588 45296892    0    0  2873 772219 344671 316581  2  6 93  0  0
 4  0 436116 125464688  44588 45400196    0    0  3830 1466162 162413 196481  1  3 96  0  0

Note:
可见swap和free memory 在操作系统层还有空间。

OSW PS

ps  RSS kb为单位
USER        PID   PPID PRI %CPU %MEM    VSZ   RSS WCHAN  S  STARTED     TIME COMMAND
zzz ***Thu Aug 4 19:00:11 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308376 SYSC_s S   Jul 09 1-22:52:09 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:00:42 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308496 SYSC_s S   Jul 09 1-22:52:09 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:01:13 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308496 SYSC_s S   Jul 09 1-22:52:09 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:01:44 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308648 SYSC_s S   Jul 09 1-22:52:09 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:02:15 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308648 SYSC_s S   Jul 09 1-22:52:10 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:02:46 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308648 SYSC_s S   Jul 09 1-22:52:10 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:03:17 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308648 SYSC_s S   Jul 09 1-22:52:10 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:03:48 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308912 SYSC_s S   Jul 09 1-22:52:11 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:04:20 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308912 SYSC_s S   Jul 09 1-22:52:11 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:04:51 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308912 SYSC_s S   Jul 09 1-22:52:11 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:05:22 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7308912 SYSC_s S   Jul 09 1-22:52:12 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:05:53 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309032 SYSC_s S   Jul 09 1-22:52:12 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:06:24 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309032 SYSC_s S   Jul 09 1-22:52:12 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:06:55 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309160 SYSC_s S   Jul 09 1-22:52:13 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:07:26 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309280 SYSC_s S   Jul 09 1-22:52:13 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:07:57 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309280 SYSC_s S   Jul 09 1-22:52:13 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:08:28 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309280 SYSC_s S   Jul 09 1-22:52:14 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:08:59 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309420 SYSC_s S   Jul 09 1-22:52:14 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:09:30 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309420 SYSC_s S   Jul 09 1-22:52:15 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:10:01 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309540 SYSC_s S   Jul 09 1-22:52:15 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:10:32 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309540 SYSC_s S   Jul 09 1-22:52:15 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:11:03 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309540 SYSC_s S   Jul 09 1-22:52:16 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:11:34 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309540 SYSC_s S   Jul 09 1-22:52:16 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:12:05 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309724 SYSC_s S   Jul 09 1-22:52:17 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:12:36 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309724 SYSC_s S   Jul 09 1-22:52:17 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:13:08 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309848 -  S   Jul 09 1-22:52:17 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:13:39 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309848 SYSC_s S   Jul 09 1-22:52:18 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:14:10 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309992 SYSC_s S   Jul 09 1-22:52:18 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:14:41 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7309992 SYSC_s S   Jul 09 1-22:52:18 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:15:12 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310112 SYSC_s S   Jul 09 1-22:52:19 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:15:43 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310112 SYSC_s S   Jul 09 1-22:52:19 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:16:14 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310248 SYSC_s S   Jul 09 1-22:52:20 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:16:45 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310248 SYSC_s S   Jul 09 1-22:52:20 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:17:16 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310368 SYSC_s S   Jul 09 1-22:52:20 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:17:47 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310368 SYSC_s S   Jul 09 1-22:52:21 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:18:18 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310368 SYSC_s S   Jul 09 1-22:52:21 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:18:49 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310368 SYSC_s S   Jul 09 1-22:52:21 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:19:20 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310368 SYSC_s S   Jul 09 1-22:52:22 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:19:51 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310556 SYSC_s S   Jul 09 1-22:52:22 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:20:22 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310556 SYSC_s S   Jul 09 1-22:52:22 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:20:54 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310692 SYSC_s S   Jul 09 1-22:52:22 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:21:25 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310824 SYSC_s S   Jul 09 1-22:52:23 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:21:56 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310944 SYSC_s S   Jul 09 1-22:52:23 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:22:27 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310944 SYSC_s S   Jul 09 1-22:52:23 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:22:58 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310944 SYSC_s S   Jul 09 1-22:52:23 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:23:29 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7310944 SYSC_s S   Jul 09 1-22:52:24 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:24:00 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311128 SYSC_s S   Jul 09 1-22:52:24 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:24:31 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311128 SYSC_s S   Jul 09 1-22:52:24 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:25:02 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311248 SYSC_s S   Jul 09 1-22:52:24 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:25:33 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311248 SYSC_s S   Jul 09 1-22:52:25 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:26:04 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311248 SYSC_s S   Jul 09 1-22:52:25 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:26:35 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311248 SYSC_s S   Jul 09 1-22:52:26 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:27:06 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311404 SYSC_s S   Jul 09 1-22:52:26 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:27:37 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311404 SYSC_s S   Jul 09 1-22:52:27 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:28:08 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311404 SYSC_s S   Jul 09 1-22:52:27 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:28:39 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311600 SYSC_s S   Jul 09 1-22:52:28 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:29:10 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311720 SYSC_s S   Jul 09 1-22:52:28 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:29:41 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311720 SYSC_s S   Jul 09 1-22:52:29 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:30:12 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311720 SYSC_s S   Jul 09 1-22:52:29 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:30:43 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311720 SYSC_s S   Jul 09 1-22:52:29 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:31:14 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311920 SYSC_s S   Jul 09 1-22:52:30 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:31:45 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7311920 SYSC_s S   Jul 09 1-22:52:30 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:32:17 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312040 SYSC_s S   Jul 09 1-22:52:31 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:32:48 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312040 SYSC_s S   Jul 09 1-22:52:31 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:33:19 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312040 SYSC_s S   Jul 09 1-22:52:32 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:33:50 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312216 SYSC_s S   Jul 09 1-22:52:32 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:34:21 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312216 SYSC_s S   Jul 09 1-22:52:32 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:34:52 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312216 SYSC_s S   Jul 09 1-22:52:33 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:35:23 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312360 SYSC_s S   Jul 09 1-22:52:33 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:35:54 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312360 SYSC_s S   Jul 09 1-22:52:34 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:36:25 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312488 SYSC_s S   Jul 09 1-22:52:34 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:36:56 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312488 SYSC_s S   Jul 09 1-22:52:34 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:37:27 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312488 SYSC_s S   Jul 09 1-22:52:35 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:37:58 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312616 SYSC_s S   Jul 09 1-22:52:35 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:38:29 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312616 SYSC_s S   Jul 09 1-22:52:35 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:39:00 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312736 SYSC_s S   Jul 09 1-22:52:36 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:39:32 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312736 SYSC_s S   Jul 09 1-22:52:36 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:40:03 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312864 -  S   Jul 09 1-22:52:36 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:40:34 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312864 SYSC_s S   Jul 09 1-22:52:36 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:41:05 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7312864 SYSC_s S   Jul 09 1-22:52:37 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:41:37 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313048 SYSC_s S   Jul 09 1-22:52:37 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:42:08 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313048 SYSC_s S   Jul 09 1-22:52:37 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:42:39 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313048 SYSC_s S   Jul 09 1-22:52:37 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:43:10 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313180 SYSC_s S   Jul 09 1-22:52:38 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:43:41 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313180 SYSC_s S   Jul 09 1-22:52:38 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:44:12 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313320 SYSC_s S   Jul 09 1-22:52:38 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:44:43 CST 2022
grid       9668      1  19  0.4  1.3 11730252 7313320 SYSC_s S   Jul 09 1-22:52:38 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:45:14 CST 2022
grid       9668      1  19  0.4  1.3 11730372 7339608 hrtime S   Jul 09 1-22:52:50 asm_vdbg_+ASM4
zzz ***Thu Aug 4 19:45:45 CST 2022
zzz ***Thu Aug 4 19:46:16 CST 2022

Note:
持续关注进程是否有内存泄露是否过大或持续增长,当前如果正在做asm Operater有操作,我认为vdbg增长也是正常现象。

TFA OS report

# more /proc/sys/vm/max_map_count
65530

Note:
如果有tfactl 收集日志在os report中有OS 内核参数信息。

max_map_count

max_map_count 文件允许限制特定进程可以拥有的 VMA(虚拟内存区域)的数量。虚拟内存区是虚拟地址空间的连续区域。当程序尝试对文件进行内存映射、链接到共享内存段或分配堆空间时,会在进程的生命周期中创建这些区域。调整此值会限制进程可以拥有的这些 VMA 的数量。限制进程可以拥有的 VMA 数量可能会导致有问题的应用程序行为,因为当进程达到其 VMA 限制时系统将返回内存不足错误,但可以释放低内存以供其他内核使用。如果您的系统在 NORMAL 区域内存不足,则降低此值将有助于释放内存供内核使用.

mmap,OS error code  12:  Cannot allocate memory 这些错误通常是由于从操作系统中耗尽了map entries而出现的。

每个进程默认有64k map entries(max_map_count),默认的realfree allocator pagesize 是64 kB (65536), 所以64K entries可以分配4GB.加大map entries可以分配更多的内存,如 256kB (262144) pages, 限制增加到16GB.

查看realfree pages

select i.ksppinm name , v.ksppstvl cur_val,
       v.ksppstdf default_val, v.ksppstvf
  from x$ksppi i, x$ksppcv v
 where i.indx = v.indx
   and i.ksppinm in ('_realfree_heap_pagesize_hint', '_use_realfree_heap')

Diag event
ora-4030 发生时没有生成PGA heapdump信息,可以开启error TRACE

ALTER SYSTEM SET EVENTS '4030 trace name heapdump level 536870917;name errorstack level 3';

解决方案建议

1,增加map entries记录数或增加realfree pagesize

 Change the page count at the OS level: 
$ more /proc/sys/vm/max_map_count
$ sysctl -w vm.max_map_count=262144

-- OR --

Adjust the realfree heap pagesize within the database by setting the following parameters in the init/spfile and restart the database.

ALTER SYSTEM SET "_use_realfree_heap"=TRUE SCOPE=SPFILE;
ALTER SYSTEM SET "_realfree_heap_pagesize_hint"=262144 SCOPE=SPFILE;

NOTE: In Oracle 12c, the parameter _realfree_heap_pagesize_hint is changed to _realfree_heap_pagesize. In 12C, _realfree_heap_pagesize = 262144

2, 增加MEMROY_TARGET
当前系统OS free 内存较多,ASM DG较大,建议也增加一些MEMORY_TARGET(SGA+PGA AUTO). ASM 默认使用AMM, 在ASM实例中SGA的shared_pool占用较大, 所以我们的memory_target要给shared_pool预留外建议再增加2-4GB空间。

# FOR SHARED POOL

SELECT SUM(bytes)/(1024*1024*1024) FROM V$DATAFILE;
SELECT SUM(bytes)/(1024*1024*1024) FROM V$LOGFILE a, V$LOG b
WHERE a.group#=b.group#;
SELECT SUM(bytes)/(1024*1024*1024) FROM V$TEMPFILE
WHERE status='ONLINE';


o For disk groups using external redundancy, every 100 GB of space needs 1 MB of extra shared pool plus 2 MB.
o For disk groups using normal redundancy, every 50 GB of space needs 1 MB of extra shared pool plus 4 MB.
o For disk groups using high redundancy, every 33 GB of space needs 1 MB of extra shared pool plus 6 MB.
打赏

,

对不起,这篇文章暂时关闭评论。