- 설정 방법
- HANA Studio -> Administator -> Trace Configuration -> Database Trace
Component = savepoint 선택
- Trace Level = 'INFO' -> 'DEBUG'로 변경하여 ON
== Trace 미설정 상태
[620581]{-1}[-1/-1] 2019-07-12 08:49:07.030953 i Savepoint SavepointImpl.cpp(02995) : Savepoint current savepoint version: 11954, restart redo log position: 0xa90f2a482, next savepoint version: 11955, last snapshot SP version: 0
[620581]{-1}[-1/-1] 2019-07-12 08:54:07.054317 i Savepoint SavepointImpl.cpp(02995) : Savepoint current savepoint version: 11955, restart redo log position: 0xa90f32a42, next savepoint version: 11956, last snapshot SP version: 0
[620581]{-1}[-1/-1] 2019-07-12 08:59:07.070194 i Savepoint SavepointImpl.cpp(02995) : Savepoint current savepoint version: 11956, restart redo log position: 0xa90f3b1c2, next savepoint version: 11957, last snapshot SP version: 0
== Trace 설정 상태
[802758]{253286}[42/12463356] 2019-07-12 09:03:03.643929 i TraceContext TraceContext.cpp(01218) : UserName=SYSTEM, ApplicationUserName=citec, ApplicationName=HDBStudio, ApplicationSource=csns.admin.AdminConnection.openConnection(AdminConnection.java:99);csns.admin.trace.wizard.TraceEnginePage$1$1.call(TraceEnginePage.java:96);csns.admin.trace.wizard.TraceEnginePage$1$1.call(TraceEnginePage.java:1);java.util.concurrent.FutureTask.run(FutureTask.java:266);java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149);java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624);java.lang.Thread.run(Thread.java:808);, StatementHash=4b966a4d5596fe684ad914786de749bd, StatementExecutionID=844433528254402
[802758]{253286}[42/12463356] 2019-07-12 09:03:03.643899 i SQLSessionCmd Statement.cc(06273) : INI configuration is changed by 253286, client ip=10.34.41.144, client port=19684, query=alter system alter configuration ('indexserver.ini','SYSTEM') SET ('trace','savepoint')='debug' with reconfigure
[802757]{253286}[42/12463356] 2019-07-12 09:03:03.709180 i TraceContext TraceContext.cpp(01218) : UserName=SYSTEM, ApplicationUserName=citec, ApplicationName=HDBStudio, ApplicationSource=csns.admin.AdminConnection.openConnection(AdminConnection.java:99);csns.admin.trace.wizard.TraceEnginePage$1$1.call(TraceEnginePage.java:96);csns.admin.trace.wizard.TraceEnginePage$1$1.call(TraceEnginePage.java:1);java.util.concurrent.FutureTask.run(FutureTask.java:266);java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149);java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624);java.lang.Thread.run(Thread.java:808);, StatementHash=4b966a4d5596fe684ad914786de749bd, StatementExecutionID=844433528254402
[802757]{253286}[42/12463356] 2019-07-12 09:03:03.709176 i Basis Ltracer.cpp(01319) : Activating ltracer, 2048 bytes buffering, 448 pooling
[808267]{-1}[44/12463359] 2019-07-12 09:03:03.769419 i TraceContext TraceContext.cpp(01218) : UserName=
[808267]{-1}[44/12463359] 2019-07-12 09:03:03.769405 D Savepoint SavepointImpl.cpp(01901) : Lock savepoint @0x00007fe35c6ccbb0, lockMode=0, id=200
[808267]{-1}[44/12463359] 2019-07-12 09:03:03.769438 D Savepoint SavepointImpl.cpp(01531) : SavepointLock::setSharedLockOnCurrentContext(808267)
[808267]{-1}[44/12463359] 2019-07-12 09:03:03.769494 D Savepoint SavepointImpl.cpp(01933) : Unlock savepoint @0x00007fe35c6ccbb0
[808267]{-1}[44/12463359] 2019-07-12 09:03:03.769498 D Savepoint SavepointImpl.cpp(01546) : SavepointLock::unsetSharedLockOnCurrentContext(808267)
[808267]{-1}[-1/-1] 2019-07-12 09:03:03.769641 D Savepoint SavepointImpl.cpp(01901) : Lock savepoint @0x00007fe35c6ccbb0, lockMode=0, id=200
[808267]{-1}[-1/-1] 2019-07-12 09:03:03.769647 D Savepoint SavepointImpl.cpp(01531) : SavepointLock::setSharedLockOnCurrentContext(808267)
[808267]{-1}[-1/-1] 2019-07-12 09:03:03.769652 D Savepoint SavepointImpl.cpp(01933) : Unlock savepoint @0x00007fe35c6ccbb0
[808267]{-1}[-1/-1] 2019-07-12 09:03:03.769654 D Savepoint SavepointImpl.cpp(01546) : SavepointLock::unsetSharedLockOnCurrentContext(808267)
==
[619451]{-1}[-1/-1] 2019-07-12 09:03:03.814772 D Savepoint SavepointImpl.cpp(01901) : Lock savepoint @0x00007fe2bb24ffb0, lockMode=0, id=187
[619451]{-1}[-1/-1] 2019-07-12 09:03:03.814803 D Savepoint SavepointImpl.cpp(01531) : SavepointLock::setSharedLockOnCurrentContext(619451)
[619451]{-1}[-1/-1] 2019-07-12 09:03:03.814810 D Savepoint SavepointImpl.cpp(01933) : Unlock savepoint @0x00007fe2bb24ffb0
[619451]{-1}[-1/-1] 2019-07-12 09:03:03.814814 D Savepoint SavepointImpl.cpp(01546) : SavepointLock::unsetSharedLockOnCurrentContext(619451)
[619500]{-1}[-1/-1] 2019-07-12 09:03:03.834168 D Savepoint SavepointImpl.cpp(01901) : Lock savepoint @0x00007fe647d2f7b0, lockMode=0, id=142
[619500]{-1}[-1/-1] 2019-07-12 09:03:03.834198 D Savepoint SavepointImpl.cpp(01531) : SavepointLock::setSharedLockOnCurrentContext(619500)
[619500]{-1}[-1/-1] 2019-07-12 09:03:03.834208 D Savepoint SavepointImpl.cpp(01933) : Unlock savepoint @0x00007fe647d2f7b0
[619500]{-1}[-1/-1] 2019-07-12 09:03:03.834211 D Savepoint SavepointImpl.cpp(01546) : SavepointLock::unsetSharedLockOnCurrentContext(619500)
==
[619088]{-1}[-1/-1] 2019-07-12 09:03:03.840449 D Savepoint SavepointImpl.cpp(00865) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode()...
[619088]{-1}[-1/-1] 2019-07-12 09:03:03.840474 D Savepoint SavepointImpl.cpp(00957) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode(): m_RuntimedumpTimeout=0
[619088]{-1}[-1/-1] 2019-07-12 09:03:03.840478 D Savepoint SavepointImpl.cpp(00959) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode() done.
==
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861334 D Savepoint SavepointImpl.cpp(01901) : Lock savepoint @0x00007fe5742153e0, lockMode=0, id=0
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861349 D Savepoint SavepointImpl.cpp(01531) : SavepointLock::setSharedLockOnCurrentContext(832159)
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861351 D Savepoint SavepointImpl.cpp(01933) : Unlock savepoint @0x00007fe5742153e0
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861352 D Savepoint SavepointImpl.cpp(01546) : SavepointLock::unsetSharedLockOnCurrentContext(832159)
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861407 D Savepoint SavepointImpl.cpp(01901) : Lock savepoint @0x00007fe2bb24ffb0, lockMode=0, id=90
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861410 D Savepoint SavepointImpl.cpp(01531) : SavepointLock::setSharedLockOnCurrentContext(832159)
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861561 D Savepoint SavepointImpl.cpp(01933) : Unlock savepoint @0x00007fe2bb24ffb0
[832159]{253298}[44/12463360] 2019-07-12 09:03:03.861563 D Savepoint SavepointImpl.cpp(01546) : SavepointLock::unsetSharedLockOnCurrentContext(832159)
==
[619088]{-1}[-1/-1] 2019-07-12 09:03:04.840745 D Savepoint SavepointImpl.cpp(00865) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode()...
[619088]{-1}[-1/-1] 2019-07-12 09:03:04.840752 D Savepoint SavepointImpl.cpp(00957) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode(): m_RuntimedumpTimeout=0
[619088]{-1}[-1/-1] 2019-07-12 09:03:04.840757 D Savepoint SavepointImpl.cpp(00959) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode() done.
[619088]{-1}[-1/-1] 2019-07-12 09:03:05.841116 D Savepoint SavepointImpl.cpp(00865) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode()...
[619088]{-1}[-1/-1] 2019-07-12 09:03:05.841122 D Savepoint SavepointImpl.cpp(00957) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode(): m_RuntimedumpTimeout=0
[619088]{-1}[-1/-1] 2019-07-12 09:03:05.841126 D Savepoint SavepointImpl.cpp(00959) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode() done.
[619088]{-1}[-1/-1] 2019-07-12 09:03:06.841284 D Savepoint SavepointImpl.cpp(00865) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode()...
[619088]{-1}[-1/-1] 2019-07-12 09:03:06.841290 D Savepoint SavepointImpl.cpp(00957) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode(): m_RuntimedumpTimeout=0
[619088]{-1}[-1/-1] 2019-07-12 09:03:06.841294 D Savepoint SavepointImpl.cpp(00959) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode() done.
[619139]{-1}[-1/-1] 2019-07-12 09:03:07.140475 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=FINISHING, p=NonCriticalPhase
[619088]{-1}[-1/-1] 2019-07-12 09:03:07.841813 D Savepoint SavepointImpl.cpp(00865) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode()...
[619088]{-1}[-1/-1] 2019-07-12 09:03:07.841824 D Savepoint SavepointImpl.cpp(00957) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode(): m_RuntimedumpTimeout=0
[619088]{-1}[-1/-1] 2019-07-12 09:03:07.841829 D Savepoint SavepointImpl.cpp(00959) : RuntimedumpOnSavepointCriticalPhaseBlockedCallback::getExecutionMode() done.
=================================================================================================================================================================================
== Savepoint 시작
[802779]{253301}[40/-1] 2019-07-12 09:03:32.976033 i TraceContext TraceContext.cpp(01218) : UserName=SYSTEM, ApplicationUserName=otpadm, ApplicationName=hdbsql, StatementHash=5ca0537d8a072a7e19bcd0a309239c68, StatementExecutionID=844433528254460
[802779]{253301}[40/-1] 2019-07-12 09:03:32.976022 a Savepoint SavepointImpl.cpp(03491) : Enter DataAccess::SavepointVersion DataAccess::SavepointImpl::doSavepoint(bool, DataAccess::SavepointInitiation::Initiation, DataAccess::SavepointPurpose::Purpose, bool, DataAccess::SavepointVersion, bool, bool, const DataAccess::SavepointRedoLogInfo*)
Arg this = 0x00007fe6479c32c0
Arg hasLock = 0
Arg initiation = 2
Arg purpose = 0
Arg snapshot = 0
[802779]{253301}[40/-1] 2019-07-12 09:03:32.976073 d Savepoint SavepointImpl.cpp(03596) : SavepointImpl: locks acquired, start savepoint
[802779]{253301}[40/-1] 2019-07-12 09:03:32.976078 a Savepoint SavepointImpl.cpp(02157) : Enter void DataAccess::SavepointImpl::prepareSavepoint(DataAccess::SavepointState&)
Arg this = 0x00007fe6479c32c0
== PREPARE 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.976084 d Savepoint SavepointImpl.cpp(05618) : enterPhase(PREPARE)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.976089 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=PREPARE, p=StartSavepoint
[802779]{253301}[40/-1] 2019-07-12 09:03:32.980912 a Savepoint SavepointImpl.cpp(02157) : Exit void DataAccess::SavepointImpl::prepareSavepoint(DataAccess::SavepointState&)(4834usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.980921 a Savepoint SavepointImpl.cpp(02202) : Enter bool DataAccess::SavepointImpl::flushPages(DataAccess::SavepointState&, uint64_t)
Arg this = 0x00007fe6479c32c0
Arg restrictLoopCount = 18446744073709551615
== PAGEFLUSH 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.980930 d Savepoint SavepointImpl.cpp(05618) : enterPhase(PAGEFLUSH)
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.981070 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=PAGEFLUSH, p=NonCriticalPhase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.982276 d Savepoint SavepointImpl.cpp(02255) : SavepointImpl::flushPages: 169 Pages flushed in non-critical phase (1).
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.984330 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=PAGEFLUSH, p=NonCriticalPhase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.986433 d Savepoint SavepointImpl.cpp(02255) : SavepointImpl::flushPages: 4 Pages flushed in non-critical phase (2).
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.986587 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=PAGEFLUSH, p=NonCriticalPhase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.986816 d Savepoint SavepointImpl.cpp(02255) : SavepointImpl::flushPages: 0 Pages flushed in non-critical phase (3).
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.986959 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=PAGEFLUSH, p=NonCriticalPhase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987180 a Savepoint SavepointImpl.cpp(02202) : Exit bool DataAccess::SavepointImpl::flushPages(DataAccess::SavepointState&, uint64_t)(6259usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987186 a Savepoint SavepointImpl.cpp(02284) : Enter void DataAccess::SavepointImpl::preCriticalPhase(DataAccess::SavepointState&)
Arg this = 0x00007fe6479c32c0
== PRECRITICAL 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987191 d Savepoint SavepointImpl.cpp(05618) : enterPhase(PRECRITICAL)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987198 a Savepoint SavepointImpl.cpp(02284) : Exit void DataAccess::SavepointImpl::preCriticalPhase(DataAccess::SavepointState&)(12usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987202 a Savepoint SavepointImpl.cpp(02302) : Enter void DataAccess::SavepointImpl::enterCriticalPhase(DataAccess::SavepointState&)
Arg this = 0x00007fe6479c32c0
== ENTERCRITICAL_LOCK 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987206 d Savepoint SavepointImpl.cpp(05618) : enterPhase(ENTERCRITICAL_LOCK)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987221 d Savepoint SavepointImpl.cpp(02331) : SavepointImpl: in critical phase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.987225 d Savepoint SavepointImpl.cpp(05618) : enterPhase(ENTERCRITICAL_FLUSH)
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.987372 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=ENTERCRITICAL_FLUSH, p=NonCriticalPhase
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.987437 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=ENTERCRITICAL_FLUSH, p=StartCriticalPhase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991554 a Savepoint SavepointImpl.cpp(02302) : Exit void DataAccess::SavepointImpl::enterCriticalPhase(DataAccess::SavepointState&)(4352usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991559 a Savepoint SavepointImpl.cpp(02394) : Enter void DataAccess::SavepointImpl::processCriticalPhase(DataAccess::SavepointState&)
Arg this = 0x00007fe6479c32c0
== CRITICAL 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991564 d Savepoint SavepointImpl.cpp(05618) : enterPhase(CRITICAL)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991574 d Savepoint SavepointImpl.cpp(02403) : SavepointImpl::processCriticalPhase: asyncPrivateLogBufferFlush = true
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991577 a Savepoint SavepointImpl.cpp(01691) : Enter void DataAccess::SavepointPrivateLogBufferFlushThread::signal(DataAccess::PersistenceManager&)
Arg this = 0x00007fe6479c46e0
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991587 a Savepoint SavepointImpl.cpp(01691) : Exit void DataAccess::SavepointPrivateLogBufferFlushThread::signal(DataAccess::PersistenceManager&)(10usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991593 d Savepoint SavepointImpl.cpp(02426) : SavepointImpl::processCriticalPhase: 0 Pages flushed in critical phase.
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991600 a Savepoint SavepointImpl.cpp(03773) : Enter DataAccess::Savepoint::WriteRestartData DataAccess::SavepointImpl::writeRestartDataImpl(DataAccess::Savepoint::RestartKey, size_t, bool)
Arg this = 0x00007fe6479c32c0
Arg key = PersSpaces
Arg size = 128
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991609 a Savepoint SavepointImpl.cpp(03773) : Exit DataAccess::Savepoint::WriteRestartData DataAccess::SavepointImpl::writeRestartDataImpl(DataAccess::Savepoint::RestartKey, size_t, bool)(9usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991615 a Savepoint SavepointImpl.cpp(03773) : Enter DataAccess::Savepoint::WriteRestartData DataAccess::SavepointImpl::writeRestartDataImpl(DataAccess::Savepoint::RestartKey, size_t, bool)
Arg this = 0x00007fe6479c32c0
Arg key = PS_CONT_DIR
Arg size = 64
~~~
[619220]{-1}[-1/-1] 2019-07-12 09:03:32.991602 d Savepoint SavepointImpl.cpp(01735) : SavepointPrivateLogBufferFlushThread::woke up...
~~~
Arg key = PS_CONT_FILEIDMAP
~~~
[619220]{-1}[-1/-1] 2019-07-12 09:03:32.991634 d Savepoint SavepointImpl.cpp(01743) : SavepointPrivateLogBufferFlushThread::flushAllPrivateLogBufferInSavepoint()
~~~
Arg key = PS_CONT_NAMEDIR
~~~
Arg key = PS_CONT_UNDODIR
~~~
Arg key = PS_CONT_REMOPS
~~~
Arg key = PS_CONT_TIDCIDMAP
~~~
Arg key = PS_CONT_LOBDIR
~~~
Arg key = PS_CONT_DRLOADEDTABLE
~~~
Arg key = PS_CONT_REMOTEOPERATIONS
~~~
Arg key = PS_CONT_MIDSIZELOBDIR
~~~
Arg key = PS_CONT_LOBFILEIDMAP
~~~
Arg key = TransGen
~~~
Arg key = InDoubtVol
~~~
Arg key = RecoveryConfig
~~~
Arg key = PointInTimeStartupRestartData
~~~
Arg key = SystemReplicationRestartData
~~~
Arg key = SystemReplicationSnapshots
~~~
Arg key = GuidHistory
~~~
Arg key = BackupInfoRestartData
~~~
Arg key = HDB_VERSION
~~~
Arg key = LiveCacheData
~~~
Arg key = LiveCacheData
~~~
Arg key = ptime::RestartInfoInPersistency
~~~
Arg key = ptime::CheckpointMgr::segPinInfo
[802779]{253301}[40/-1] 2019-07-12 09:03:32.991938 d Savepoint SavepointImpl.cpp(02447) : SavepointImpl::processCriticalPhase: wait for async PrivateLogBufferFlush...
~~~
[619220]{-1}[-1/-1] 2019-07-12 09:03:32.992121 d Savepoint SavepointImpl.cpp(01733) : SavepointPrivateLogBufferFlushThread::wait()...
~~~
[802779]{253301}[40/-1] 2019-07-12 09:03:32.992134 d Savepoint SavepointImpl.cpp(02450) : SavepointImpl::processCriticalPhase: wait for async PrivateLogBufferFlush took 0 usec
[802779]{253301}[40/-1] 2019-07-12 09:03:32.992146 d Savepoint SavepointImpl.cpp(02519) : store restart redo log position 0xa90f45802 for spv 11957, timestamp=1562889808224827
~~~
Arg key = RedoLogPos
~~~
Arg key = LogSegmentInfo
~~~
Arg key = TransactionInfo
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.992339 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=CRITICAL, p=CriticalPhase
[619139]{-1}[-1/-1] 2019-07-12 09:03:32.992451 d Savepoint SavepointImpl.cpp(05661) : transferStatisticsData: m_Phase=CRITICAL, p=EndCriticalPhase
== EXITCRITICAL 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996433 d Savepoint SavepointImpl.cpp(05618) : enterPhase(EXITCRITICAL)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996437 d Savepoint SavepointImpl.cpp(02612) : SavepointImpl: end critical phase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996448 d Savepoint SavepointImpl.cpp(02619) : SavepointImpl: after critical phase
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996452 a Savepoint SavepointImpl.cpp(02605) : Exit void DataAccess::SavepointImpl::exitCriticalPhase(DataAccess::SavepointState&)(24usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996455 a Savepoint SavepointImpl.cpp(02624) : Enter void DataAccess::SavepointImpl::enterPostCriticalPhase(DataAccess::SavepointState&)
Arg this = 0x00007fe6479c32c0
== POSTCRITICAL 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996459 d Savepoint SavepointImpl.cpp(05618) : enterPhase(POSTCRITICAL)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996600 a Savepoint SavepointImpl.cpp(03773) : Enter DataAccess::Savepoint::WriteRestartData DataAccess::SavepointImpl::writeRestartDataImpl(DataAccess::Savepoint::RestartKey, size_t, bool)
Arg this = 0x00007fe6479c32c0
Arg key = catalogmaster
~~~
Arg key = checkOldDeltaLogEntriesDone
~~~
Arg key = l2dmigration
~~~
Arg key = landscapeid
~~~
Arg key = servicetype
~~~
Arg key = transactionmaster
~~~
Arg key = volumeid
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996693 a Savepoint SavepointImpl.cpp(02624) : Exit void DataAccess::SavepointImpl::enterPostCriticalPhase(DataAccess::SavepointState&)(238usec)
== FINISH 단계
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996701 d Savepoint SavepointImpl.cpp(05618) : enterPhase(FINISHING)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996704 a Savepoint SavepointImpl.cpp(03773) : Enter DataAccess::Savepoint::WriteRestartData DataAccess::SavepointImpl::writeRestartDataImpl(DataAccess::Savepoint::RestartKey, size_t, bool)
Arg this = 0x00007fe6479c32c0
Arg key = SnapshotDirectory
Arg size = 0
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996711 a Savepoint SavepointImpl.cpp(03773) : Exit DataAccess::Savepoint::WriteRestartData DataAccess::SavepointImpl::writeRestartDataImpl(DataAccess::Savepoint::RestartKey, size_t, bool)(7usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.996896 d Savepoint SavepointImpl.cpp(02833) : RestartPage written : 0x60000005beeeP [0x5beee000,4k,0]
[802779]{253301}[40/-1] 2019-07-12 09:03:32.997280 d Savepoint SavepointImpl.cpp(02957) : SavepointImpl: AnchorPage written
[802779]{253301}[40/-1] 2019-07-12 09:03:32.997302 d Savepoint SavepointImpl.cpp(02968) : setPageFreeAfterSavepoint for RestartPage : 0x60000005bcbaP [0x5bcba000,4k,0] version=11958, prevVersion=0
[802779]{253301}[40/-1] 2019-07-12 09:03:32.997460 i Savepoint SavepointImpl.cpp(02995) : Savepoint current savepoint version: 11957, restart redo log position: 0xa90f45802, next savepoint version: 11958, last snapshot SP version: 0
[802779]{253301}[40/-1] 2019-07-12 09:03:32.997470 d Savepoint SavepointImpl.cpp(03023) : SavepointStatistics: M_SAVEPOINT_STATISTICS[384184]:PersistenceManager/Savepoint(SAVEPOINTS=2274,SAVEPOINTS_IN_REPLAY=0,START_TIME=2019-07-12 09:03:32.976069,END_TIME=<none>,BLOCKING_PHASE_START_TIME=2019-07-12 09:03:32.987209,BLOCKING_PHASE_END_TIME=2019-07-12 09:03:32.996436,CRITICAL_PHASE_START_TIME=2019-07-12 09:03:32.991566,INITIATION=TRIGGERED_TIMEBASED,PURPOSE=NORMAL,STATE=FINISHING,VERSION=11957,REQUESTED_FREQUENCY=300s,LAST_FREQUENCY=0s,MAX_FREQUENCY=373s,MIN_FREQUENCY=265s,SUM_FREQUENCY=681992s,AVG_FREQUENCY=300s,LAST_DURATION=21390us,MAX_DURATION=73871377us,MIN_DURATION=19082us,SUM_DURATION=195404757us,AVG_DURATION=85967us,CALLBACK_EXECUTED=,LAST_PREPARE_TIME=4823us,MAX_PREPARE_TIME=8448us,MIN_PREPARE_TIME=3571us,SUM_PREPARE_TIME=11698965us,AVG_PREPARE_TIME=5144us,LAST_PREPARE_CALLBACK_TIME=216us,MAX_PREPARE_CALLBACK_TIME=2339us,MIN_PREPARE_CALLBACK_TIME=62us,SUM_PREPARE_CALLBACK_TIME=715720us,AVG_PREPARE_CALLBACK_TIME=314us,LAST_NON_CRITICAL_PHASE_FLUSH_RETRY_COUNT=3,MAX_NON_CRITICAL_PHASE_FLUSH_RETRY_COUNT=3,MIN_NON_CRITICAL_PHASE_FLUSH_RETRY_COUNT=2,SUM_NON_CRITICAL_PHASE_FLUSH_RETRY_COUNT=6451,AVG_NON_CRITICAL_PHASE_FLUSH_RETRY_COUNT=2,LAST_NON_CRITICAL_PHASE_TIME=6us,MAX_NON_CRITICAL_PHASE_TIME=30us,MIN_NON_CRITICAL_PHASE_TIME=1us,SUM_NON_CRITICAL_PHASE_TIME=9224us,AVG_NON_CRITICAL_PHASE_TIME=4us,LAST_NON_CRITICAL_PHASE_PREPARE_FLUSH_TIME=1734us,MAX_NON_CRITICAL_PHASE_PREPARE_FLUSH_TIME=29218us,MIN_NON_CRITICAL_PHASE_PREPARE_FLUSH_TIME=104us,SUM_NON_CRITICAL_PHASE_PREPARE_FLUSH_TIME=3812383us,AVG_NON_CRITICAL_PHASE_PREPARE_FLUSH_TIME=1676us,LAST_NON_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=1us,MAX_NON_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=28us,MIN_NON_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=0us,SUM_NON_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=3308us,AVG_NON_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=1us,LAST_NON_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=0us,MAX_NON_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=1us,MIN_NON_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=0us,SUM_NON_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=491us,AVG_NON_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=0us,LAST_NON_CRITICAL_FLUSH_WAIT_TIME=4505us,MAX_NON_CRITICAL_FLUSH_WAIT_TIME=73854686us,MIN_NON_CRITICAL_FLUSH_WAIT_TIME=2728us,SUM_NON_CRITICAL_FLUSH_WAIT_TIME=147663709us,AVG_NON_CRITICAL_FLUSH_WAIT_TIME=64935us,LAST_NON_CRITICAL_PHASE_CALLBACK_TIME=3us,MAX_NON_CRITICAL_PHASE_CALLBACK_TIME=29us,MIN_NON_CRITICAL_PHASE_CALLBACK_TIME=1us,SUM_NON_CRITICAL_PHASE_CALLBACK_TIME=7791us,AVG_NON_CRITICAL_PHASE_CALLBACK_TIME=3us,LAST_NON_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,MAX_NON_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=8276us,MIN_NON_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,SUM_NON_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,AVG_NON_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,LAST_NON_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,MAX_NON_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=13299us,MIN_NON_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,SUM_NON_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,AVG_NON_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,LAST_NON_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,MAX_NON_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,MIN_NON_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,SUM_NON_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,AVG_NON_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,LAST_NON_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,MAX_NON_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,MIN_NON_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,SUM_NON_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,AVG_NON_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,LAST_NON_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,MAX_NON_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=8276us,MIN_NON_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,SUM_NON_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,AVG_NON_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,LAST_NON_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,MAX_NON_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=927us,MIN_NON_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=5us,SUM_NON_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,AVG_NON_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,LAST_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,MAX_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,MIN_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,SUM_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,AVG_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,LAST_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,MAX_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,MIN_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,SUM_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,AVG_NON_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,LAST_NON_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,MAX_NON_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=1592us,MIN_NON_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,SUM_NON_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,AVG_NON_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,LAST_NON_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,MAX_NON_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=165us,MIN_NON_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,SUM_NON_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,AVG_NON_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,LAST_NON_CRITICAL_PHASE_FLUSHED_CONV_PAGES=12,MAX_NON_CRITICAL_PHASE_FLUSHED_CONV_PAGES=39,MIN_NON_CRITICAL_PHASE_FLUSHED_CONV_PAGES=0,SUM_NON_CRITICAL_PHASE_FLUSHED_CONV_PAGES=14325,AVG_NON_CRITICAL_PHASE_FLUSHED_CONV_PAGES=0.006,LAST_NON_CRITICAL_PHASE_FLUSHED_PAGES=123,MAX_NON_CRITICAL_PHASE_FLUSHED_PAGES=9841,MIN_NON_CRITICAL_PHASE_FLUSHED_PAGES=0,SUM_NON_CRITICAL_PHASE_FLUSHED_PAGES=550812,AVG_NON_CRITICAL_PHASE_FLUSHED_PAGES=0.242,LAST_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=2,MAX_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=4,MIN_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=2,SUM_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=4568,AVG_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=0.002,LAST_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=40,MAX_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=186,MIN_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=2,SUM_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=68979,AVG_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=0.030,LAST_NON_CRITICAL_PHASE_FLUSHED_CONV_SIZE=3145728B,MAX_NON_CRITICAL_PHASE_FLUSHED_CONV_SIZE=10223616B,MIN_NON_CRITICAL_PHASE_FLUSHED_CONV_SIZE=0B,SUM_NON_CRITICAL_PHASE_FLUSHED_CONV_SIZE=3755212800B,AVG_NON_CRITICAL_PHASE_FLUSHED_CONV_SIZE=1651368B,LAST_NON_CRITICAL_PHASE_FLUSHED_SIZE=6365184B,MAX_NON_CRITICAL_PHASE_FLUSHED_SIZE=1548701696B,MIN_NON_CRITICAL_PHASE_FLUSHED_SIZE=0B,SUM_NON_CRITICAL_PHASE_FLUSHED_SIZE=28240429056B,AVG_NON_CRITICAL_PHASE_FLUSHED_SIZE=12418834B,LAST_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=524288B,MAX_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=1048576B,MIN_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=524288B,SUM_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=1197473792B,AVG_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=526593B,LAST_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=655360B,MAX_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=3047424B,MIN_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=32768B,SUM_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=1130151936B,AVG_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=496988B,LAST_NON_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=65536B,MAX_NON_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=262144B,MIN_NON_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=0B,SUM_NON_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=153747456B,AVG_NON_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=67611B,LAST_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=16384B,MAX_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=65536B,MIN_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=16384B,SUM_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=37945344B,AVG_NON_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=16686B,LAST_ENTER_CRITICAL_PHASE_TIME=0us,MAX_ENTER_CRITICAL_PHASE_TIME=0us,MIN_ENTER_CRITICAL_PHASE_TIME=0us,SUM_ENTER_CRITICAL_PHASE_TIME=0us,AVG_ENTER_CRITICAL_PHASE_TIME=0us,LAST_ENTER_CRITICAL_PHASE_LOCK_WAIT_TIME=11us,MAX_ENTER_CRITICAL_PHASE_LOCK_WAIT_TIME=159us,MIN_ENTER_CRITICAL_PHASE_LOCK_WAIT_TIME=4us,SUM_ENTER_CRITICAL_PHASE_LOCK_WAIT_TIME=24555us,AVG_ENTER_CRITICAL_PHASE_LOCK_WAIT_TIME=10us,LAST_ENTER_CRITICAL_PHASE_QUEUE_PROCESSED_WAIT_TIME=0us,MAX_ENTER_CRITICAL_PHASE_QUEUE_PROCESSED_WAIT_TIME=0us,MIN_ENTER_CRITICAL_PHASE_QUEUE_PROCESSED_WAIT_TIME=0us,SUM_ENTER_CRITICAL_PHASE_QUEUE_PROCESSED_WAIT_TIME=0us,AVG_ENTER_CRITICAL_PHASE_QUEUE_PROCESSED_WAIT_TIME=0us,LAST_ENTER_CRITICAL_PHASE_IO_WAIT_TIME=0us,MAX_ENTER_CRITICAL_PHASE_IO_WAIT_TIME=0us,MIN_ENTER_CRITICAL_PHASE_IO_WAIT_TIME=0us,SUM_ENTER_CRITICAL_PHASE_IO_WAIT_TIME=0us,AVG_ENTER_CRITICAL_PHASE_IO_WAIT_TIME=0us,LAST_CRITICAL_PHASE_DURATION=4854us,MAX_CRITICAL_PHASE_DURATION=26303us,MIN_CRITICAL_PHASE_DURATION=0us,SUM_CRITICAL_PHASE_DURATION=13731973us,AVG_CRITICAL_PHASE_DURATION=3019us,LAST_CRITICAL_PHASE_PREPARE_FLUSH_TIME=2us,MAX_CRITICAL_PHASE_PREPARE_FLUSH_TIME=1587us,MIN_CRITICAL_PHASE_PREPARE_FLUSH_TIME=1us,SUM_CRITICAL_PHASE_PREPARE_FLUSH_TIME=46254us,AVG_CRITICAL_PHASE_PREPARE_FLUSH_TIME=10us,LAST_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=1us,MAX_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=28us,MIN_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=0us,SUM_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=6617us,AVG_CRITICAL_PHASE_PREPARE_FLUSH_LOGICAL_TIME=1us,LAST_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=0us,MAX_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=1us,MIN_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=0us,SUM_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=982us,AVG_CRITICAL_PHASE_PREPARE_FLUSH_ROWSTORE_TIME=0us,LAST_CRITICAL_PHASE_CALLBACK_TIME=343us,MAX_CRITICAL_PHASE_CALLBACK_TIME=1430us,MIN_CRITICAL_PHASE_CALLBACK_TIME=79us,SUM_CRITICAL_PHASE_CALLBACK_TIME=505250us,AVG_CRITICAL_PHASE_CALLBACK_TIME=111us,LAST_CRITICAL_PHASE_LOG_BUFFER_FLUSH_TIME=196us,MAX_CRITICAL_PHASE_LOG_BUFFER_FLUSH_TIME=2229us,MIN_CRITICAL_PHASE_LOG_BUFFER_FLUSH_TIME=0us,SUM_CRITICAL_PHASE_LOG_BUFFER_FLUSH_TIME=2988563us,AVG_CRITICAL_PHASE_LOG_BUFFER_FLUSH_TIME=657us,LAST_CRITICAL_PHASE_FLUSH_WAIT_TIME=4221us,MAX_CRITICAL_PHASE_FLUSH_WAIT_TIME=24936us,MIN_CRITICAL_PHASE_FLUSH_WAIT_TIME=0us,SUM_CRITICAL_PHASE_FLUSH_WAIT_TIME=11869698us,AVG_CRITICAL_PHASE_FLUSH_WAIT_TIME=2609us,LAST_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,MAX_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=9399us,MIN_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,SUM_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,AVG_CRITICAL_PHASE_GET_PAGES_FOR_FLUSH_TIME=0us,LAST_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,MAX_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=10122us,MIN_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,SUM_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,AVG_CRITICAL_PHASE_ASSEMBLE_PAGES_TIME=0us,LAST_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,MAX_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,MIN_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,SUM_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,AVG_CRITICAL_PHASE_ENCRYPT_PAGES_TIME=0us,LAST_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,MAX_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,MIN_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,SUM_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,AVG_CRITICAL_PHASE_ENCRYPT_CONVERTER_PAGES_TIME=0us,LAST_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,MAX_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=9399us,MIN_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,SUM_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,AVG_CRITICAL_PHASE_GET_ROWSTORE_PAGES_FOR_FLUSH_TIME=0us,LAST_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,MAX_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=73us,MIN_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,SUM_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,AVG_CRITICAL_PHASE_ASSEMBLE_ROWSTORE_PAGES_TIME=0us,LAST_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,MAX_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,MIN_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,SUM_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,AVG_CRITICAL_PHASE_ENCRYPT_ROWSTORE_PAGES_TIME=0us,LAST_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,MAX_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,MIN_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,SUM_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,AVG_CRITICAL_PHASE_ENCRYPT_ROWSTORE_CONVERTER_PAGES_TIME=0us,LAST_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,MAX_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=54us,MIN_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,SUM_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,AVG_CRITICAL_PHASE_TRIGGER_PAGES_WRITE_TIME=0us,LAST_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,MAX_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=1us,MIN_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,SUM_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,AVG_CRITICAL_PHASE_TRIGGER_ROWSTORE_PAGES_WRITE_TIME=0us,LAST_CRITICAL_PHASE_FLUSHED_CONV_PAGES=0,MAX_CRITICAL_PHASE_FLUSHED_CONV_PAGES=7,MIN_CRITICAL_PHASE_FLUSHED_CONV_PAGES=0,SUM_CRITICAL_PHASE_FLUSHED_CONV_PAGES=112,AVG_CRITICAL_PHASE_FLUSHED_CONV_PAGES=0.000,LAST_CRITICAL_PHASE_FLUSHED_PAGES=0,MAX_CRITICAL_PHASE_FLUSHED_PAGES=164,MIN_CRITICAL_PHASE_FLUSHED_PAGES=0,SUM_CRITICAL_PHASE_FLUSHED_PAGES=2020,AVG_CRITICAL_PHASE_FLUSHED_PAGES=0.000,LAST_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=0,MAX_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=0,MIN_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=0,SUM_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=0,AVG_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_PAGES=0.000,LAST_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=0,MAX_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=1,MIN_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=0,SUM_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=2,AVG_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGES=0.000,LAST_CRITICAL_PHASE_FLUSHED_CONV_SIZE=0B,MAX_CRITICAL_PHASE_FLUSHED_CONV_SIZE=1835008B,MIN_CRITICAL_PHASE_FLUSHED_CONV_SIZE=0B,SUM_CRITICAL_PHASE_FLUSHED_CONV_SIZE=29360128B,AVG_CRITICAL_PHASE_FLUSHED_CONV_SIZE=6455B,LAST_CRITICAL_PHASE_FLUSHED_SIZE=0B,MAX_CRITICAL_PHASE_FLUSHED_SIZE=45690880B,MIN_CRITICAL_PHASE_FLUSHED_SIZE=0B,SUM_CRITICAL_PHASE_FLUSHED_SIZE=502988800B,AVG_CRITICAL_PHASE_FLUSHED_SIZE=110595B,LAST_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=0B,MAX_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=0B,MIN_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=0B,SUM_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=0B,AVG_CRITICAL_PHASE_FLUSHED_ROWSTORE_CONV_SIZE=0B,LAST_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=0B,MAX_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=16384B,MIN_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=0B,SUM_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=32768B,AVG_CRITICAL_PHASE_FLUSHED_ROWSTORE_SIZE=7B,LAST_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=0B,MAX_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=0B,MIN_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=0B,SUM_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=0B,AVG_CRITICAL_PHASE_FLUSHED_PAGECHUNK_SIZE=0B,LAST_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=0B,MAX_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=0B,MIN_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=0B,SUM_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=0B,AVG_CRITICAL_PHASE_FLUSHED_ROWSTORE_PAGECHUNK_SIZE=0B,LAST_POST_CRITICAL_PHASE_CALLBACK_TIME=136us,MAX_POST_CRITICAL_PHASE_CALLBACK_TIME=40679us,MIN_POST_CRITICAL_PHASE_CALLBACK_TIME=101us,SUM_POST_CRITICAL_PHASE_CALLBACK_TIME=2203457us,AVG_POST_CRITICAL_PHASE_CALLBACK_TIME=484us,LAST_FINAL_FLUSH_WAIT_TIME=377us,MAX_FINAL_FLUSH_WAIT_TIME=54843us,MIN_FINAL_FLUSH_WAIT_TIME=295us,SUM_FINAL_FLUSH_WAIT_TIME=2401747us,AVG_FINAL_FLUSH_WAIT_TIME=1056us,LAST_ANCHOR_WRITE_TIME=217us,MAX_ANCHOR_WRITE_TIME=8602us,MIN_ANCHOR_WRITE_TIME=112us,SUM_ANCHOR_WRITE_TIME=808360us,AVG_ANCHOR_WRITE_TIME=355us,LAST_FREEING_PAGES_TIME=57us,MAX_FREEING_PAGES_TIME=698us,MIN_FREEING_PAGES_TIME=17us,SUM_FREEING_PAGES_TIME=107210us,AVG_FREEING_PAGES_TIME=47us,LAST_DONE_CB_TIME=83us,MAX_DONE_CB_TIME=7012us,MIN_DONE_CB_TIME=21us,SUM_DONE_CB_TIME=125926us,AVG_DONE_CB_TIME=55us,LAST_RTT_SIZE=0,LAST_RTT_PRERESTART_SIZE=0,LAST_RTT_ONLINE_SIZE=0,LAST_RTT_INDOUBT_SIZE=0,REDO_LOG_POSITION=45381605378,LAST_COMMIT_TIME=2019-07-12 09:03:28.224827,BLOCKED_SAVEPOINT_RUNTIME_DUMPS_WRITTEN=0,BLOCKED_SAVEPOINT_RUNTIME_DUMPS_SKIPPED=0)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.997895 a Savepoint SavepointImpl.cpp(02728) : Exit void DataAccess::SavepointImpl::finishSavepoint(DataAccess::SavepointState&)(1199usec)
[802779]{253301}[40/-1] 2019-07-12 09:03:32.997908 a Savepoint SavepointImpl.cpp(03491) : Exit DataAccess::SavepointVersion DataAccess::SavepointImpl::doSavepoint(bool, DataAccess::SavepointInitiation::Initiation, DataAccess::SavepointPurpose::Purpose, bool, DataAccess::SavepointVersion, bool, bool, const DataAccess::SavepointRedoLogInfo*)(21886usec)