My Oracle Support Banner

ACFS.log is constantly being written to the message, causing the acfs.log file size to become larger (Doc ID 2404244.1)

Last updated on MARCH 23, 2023

Applies to:

Oracle Database - Enterprise Edition - Version 11.2.0.4 and later
Oracle Database Cloud Schema Service - Version N/A and later
Gen 1 Exadata Cloud at Customer (Oracle Exadata Database Cloud Machine) - Version N/A and later
Oracle Database Exadata Express Cloud Service - Version N/A and later
Oracle Cloud Infrastructure - Database Service - Version N/A and later
Generic Linux

Symptoms

Following message was reported in acfs.log repeatedly , cause acfs.log file size to become too large.

V 5792993.172 mms[5984] AsmVolStateOpen: ASM open msg succeed for vol BACKUP_DG.VOLBACKUP
V 5792993.172 mms[5984] AsmVolStateOpen: strWidthBlks 256 strWidth 131072 strSetBlks 65536 strUnitBlks 1024
V 5792993.172 mms[5984] AsmVolStateOpen: stripeWidth 128K, columns 4 size 716000Mb
V 5792993.172 mms[5984] asmSetVolumeLimits: VOLBACKUP max_sectors 1024, hw_sectors 65535 , hw_segs 1024, phys_segs 1024
V 5792993.172 mms[5984] AsmVolStateOpen: volSize=716000M
V 5792993.172 mms[5984] VolAction out: VOL_OPEN st VOL_OPEN prev VOL_CLOSED vol 'BACKUP_DG.VOLBACKUP', ret 0
V 5792993.172 mms[5984] DgAction out: VOLOPEN st ACTIVE prev INITIAL dg 'BACKUP_DG' ret 0
V 5792993.172 mms[5984] RootAction out: VOLOPEN st ACTIVE prev INITIAL ret 0
V 5792993.172 mms[5984] asmOpen_int: done: major 252 minor 235521 new refcnt 1
V 5792993.173 mms[5984] asmClose_int: closing volume device minor 235521
V 5792993.173 mms[5984] RootAction in: VOLCLOSE st ACTIVE prev INITIAL dispatch VolClose arg 0xffff8810337e20c0
V 5792993.173 mms[5984] DgAction in: VOLCLOSE st ACTIVE prev INITIAL dg 'BACKUP_DG' func VolClose arg1 0xffff880f876fcc00, arg2 0xffff8810337e20c0
V 5792993.173 mms[5984] VolAction in: VOL_CLOSE st VOL_OPEN prev VOL_CLOSED func Close vol 'BACKUP_DG.VOLBACKUP', arg 0xffff881024e5b000
V 5792993.173 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.173 mms[5984] Asm_sharedClose: VOLCLOSE message for dg 3, volnum 1 vol VOLBACKUP
V 5792993.173 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.173 mms[5984] Stats for volume 'VOLBACKUP':
V 5792993.173 mms[5984] vol IO: 0 writes, 0 sectors, 0 err 406106 reads, 8057998 sectors, 0 err
V 5792993.173 mms[5984] dev IO: 0 writes, 0 sectors, 0 err 406106 reads, 8057998 sectors, 0 err
V 5792993.173 mms[5984] DRL IO: 0 flushes, 0 cache hits
V 5792993.173 mms[5984] ALL IO: 4 peak allocations
V 5792993.173 mms[5984] Rbal IO: 0 held, 0 q2Restart, 0 unlockIo
V 5792993.173 mms[5984] VolAction out: VOL_CLOSE st VOL_CLOSED prev VOL_OPEN vol 'BACKUP_DG.VOLBACKUP', ret 0
V 5792993.173 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.173 mms[5984] Asm_dgUnuse: DGUNUSE message for dg(3) incarnation(1991852935)
V 5792993.173 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.173 mms[5984] asmDeleteDevice: close device dm-26
V 5792993.173 mms[5984] DgAction out: VOLCLOSE st ACTIVE prev INITIAL dg 'BACKUP_DG' ret 0
V 5792993.173 mms[5984] RootAction out: VOLCLOSE st ACTIVE prev INITIAL ret 0
V 5792993.173 mms[5984] RootAction in: VOLOPEN st ACTIVE prev INITIAL dispatch VolOpen arg 0xffff881024e5b000
V 5792993.173 mms[5984] DgAction in: VOLOPEN st ACTIVE prev INITIAL dg 'BACKUP_DG' func VolOpen arg1 0xffff880f876fcc00, arg2 0xffff881024e5b000
V 5792993.173 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.173 mms[5984] Asm_dgUse: DGUSE message for dg 'BACKUP_DG' incarnation(1991852935)
V 5792993.173 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.174 oracle[21602] Asm_loadDisks: 1 disks, highest number = 0, DG BACKUP_DG
V 5792993.174 oracle[21602] Asm_loadDisks: copying chunk 0, 1 disks, 0 remaining, DG BACKUP_DG
V 5792993.174 oracle[21602] Asm_loadDisks: chunk=0, slot=0, list[0].dev_dsknum=0
V 5792993.174 oracle[21602] Asm_loadDisks: load device 0
V 5792993.174 oracle[21602] Asm_loadDisks: lock inited
V 5792993.174 oracle[21602] asmAddDevice: opened disk dm-26
V 5792993.174 oracle[21602] Asm_loadDisks: device 0 now open
V 5792993.174 oracle[21602] Asm_loadDisks: set dev_mode = 7f for device[0]
V 5792993.174 oracle[21602] Asm_loadDisks: bump devCount to 1
V 5792993.174 oracle[21602] asmSetVolumeLimits: VOLBACKUP max_sectors 1024, hw_sectors 65535 , hw_segs 1024, phys_segs 1024
V 5792993.174 oracle[21602] Asm_loadDisks: devCount=1 AU=4194304 for dg BACKUP_DG
V 5792993.174 mms[5984] VolAction in: VOL_OPEN st VOL_CLOSED prev VOL_OPEN func Open vol 'BACKUP_DG.VOLBACKUP', arg 0xffff881024e5b000
V 5792993.174 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.174 mms[5984] AsmVolStateOpen: VOLOPEN message for dg 'BACKUP_DG', vol 'VOLBACKUP'
V 5792993.174 mms[5984] Asm_getVbgCounts: busy/run 0/3
V 5792993.174 oracle[21534] Asm_allocExtents: extBlkListSize: 1408 bytes, blksNeeded 175
V 5792993.174 oracle[21534] Asm_allocExtents: 702K extent mem for vol, 702K total
V 5792993.174 oracle[21534] Asm_allocExtents: auPerExt 2, blksPerAu 8192, blksPerExt 16384
V 5792993.174 oracle[21534] Asm_allocExtents: extsPerExtBlk 512, blksPerExtBlk 8388608
V 5792993.174 oracle[21534] Asm_updateDevModes: DG BACKUP_DG, ASM passed 1 devs, using 1/1 DG devs
V 5792993.174 oracle[21534] Asm_loadExtents: count 60 (480 bytes), userAdrs 0x7ed8cfd0
V 5792993.174 oracle[21534] Asm_loadExtents: loading 60 extents for vol VOLBACKUP
V 5792993.174 oracle[21534] Asm_loadExtents: 0 extents copied, 60 exts this copy nextExtent=f2ebc000lx
V 5792993.175 oracle[21534] Asm_loadExtents: count 7680 (61440 bytes), userAdrs 0x7ed8cfd0
V 5792993.175 oracle[21534] Asm_loadExtents: loading 7680 extents for vol VOLBACKUP
V 5792993.175 oracle[21534] Asm_loadExtents: 0 extents copied, 452 exts this copy nextExtent=f2ebc1e0lx
V 5792993.175 oracle[21534] Asm_loadExtents: 452 extents copied, 512 exts this copy nextExtent=f2ebb000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 964 extents copied, 512 exts this copy nextExtent=f2eba000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 1476 extents copied, 512 exts this copy nextExtent=f2eb9000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 1988 extents copied, 512 exts this copy nextExtent=f2eb8000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 2500 extents copied, 512 exts this copy nextExtent=f2ebe000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 3012 extents copied, 512 exts this copy nextExtent=f2ebf000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 3524 extents copied, 512 exts this copy nextExtent=19b0f000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 4036 extents copied, 512 exts this copy nextExtent=19b0e000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 4548 extents copied, 512 exts this copy nextExtent=19b0d000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 5060 extents copied, 512 exts this copy nextExtent=19b0c000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 5572 extents copied, 512 exts this copy nextExtent=19b0b000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 6084 extents copied, 512 exts this copy nextExtent=19b0a000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 6596 extents copied, 512 exts this copy nextExtent=19b09000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 7108 extents copied, 512 exts this copy nextExtent=19b08000lx
V 5792993.175 oracle[21534] Asm_loadExtents: 7620 extents copied, 60 exts this copy nextExtent=953c3000lx
V 5792993.176 oracle[21534] Asm_loadExtents: count 7680 (61440 bytes), userAdrs 0x7ed8cfd0
V 5792993.176 oracle[21534] Asm_loadExtents: loading 7680 extents for vol VOLBACKUP
V 5792993.176 oracle[21534] Asm_loadExtents: 0 extents copied, 452 exts this copy nextExtent=953c31e0lx
V 5792993.176 oracle[21534] Asm_loadExtents: 452 extents copied, 512 exts this copy nextExtent=953c1000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 964 extents copied, 512 exts this copy nextExtent=953c6000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 1476 extents copied, 512 exts this copy nextExtent=953c2000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 1988 extents copied, 512 exts this copy nextExtent=953c0000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 2500 extents copied, 512 exts this copy nextExtent=953c5000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 3012 extents copied, 512 exts this copy nextExtent=953c7000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 3524 extents copied, 512 exts this copy nextExtent=1e3dc000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 4036 extents copied, 512 exts this copy nextExtent=1e3da000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 4548 extents copied, 512 exts this copy nextExtent=1e3dd000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 5060 extents copied, 512 exts this copy nextExtent=1e3d8000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 5572 extents copied, 512 exts this copy nextExtent=69683000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 6084 extents copied, 512 exts this copy nextExtent=69682000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 6596 extents copied, 512 exts this copy nextExtent=69680000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 7108 extents copied, 512 exts this copy nextExtent=69681000lx
V 5792993.176 oracle[21534] Asm_loadExtents: 7620 extents copied, 60 exts this copy nextExtent=69685000lx
V 5792993.177 oracle[21534] Asm_loadExtents: count 7680 (61440 bytes), userAdrs 0x7ed8cfd0

Changes

 

Cause

To view full details, sign in with your My Oracle Support account.

Don't have a My Oracle Support account? Click to get started!


In this Document
Symptoms
Changes
Cause
Solution


My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.