/var/log/veeam/Backup/BackupJob/Session_20190113_050001_\{8ad64c4a-ccf8-4664-a1d2-51e90b927548\}/Job.log
Code: Select all
[13.01.2019 05:00:21] <140460756543232> lpbcore| Accurate disk size: 375809638400
[13.01.2019 05:00:21] <140460756543232> lpbcore| Session progress: 0%; processed size: 1048576 processing speed: 77328 bottleneck stats: 98/11/0/0
[13.01.2019 05:00:21] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1 MB at 428.5 KB/s (0%).
[13.01.2019 05:00:26] <140460756543232> lpbcore| Session progress: 0%; processed size: 224395264 processing speed: 12515073 bottleneck stats: 89/14/0/11
[13.01.2019 05:00:26] <140460756543232> vmb | [SessionLog][processing] Backing up sda 214 MB at 31.7 MB/s (0%).
[13.01.2019 05:00:27] <140460756543232> lpbcore| Session progress: 0%; processed size: 242221056 processing speed: 12855379 bottleneck stats: 89/14/0/14
[13.01.2019 05:00:27] <140460756543232> vmb | [SessionLog][processing] Backing up sda 231 MB at 30.1 MB/s (0%).
[13.01.2019 05:00:27] <140460756543232> lpbcore| Session progress: 0%; processed size: 248512512 processing speed: 12992759 bottleneck stats: 89/14/0/14
[13.01.2019 05:00:27] <140460756543232> vmb | [SessionLog][processing] Backing up sda 237 MB at 29.8 MB/s (0%).
[13.01.2019 05:00:27] <140460756543232> lpbcore| Session progress: 0%; processed size: 251658240 processing speed: 13088793 bottleneck stats: 89/14/0/14
[13.01.2019 05:00:27] <140460756543232> vmb | [SessionLog][processing] Backing up sda 240 MB at 29.8 MB/s (0%).
[13.01.2019 05:00:35] <140460756543232> lpbcore| Session progress: 0%; processed size: 513802240 processing speed: 18897430 bottleneck stats: 89/17/0/14
[13.01.2019 05:00:35] <140460756543232> vmb | [SessionLog][processing] Backing up sda 490 MB at 30.6 MB/s (0%).
[13.01.2019 05:00:36] <140460756543232> lpbcore| Session progress: 1%; processed size: 584056832 processing speed: 20366734 bottleneck stats: 89/19/0/17
[13.01.2019 05:00:36] <140460756543232> vmb | [SessionLog][processing] Backing up sda 557 MB at 31.8 MB/s (1%).
[13.01.2019 05:00:36] <140460756543232> lpbcore| Session progress: 1%; processed size: 585105408 processing speed: 20396186 bottleneck stats: 89/19/0/17
[13.01.2019 05:00:36] <140460756543232> vmb | [SessionLog][processing] Backing up sda 558 MB at 31.9 MB/s (1%).
[13.01.2019 05:00:36] <140460756543232> lpbcore| Session progress: 1%; processed size: 587202560 processing speed: 20439366 bottleneck stats: 89/19/0/17
[13.01.2019 05:00:36] <140460756543232> vmb | [SessionLog][processing] Backing up sda 560 MB at 31.9 MB/s (1%).
[13.01.2019 05:00:43] <140460756543232> lpbcore| Session progress: 1%; processed size: 706740224 processing speed: 20329073 bottleneck stats: 89/22/0/19
[13.01.2019 05:00:43] <140460756543232> vmb | [SessionLog][processing] Backing up sda 674 MB at 28.6 MB/s (1%).
[13.01.2019 05:00:44] <140460756543232> lpbcore| Session progress: 1%; processed size: 737148928 processing speed: 20132979 bottleneck stats: 89/22/0/22
[13.01.2019 05:00:44] <140460756543232> vmb | [SessionLog][processing] Backing up sda 703 MB at 27.6 MB/s (1%).
[13.01.2019 05:00:48] <140460756543232> lpbcore| Session progress: 1%; processed size: 810549248 processing speed: 20136368 bottleneck stats: 89/24/0/22
[13.01.2019 05:00:48] <140460756543232> vmb | [SessionLog][processing] Backing up sda 773 MB at 26.6 MB/s (1%).
[13.01.2019 05:00:55] <140460756543232> lpbcore| Session progress: 1%; processed size: 926941184 processing speed: 19642746 bottleneck stats: 89/26/0/24
[13.01.2019 05:00:55] <140460756543232> vmb | [SessionLog][processing] Backing up sda 884 MB at 24.5 MB/s (1%).
[13.01.2019 05:01:03] <140460756543232> lpbcore| Session progress: 2%; processed size: 1142947840 processing speed: 20855951 bottleneck stats: 89/34/0/26
[13.01.2019 05:01:03] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.1 GB at 25 MB/s (2%).
[13.01.2019 05:01:10] <140460756543232> lpbcore| Session progress: 2%; processed size: 1282408448 processing speed: 20730818 bottleneck stats: 89/35/0/34
[13.01.2019 05:01:10] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.2 GB at 24.1 MB/s (2%).
[13.01.2019 05:01:12] <140460756543232> lpbcore| Session progress: 2%; processed size: 1336934400 processing speed: 20864497 bottleneck stats: 89/36/0/35
[13.01.2019 05:01:12] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.2 GB at 24.1 MB/s (2%).
[13.01.2019 05:01:16] <140460756543232> lpbcore| Session progress: 2%; processed size: 1432354816 processing speed: 21068688 bottleneck stats: 89/37/0/36
[13.01.2019 05:01:16] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.3 GB at 24 MB/s (2%).
[13.01.2019 05:01:17] <140460756543232> lpbcore| Session progress: 2%; processed size: 1462763520 processing speed: 21039086 bottleneck stats: 89/37/0/37
[13.01.2019 05:01:17] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.4 GB at 23.9 MB/s (2%).
[13.01.2019 05:01:21] <140460756543232> lpbcore| Session progress: 2%; processed size: 1541406720 processing speed: 21178696 bottleneck stats: 89/38/0/37
[13.01.2019 05:01:21] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.4 GB at 23.9 MB/s (2%).
[13.01.2019 05:01:21] <140460756543232> lpbcore| Session progress: 2%; processed size: 1547698176 processing speed: 21166261 bottleneck stats: 89/38/0/37
[13.01.2019 05:01:21] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.4 GB at 23.8 MB/s (2%).
[13.01.2019 05:01:24] <140460756543232> lpbcore| Session progress: 3%; processed size: 1607467008 processing speed: 20971520 bottleneck stats: 89/38/0/38
[13.01.2019 05:01:24] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.5 GB at 23.4 MB/s (3%).
[13.01.2019 05:01:27] <140460756543232> lpbcore| Session progress: 3%; processed size: 1677721600 processing speed: 21114305 bottleneck stats: 89/39/0/38
[13.01.2019 05:01:27] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.6 GB at 23.4 MB/s (3%).
[13.01.2019 05:01:32] <140460756543232> lpbcore| Session progress: 3%; processed size: 1763704832 processing speed: 21053129 bottleneck stats: 89/40/0/39
[13.01.2019 05:01:32] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.6 GB at 23.2 MB/s (3%).
[13.01.2019 05:01:32] <140460756543232> lpbcore| Session progress: 3%; processed size: 1788870656 processing speed: 21147042 bottleneck stats: 89/40/0/40
[13.01.2019 05:01:32] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.7 GB at 23.2 MB/s (3%).
[13.01.2019 05:01:33] <140460756543232> lpbcore| Session progress: 3%; processed size: 1802502144 processing speed: 21153149 bottleneck stats: 89/40/0/40
[13.01.2019 05:01:33] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.7 GB at 23.2 MB/s (3%).
[13.01.2019 05:01:33] <140460756543232> lpbcore| Session progress: 3%; processed size: 1806696448 processing speed: 21148762 bottleneck stats: 89/40/0/40
[13.01.2019 05:01:33] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.7 GB at 23.2 MB/s (3%).
[13.01.2019 05:01:33] <140460756543232> lpbcore| Session progress: 3%; processed size: 1809842176 processing speed: 21163041 bottleneck stats: 89/40/0/40
[13.01.2019 05:01:33] <140460756543232> vmb | [SessionLog][processing] Backing up sda 1.7 GB at 23.2 MB/s (3%).
[13.01.2019 05:01:37] <140460756543232> lpbcore| Session progress: 8%; processed size: 4490002432 processing speed: 50502805 bottleneck stats: 86/53/0/40
[13.01.2019 05:01:37] <140460756543232> vmb | [SessionLog][processing] Backing up sda 4.2 GB at 55.1 MB/s (8%).
[13.01.2019 05:01:42] <140460756543232> lpbcore| Session progress: 15%; processed size: 8248098816 processing speed: 87933760 bottleneck stats: 81/65/0/40
[13.01.2019 05:01:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 7.7 GB at 95.2 MB/s (15%).
[13.01.2019 05:01:46] <140460756543232> lpbcore| Session progress: 22%; processed size: 12006195200 processing speed: 121738288 bottleneck stats: 77/72/0/72
[13.01.2019 05:01:46] <140460756543232> vmb | [SessionLog][processing] Backing up sda 11.2 GB at 130.9 MB/s (22%).
[13.01.2019 05:01:51] <140460756543232> lpbcore| Session progress: 29%; processed size: 15764291584 processing speed: 152425394 bottleneck stats: 73/82/0/72
[13.01.2019 05:01:51] <140460756543232> vmb | [SessionLog][processing] Backing up sda 14.7 GB at 163 MB/s (29%).
[13.01.2019 05:01:56] <140460756543232> lpbcore| Session progress: 36%; processed size: 19522387968 processing speed: 180420386 bottleneck stats: 70/91/0/72
[13.01.2019 05:01:56] <140460756543232> vmb | [SessionLog][processing] Backing up sda 18.2 GB at 191.9 MB/s (36%).
[13.01.2019 05:02:01] <140460756543232> lpbcore| Session progress: 43%; processed size: 23280484352 processing speed: 206036572 bottleneck stats: 67/100/0/72
[13.01.2019 05:02:01] <140460756543232> vmb | [SessionLog][processing] Backing up sda 21.7 GB at 218 MB/s (43%).
[13.01.2019 05:02:07] <140460756543232> lpbcore| Session progress: 50%; processed size: 27038580736 processing speed: 227232149 bottleneck stats: 64/100/0/100
[13.01.2019 05:02:07] <140460756543232> vmb | [SessionLog][processing] Backing up sda 25.2 GB at 239.2 MB/s (50%).
[13.01.2019 05:02:12] <140460756543232> lpbcore| Session progress: 57%; processed size: 30796677120 processing speed: 248807752 bottleneck stats: 61/100/0/100
[13.01.2019 05:02:12] <140460756543232> vmb | [SessionLog][processing] Backing up sda 28.7 GB at 260.8 MB/s (57%).
[13.01.2019 05:02:16] <140460756543232> lpbcore| Session progress: 64%; processed size: 34554773504 processing speed: 268837611 bottleneck stats: 59/100/0/100
[13.01.2019 05:02:16] <140460756543232> vmb | [SessionLog][processing] Backing up sda 32.2 GB at 280.8 MB/s (64%).
[13.01.2019 05:02:21] <140460756543232> lpbcore| Session progress: 71%; processed size: 38312869888 processing speed: 287386039 bottleneck stats: 57/100/0/100
[13.01.2019 05:02:21] <140460756543232> vmb | [SessionLog][processing] Backing up sda 35.7 GB at 299.1 MB/s (71%).
[13.01.2019 05:02:26] <140460756543232> lpbcore| Session progress: 78%; processed size: 42070966272 processing speed: 304451726 bottleneck stats: 55/100/0/100
[13.01.2019 05:02:26] <140460756543232> vmb | [SessionLog][processing] Backing up sda 39.2 GB at 315.9 MB/s (78%).
[13.01.2019 05:02:31] <140460756543232> lpbcore| Session progress: 85%; processed size: 45829062656 processing speed: 320612994 bottleneck stats: 53/100/0/100
[13.01.2019 05:02:31] <140460756543232> vmb | [SessionLog][processing] Backing up sda 42.7 GB at 331.7 MB/s (85%).
[13.01.2019 05:02:35] <140460756543232> lpbcore| Session progress: 92%; processed size: 49587159040 processing speed: 335667542 bottleneck stats: 52/100/0/100
[13.01.2019 05:02:35] <140460756543232> vmb | [SessionLog][processing] Backing up sda 46.2 GB at 346.3 MB/s (92%).
[13.01.2019 05:02:40] <140460756543232> lpbcore| Session progress: 99%; processed size: 53029634048 processing speed: 347897277 bottleneck stats: 50/100/0/100
[13.01.2019 05:02:40] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.4 GB at 358 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53077868544 processing speed: 344411003 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.4 GB at 354.1 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53082062848 processing speed: 344355544 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.4 GB at 354.1 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53086257152 processing speed: 344306811 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.4 GB at 354 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53090451456 processing speed: 344251403 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.4 GB at 353.9 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53092548608 processing speed: 344227057 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.4 GB at 353.9 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53096742912 processing speed: 344189535 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.5 GB at 353.9 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53100937216 processing speed: 344163181 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.5 GB at 353.8 MB/s (99%).
[13.01.2019 05:02:42] <140460756543232> lpbcore| Session progress: 99%; processed size: 53105131520 processing speed: 344145755 bottleneck stats: 51/100/0/100
[13.01.2019 05:02:42] <140460756543232> vmb | [SessionLog][processing] Backing up sda 49.5 GB at 353.8 MB/s (99%).
Code: Select all
[13.01.2019 05:00:19] <140479421200128> vsnap | Total changes size: accounted [211550208], unaccounted [471859200] bytes.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing CBT data ok.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker ok.
[13.01.2019 05:00:19] <140479421200128> dsk | Swap filter is disabled.
[13.01.2019 05:00:19] <140479421200128> dt | Notification sent: changed data size is '53514076160' bytes.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing CBT data
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] CBT data is already initialized
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker ok.
[13.01.2019 05:00:19] <140479421200128> dsk | Swap filter is disabled.
[13.01.2019 05:00:19] <140479421200128> dsk | Creating disk reader with random access ( processing block size: '1048576').
[13.01.2019 05:00:19] <140479421200128> dt | Transmission specification:
[13.01.2019 05:00:19] <140479421200128> dt | Block size: [1048576].
[13.01.2019 05:00:19] <140479421200128> dt | Is source compressed: [false].
[13.01.2019 05:00:19] <140479421200128> dt | Is incremental CTK available: [true].
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing defined block mask.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing defined block mask. ok.
[13.01.2019 05:00:19] <140479421200128> dt | Defined blocks mask is available for the disk.
[13.01.2019 05:00:21] <140479421200128> dt | Received upload configuration spec.
[13.01.2019 05:00:21] <140479421200128> dt | Transmitter tokens count: [24].
[13.01.2019 05:00:21] <140479421200128> dt | Negotiating encryption config. Source: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''.
[13.01.2019 05:00:21] <140479421200128> dt | Target: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''.
[13.01.2019 05:00:21] <140479421200128> dt | Negotiating encryption config. Source: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''. ok.
[13.01.2019 05:00:21] <140479421200128> alg | Using simple sender manager
[13.01.2019 05:00:21] <140479421200128> alg | Filter digests are available. Filtering read device will be created.
[13.01.2019 05:00:21] <140479907747584> cli | Thread started. Thread id: 140479907747584, parent id: 140479421200128, role: area reader : BLOCKS_READER: DISK=Disk Emulator, CTK=Incremental multi-tracker { bitmap_tracker:// , bitmap_tracker:// , }
[13.01.2019 05:00:21] <140478901114624> cli | Thread started. Thread id: 140478901114624, parent id: 140479421200128, role: Area processing dispatcher: Digests calculator.
[13.01.2019 05:00:21] <140478884329216> cli | Thread started. Thread id: 140478884329216, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478867543808> cli | Thread started. Thread id: 140478867543808, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140479421200128> alg | Transmission pipe configuration, CPU number: 2, digests calc: enabled, compression level: Lz4, crypto policy: Policy: 'enNoCryptoProcessing', target keyset ID: 'null', compression: enabled.
[13.01.2019 05:00:21] <140478842365696> cli | Thread started. Thread id: 140478842365696, parent id: 140479421200128, role: Area writer: File blocks transmission channel (sender).
[13.01.2019 05:00:21] <140478859151104> cli | Thread started. Thread id: 140478859151104, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478875936512> cli | Thread started. Thread id: 140478875936512, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478850758400> cli | Thread started. Thread id: 140478850758400, parent id: 140479421200128, role: Area processing dispatcher: Data compressor: [Lz4].
[13.01.2019 05:00:21] <140478892721920> cli | Thread started. Thread id: 140478892721920, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478909507328> cli | Thread started. Thread id: 140478909507328, parent id: 140479421200128, role: Processing thread: Digests calculator.
[13.01.2019 05:00:21] <140478917900032> cli | Thread started. Thread id: 140478917900032, parent id: 140479421200128, role: Processing thread: Digests calculator.
[13.01.2019 05:00:21] <140479001761536> cli | Thread started. Thread id: 140479001761536, parent id: 140479421200128, role: Processing thread: Digests calculator.
[13.01.2019 05:00:21] <140479421200128> cli | - 0%, workload src: 98/11/80, ntf: 0/0/0
[13.01.2019 05:00:26] <140479421200128> cli | - 1%, workload src: 89/14/0, ntf: 0/11/0
[13.01.2019 05:00:27] <140479421200128> cli | - 2%, workload src: 89/14/0, ntf: 89/14/0
[13.01.2019 05:00:27] <140479421200128> cli | - 3%, workload src: 89/14/0, ntf: 89/14/0
[13.01.2019 05:00:27] <140479421200128> cli | - 4%, workload src: 89/14/0, ntf: 89/14/0
[13.01.2019 05:00:35] <140479421200128> cli | - 5%, workload src: 89/17/0, ntf: 89/14/0
[13.01.2019 05:00:36] <140479421200128> cli | - 6%, workload src: 89/19/0, ntf: 89/17/0
[13.01.2019 05:00:36] <140479421200128> cli | - 7%, workload src: 89/19/0, ntf: 89/17/0
[13.01.2019 05:00:36] <140479421200128> cli | - 8%, workload src: 89/19/0, ntf: 89/17/0
[13.01.2019 05:00:43] <140479421200128> cli | - 9%, workload src: 89/22/0, ntf: 89/19/0
[13.01.2019 05:00:44] <140479421200128> cli | - 10%, workload src: 89/22/0, ntf: 89/22/0
[13.01.2019 05:00:48] <140479421200128> cli | - 11%, workload src: 89/24/0, ntf: 89/22/0
[13.01.2019 05:00:55] <140479421200128> cli | - 12%, workload src: 89/26/0, ntf: 89/24/0
[13.01.2019 05:01:03] <140479421200128> cli | - 13%, workload src: 89/34/0, ntf: 89/26/0
[13.01.2019 05:01:10] <140479421200128> cli | - 14%, workload src: 89/35/0, ntf: 89/34/0
[13.01.2019 05:01:12] <140479421200128> cli | - 15%, workload src: 89/36/0, ntf: 89/35/0
[13.01.2019 05:01:16] <140479421200128> cli | - 16%, workload src: 89/37/0, ntf: 89/36/0
[13.01.2019 05:01:17] <140479421200128> cli | - 17%, workload src: 89/37/0, ntf: 89/37/0
[13.01.2019 05:01:21] <140479421200128> cli | - 18%, workload src: 89/38/0, ntf: 89/37/0
[13.01.2019 05:01:21] <140479421200128> cli | - 19%, workload src: 89/38/0, ntf: 89/37/0
[13.01.2019 05:01:24] <140479421200128> cli | - 20%, workload src: 89/38/0, ntf: 89/38/0
[13.01.2019 05:01:27] <140479421200128> cli | - 21%, workload src: 89/39/0, ntf: 89/38/0
[13.01.2019 05:01:32] <140479421200128> cli | - 22%, workload src: 89/40/0, ntf: 89/39/0
[13.01.2019 05:01:32] <140479421200128> cli | - 23%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:33] <140479421200128> cli | - 24%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:33] <140479421200128> cli | - 25%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:33] <140479421200128> cli | - 26%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:37] <140479421200128> cli | - 27%, workload src: 86/53/0, ntf: 89/40/0
[13.01.2019 05:01:42] <140479421200128> cli | - 28%, workload src: 81/65/0, ntf: 89/40/0
[13.01.2019 05:01:46] <140479421200128> cli | - 29%, workload src: 77/72/0, ntf: 89/72/0
[13.01.2019 05:01:51] <140479421200128> cli | - 30%, workload src: 73/82/0, ntf: 89/72/0
[13.01.2019 05:01:56] <140479421200128> cli | - 31%, workload src: 70/91/0, ntf: 89/72/0
[13.01.2019 05:02:01] <140479421200128> cli | - 32%, workload src: 67/100/0, ntf: 89/72/0
[13.01.2019 05:02:07] <140479421200128> cli | - 33%, workload src: 64/100/0, ntf: 89/100/0
[13.01.2019 05:02:12] <140479421200128> cli | - 34%, workload src: 61/100/0, ntf: 89/100/0
[13.01.2019 05:02:16] <140479421200128> cli | - 35%, workload src: 59/100/0, ntf: 89/100/0
[13.01.2019 05:02:21] <140479421200128> cli | - 36%, workload src: 57/100/0, ntf: 89/100/0
[13.01.2019 05:02:26] <140479421200128> cli | - 37%, workload src: 55/100/0, ntf: 89/100/0
[13.01.2019 05:02:31] <140479421200128> cli | - 38%, workload src: 53/100/0, ntf: 89/100/0
[13.01.2019 05:02:35] <140479421200128> cli | - 39%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:40] <140479421200128> cli | - 40%, workload src: 50/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 41%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 42%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 43%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 44%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 45%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 46%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 47%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 48%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 49%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 50%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:44] <140479421200128> cli | - 51%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:44] <140479421200128> cli | - 52%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:44] <140479421200128> cli | - 53%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 54%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 55%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 56%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 57%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 58%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 59%, workload src: 52/100/0, ntf: 89/100/0
Code: Select all
[13.01.2019 05:00:19] <140479421200128> vsnap | Total changes size: accounted [211550208], unaccounted [471859200] bytes.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing CBT data ok.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker ok.
[13.01.2019 05:00:19] <140479421200128> dsk | Swap filter is disabled.
[13.01.2019 05:00:19] <140479421200128> dt | Notification sent: changed data size is '53514076160' bytes.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing CBT data
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] CBT data is already initialized
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing block tracker ok.
[13.01.2019 05:00:19] <140479421200128> dsk | Swap filter is disabled.
[13.01.2019 05:00:19] <140479421200128> dsk | Creating disk reader with random access ( processing block size: '1048576').
[13.01.2019 05:00:19] <140479421200128> dt | Transmission specification:
[13.01.2019 05:00:19] <140479421200128> dt | Block size: [1048576].
[13.01.2019 05:00:19] <140479421200128> dt | Is source compressed: [false].
[13.01.2019 05:00:19] <140479421200128> dt | Is incremental CTK available: [true].
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing defined block mask.
[13.01.2019 05:00:19] <140479421200128> dsk | [emulation] Initializing defined block mask. ok.
[13.01.2019 05:00:19] <140479421200128> dt | Defined blocks mask is available for the disk.
[13.01.2019 05:00:21] <140479421200128> dt | Received upload configuration spec.
[13.01.2019 05:00:21] <140479421200128> dt | Transmitter tokens count: [24].
[13.01.2019 05:00:21] <140479421200128> dt | Negotiating encryption config. Source: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''.
[13.01.2019 05:00:21] <140479421200128> dt | Target: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''.
[13.01.2019 05:00:21] <140479421200128> dt | Negotiating encryption config. Source: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''. ok.
[13.01.2019 05:00:21] <140479421200128> alg | Using simple sender manager
[13.01.2019 05:00:21] <140479421200128> alg | Filter digests are available. Filtering read device will be created.
[13.01.2019 05:00:21] <140479907747584> cli | Thread started. Thread id: 140479907747584, parent id: 140479421200128, role: area reader : BLOCKS_READER: DISK=Disk Emulator, CTK=Incremental multi-tracker { bitmap_tracker:// , bitmap_tracker:// , }
[13.01.2019 05:00:21] <140478901114624> cli | Thread started. Thread id: 140478901114624, parent id: 140479421200128, role: Area processing dispatcher: Digests calculator.
[13.01.2019 05:00:21] <140478884329216> cli | Thread started. Thread id: 140478884329216, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478867543808> cli | Thread started. Thread id: 140478867543808, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140479421200128> alg | Transmission pipe configuration, CPU number: 2, digests calc: enabled, compression level: Lz4, crypto policy: Policy: 'enNoCryptoProcessing', target keyset ID: 'null', compression: enabled.
[13.01.2019 05:00:21] <140478842365696> cli | Thread started. Thread id: 140478842365696, parent id: 140479421200128, role: Area writer: File blocks transmission channel (sender).
[13.01.2019 05:00:21] <140478859151104> cli | Thread started. Thread id: 140478859151104, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478875936512> cli | Thread started. Thread id: 140478875936512, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478850758400> cli | Thread started. Thread id: 140478850758400, parent id: 140479421200128, role: Area processing dispatcher: Data compressor: [Lz4].
[13.01.2019 05:00:21] <140478892721920> cli | Thread started. Thread id: 140478892721920, parent id: 140479421200128, role: Processing thread: Compression transformator.
[13.01.2019 05:00:21] <140478909507328> cli | Thread started. Thread id: 140478909507328, parent id: 140479421200128, role: Processing thread: Digests calculator.
[13.01.2019 05:00:21] <140478917900032> cli | Thread started. Thread id: 140478917900032, parent id: 140479421200128, role: Processing thread: Digests calculator.
[13.01.2019 05:00:21] <140479001761536> cli | Thread started. Thread id: 140479001761536, parent id: 140479421200128, role: Processing thread: Digests calculator.
[13.01.2019 05:00:21] <140479421200128> cli | - 0%, workload src: 98/11/80, ntf: 0/0/0
[13.01.2019 05:00:26] <140479421200128> cli | - 1%, workload src: 89/14/0, ntf: 0/11/0
[13.01.2019 05:00:27] <140479421200128> cli | - 2%, workload src: 89/14/0, ntf: 89/14/0
[13.01.2019 05:00:27] <140479421200128> cli | - 3%, workload src: 89/14/0, ntf: 89/14/0
[13.01.2019 05:00:27] <140479421200128> cli | - 4%, workload src: 89/14/0, ntf: 89/14/0
[13.01.2019 05:00:35] <140479421200128> cli | - 5%, workload src: 89/17/0, ntf: 89/14/0
[13.01.2019 05:00:36] <140479421200128> cli | - 6%, workload src: 89/19/0, ntf: 89/17/0
[13.01.2019 05:00:36] <140479421200128> cli | - 7%, workload src: 89/19/0, ntf: 89/17/0
[13.01.2019 05:00:36] <140479421200128> cli | - 8%, workload src: 89/19/0, ntf: 89/17/0
[13.01.2019 05:00:43] <140479421200128> cli | - 9%, workload src: 89/22/0, ntf: 89/19/0
[13.01.2019 05:00:44] <140479421200128> cli | - 10%, workload src: 89/22/0, ntf: 89/22/0
[13.01.2019 05:00:48] <140479421200128> cli | - 11%, workload src: 89/24/0, ntf: 89/22/0
[13.01.2019 05:00:55] <140479421200128> cli | - 12%, workload src: 89/26/0, ntf: 89/24/0
[13.01.2019 05:01:03] <140479421200128> cli | - 13%, workload src: 89/34/0, ntf: 89/26/0
[13.01.2019 05:01:10] <140479421200128> cli | - 14%, workload src: 89/35/0, ntf: 89/34/0
[13.01.2019 05:01:12] <140479421200128> cli | - 15%, workload src: 89/36/0, ntf: 89/35/0
[13.01.2019 05:01:16] <140479421200128> cli | - 16%, workload src: 89/37/0, ntf: 89/36/0
[13.01.2019 05:01:17] <140479421200128> cli | - 17%, workload src: 89/37/0, ntf: 89/37/0
[13.01.2019 05:01:21] <140479421200128> cli | - 18%, workload src: 89/38/0, ntf: 89/37/0
[13.01.2019 05:01:21] <140479421200128> cli | - 19%, workload src: 89/38/0, ntf: 89/37/0
[13.01.2019 05:01:24] <140479421200128> cli | - 20%, workload src: 89/38/0, ntf: 89/38/0
[13.01.2019 05:01:27] <140479421200128> cli | - 21%, workload src: 89/39/0, ntf: 89/38/0
[13.01.2019 05:01:32] <140479421200128> cli | - 22%, workload src: 89/40/0, ntf: 89/39/0
[13.01.2019 05:01:32] <140479421200128> cli | - 23%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:33] <140479421200128> cli | - 24%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:33] <140479421200128> cli | - 25%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:33] <140479421200128> cli | - 26%, workload src: 89/40/0, ntf: 89/40/0
[13.01.2019 05:01:37] <140479421200128> cli | - 27%, workload src: 86/53/0, ntf: 89/40/0
[13.01.2019 05:01:42] <140479421200128> cli | - 28%, workload src: 81/65/0, ntf: 89/40/0
[13.01.2019 05:01:46] <140479421200128> cli | - 29%, workload src: 77/72/0, ntf: 89/72/0
[13.01.2019 05:01:51] <140479421200128> cli | - 30%, workload src: 73/82/0, ntf: 89/72/0
[13.01.2019 05:01:56] <140479421200128> cli | - 31%, workload src: 70/91/0, ntf: 89/72/0
[13.01.2019 05:02:01] <140479421200128> cli | - 32%, workload src: 67/100/0, ntf: 89/72/0
[13.01.2019 05:02:07] <140479421200128> cli | - 33%, workload src: 64/100/0, ntf: 89/100/0
[13.01.2019 05:02:12] <140479421200128> cli | - 34%, workload src: 61/100/0, ntf: 89/100/0
[13.01.2019 05:02:16] <140479421200128> cli | - 35%, workload src: 59/100/0, ntf: 89/100/0
[13.01.2019 05:02:21] <140479421200128> cli | - 36%, workload src: 57/100/0, ntf: 89/100/0
[13.01.2019 05:02:26] <140479421200128> cli | - 37%, workload src: 55/100/0, ntf: 89/100/0
[13.01.2019 05:02:31] <140479421200128> cli | - 38%, workload src: 53/100/0, ntf: 89/100/0
[13.01.2019 05:02:35] <140479421200128> cli | - 39%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:40] <140479421200128> cli | - 40%, workload src: 50/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 41%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 42%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 43%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 44%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 45%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 46%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 47%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 48%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 49%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:42] <140479421200128> cli | - 50%, workload src: 51/100/0, ntf: 89/100/0
[13.01.2019 05:02:44] <140479421200128> cli | - 51%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:44] <140479421200128> cli | - 52%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:44] <140479421200128> cli | - 53%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 54%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 55%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 56%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 57%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 58%, workload src: 52/100/0, ntf: 89/100/0
[13.01.2019 05:02:45] <140479421200128> cli | - 59%, workload src: 52/100/0, ntf: 89/100/0
root@edx:/edx/app/edxapp# tail -n 100 /var/log/veeam/Backup/BackupJob/Session_20190113_050001_\{8ad64c4a-ccf8-4664-a1d2-51e90b927548\}/
Agent.Repair.log Agent.Source.log Agent.Target.log Job.log
root@edx:/edx/app/edxapp# tail -n 100 /var/log/veeam/Backup/BackupJob/Session_20190113_050001_\{8ad64c4a-ccf8-4664-a1d2-51e90b927548\}/Agent.Target.log
[13.01.2019 05:00:21] <140304233527040> stg | Metadata for the new increment [{bf3db768-a6f5-4672-861e-423a28a88763}] is created.
[13.01.2019 05:00:21] <140304233527040> stg | Periodic flush controller initialized. Controller setting: [Disabled].
[13.01.2019 05:00:21] <140304233527040> stg | Increment constructed.
[13.01.2019 05:00:21] <140304233527040> stg | MTA FIB record handle is created, handle '0x000x7f9b18001130', recorder '0x000x7f9b1a9bc230'.
[13.01.2019 05:00:21] <140303688263424> stg | Committing metadata.
[13.01.2019 05:00:21] <140303688263424> stg | Removing lock on file [/tmp/veeam/10.136.2.34cnotbk/edx BackupJob/BackupJob_2019-01-13T050007.vib].
[13.01.2019 05:00:21] <140303688263424> stg | Applying exclusive lock on file [/tmp/veeam/10.136.2.34cnotbk/edx BackupJob/BackupJob_2019-01-13T050007.vib].
[13.01.2019 05:00:21] <140303688263424> stg | Metadata snapshot was saved. [1] banks were written to disk.
[13.01.2019 05:00:21] <140303688263424> stg | Removing lock on file [/tmp/veeam/10.136.2.34cnotbk/edx BackupJob/BackupJob_2019-01-13T050007.vib].
[13.01.2019 05:00:21] <140303688263424> stg | Applying exclusive lock on file [/tmp/veeam/10.136.2.34cnotbk/edx BackupJob/BackupJob_2019-01-13T050007.vib].
[13.01.2019 05:00:21] <140303688263424> stg | Metadata snapshot was saved. [1] banks were written to disk.
[13.01.2019 05:00:21] <140303688263424> stg | Metadata were committed. Reason: [Changes in custom properties of the increment '{bf3db768-a6f5-4672-861e-423a28a88763}'.]. Version: [3]. Storage: [/tmp/veeam/10.136.2.34cnotbk/edx BackupJob/BackupJob_2019-01-13T050007.vib]. Banks number: [3].
[13.01.2019 05:00:21] <140303688263424> stg | Committing metadata. ok.
[13.01.2019 05:00:21] <140303688263424> dt | Opening backup disk target for disk '{bf3db768-a6f5-4672-861e-423a28a88763}'. ok.
[13.01.2019 05:00:21] <140303688263424> dt | Source crypto policy: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''.
[13.01.2019 05:00:21] <140303688263424> dt | Source requested traffic compression level: [Lz4].
[13.01.2019 05:00:21] <140303688263424> dt | Preparing transmission configuration spec.
[13.01.2019 05:00:21] <140303688263424> dt | Traffic compression enabled: [Lz4].
[13.01.2019 05:00:21] <140303688263424> dt | Target blocks repacking is not required, target and traffic compression are equal.
[13.01.2019 05:00:21] <140303688263424> dt | Digests were extracted from target.
[13.01.2019 05:00:21] <140303688263424> dt | Digests calculation and filtering should be done at upload side.
[13.01.2019 05:00:21] <140303688263424> dt | Creating recommended transport configuration using pool id [2].
[13.01.2019 05:00:21] <140303688263424> dt | Negotiating encryption config. Target: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''.
[13.01.2019 05:00:21] <140303688263424> dt | Negotiating encryption config. Target: 'Policy: 'enNoCryptoProcessing', target keyset ID: 'null''. ok.
[13.01.2019 05:00:21] <140303688263424> dt | Multi-streaming is disabled.
[13.01.2019 05:00:21] <140303688263424> dt | Receiver tokens count: [8].
[13.01.2019 05:00:21] <140303688263424> dt | Preparing transmission configuration spec. ok.
[13.01.2019 05:00:21] <140303688263424> alg | Using simple receiver manager
[13.01.2019 05:00:21] <140303394649856> cli | Thread started. Thread id: 140303394649856, parent id: 140303688263424, role: area reader : Receiver channel for {bf3db768-a6f5-4672-861e-423a28a88763}
[13.01.2019 05:00:21] <140303688263424> alg | Transmission pipe configuration, CPU number: 2, crypto policy: Policy: 'enNoCryptoProcessing', target keyset ID: 'null'
[13.01.2019 05:00:21] <140303428220672> cli | Thread started. Thread id: 140303428220672, parent id: 140303688263424, role: Area writer: Traditional incremental backup of the FIB Increment {bf3db768-a6f5-4672-861e-423a28a88763}.
[13.01.2019 05:00:21] <140303688263424> cli | - 0%, workload rps: 0/11/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:26] <140303688263424> cli | - 1%, workload rps: 89/14/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:27] <140303688263424> cli | - 2%, workload rps: 89/14/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:27] <140303688263424> cli | - 3%, workload rps: 89/14/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:27] <140303688263424> cli | - 4%, workload rps: 89/14/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:35] <140303688263424> cli | - 5%, workload rps: 89/17/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:37] <140303688263424> cli | - 6%, workload rps: 89/19/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:37] <140303688263424> cli | - 7%, workload rps: 89/19/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:37] <140303688263424> cli | - 8%, workload rps: 89/19/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:43] <140303688263424> cli | - 9%, workload rps: 89/22/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:44] <140303688263424> cli | - 10%, workload rps: 89/22/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:49] <140303688263424> cli | - 11%, workload rps: 89/24/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:00:55] <140303688263424> cli | - 12%, workload rps: 89/26/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:03] <140303688263424> cli | - 13%, workload rps: 89/34/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:10] <140303688263424> cli | - 14%, workload rps: 89/35/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:12] <140303688263424> cli | - 15%, workload rps: 89/36/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:16] <140303688263424> cli | - 16%, workload rps: 89/37/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:17] <140303688263424> cli | - 17%, workload rps: 89/37/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:21] <140303688263424> cli | - 18%, workload rps: 89/38/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:21] <140303688263424> cli | - 19%, workload rps: 89/38/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:25] <140303688263424> cli | - 20%, workload rps: 89/38/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:28] <140303688263424> cli | - 21%, workload rps: 89/39/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:32] <140303688263424> cli | - 22%, workload rps: 89/40/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:33] <140303688263424> cli | - 23%, workload rps: 89/40/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:33] <140303688263424> cli | - 24%, workload rps: 89/40/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:45] <140303688263424> cli | - 24%, workload rps: 89/65/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:46] <140303688263424> cli | - 25%, workload rps: 89/72/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:46] <140303688263424> cli | - 26%, workload rps: 89/72/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:46] <140303688263424> cli | - 27%, workload rps: 89/72/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:01:46] <140303688263424> cli | - 28%, workload rps: 89/72/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:04] <140303688263424> cli | - 28%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:06] <140303688263424> cli | - 29%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:06] <140303688263424> cli | - 30%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:06] <140303688263424> cli | - 31%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:06] <140303688263424> cli | - 32%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:22] <140303688263424> cli | - 32%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:23] <140303688263424> cli | - 33%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:23] <140303688263424> cli | - 34%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:23] <140303688263424> cli | - 35%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:23] <140303688263424> cli | - 36%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:39] <140303688263424> cli | - 36%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:41] <140303688263424> cli | - 37%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:41] <140303688263424> cli | - 38%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:41] <140303688263424> cli | - 39%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:41] <140303688263424> cli | - 40%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 41%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 42%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 43%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 44%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 45%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 46%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 47%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 48%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 49%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:42] <140303688263424> cli | - 50%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 51%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 52%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 53%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 54%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 55%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 56%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 57%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 58%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 59%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 60%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 61%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 62%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli | - 63%, workload rps: 89/100/0, client: {8b9f2796-c562-4cae-9fec-f0ce4a51b291}
[13.01.2019 05:02:46] <140303688263424> cli