Example of Hot Copy Log Message
The longest message enabled by the hotcopy
logging category looks like:
Hot copy 731635bb-867c-4681-b78c-1de9ef662cd3 stage 1 45.129 sec during 2196 replic msgs, waited for node 3, thread sync 1.25 sec, 4321 archive reads (195MB, 4.3MB/sec, 7.0%) 22135 writes (885MB, 19.7MB/sec, 35.7%), 171+0 journal writes (255KB, 5.6KB/sec, rd 3.3% wr 5.4%) sequence 1..171
Note: Portions of this message are omitted when they do not apply or are zero.
The following table describes the breakdown of items within this hot copy log message:
Item | Description |
---|---|
Hot copy 731635bb-867c-4681-b78c-1de9ef662cd3 | The unique identifier for the hot copy. |
stage 1 | The stage or inter-stage. For more details see Hot Copy Stages. |
45.129 sec |
The time elapsed while performing this stage on this node. |
during 2196 replic msgs | The number of replication messages received while performing this stage; this is a measure of database write load. |
waited for node 3 | Identifies the node (in a multi-node hot copy) which was the last to complete this inter-stage. |
thread sync 1.25 sec | The time elapsed while waiting to synchronize with other threads. |
4321 archive reads | The number of files read from the archive. |
195MB | The total size of files read from the archive. |
4.3MB/sec | The number of bytes (for archive reads) read per second. |
7.0% | The percentage of time elapsed while waiting for archive reads. |
22135 writes | The number of archive files written to the copy |
885MB | The total size of files written to the copy. |
19.7MB/sec | The number of bytes written per second. |
35.7% | The percentage of time elapsed while waiting for writes. |
171+0 journal writes | The number of journal files copied; the first number is for current journal files, the second number relates to the journal files which were copied after they were reaped. |
255KB | The total size of journal files. |
5.6KB/sec | The number of bytes copied per second. |
rd 3.3% | The percentage of time elapsed while waiting for journal reads. |
wr 5.4% | The percentage of time elapsed while waiting for journal writes. |
sequence 1..171 | The range of journal file sequence numbers copied. |
lastJournalSequence 168 |
The journal position when no journal files were copied. Note: As per the note above, an item such as this may be omitted from the log message. |
The hot copy stages and inter-stages are described as follows:
Stage/inter-stage | Description |
---|---|
stage 0 | The startup stage. |
stage 0+ | The inter-stage which waits for other nodes to complete stage 0 |
stage 1 |
Where the archive and journal are copied in the background. |
stage 1+ | The inter-stage waiting for other nodes to complete stage 1. |
stage 2 | Where commits are delayed and the last portion of the journal is copied. |
stage 2+ | The inter-stage waiting for other nodes to complete stage 2. |
stage 3 | Where the copy is finalized and the journal is cleaned up. |
stage 3+ | The inter-stage waiting for other nodes to complete stage 3. |
Note: To identify a stage where a hot copy log message was generated, see the second item in the message.