cloudlet-log for checking reset_blob_pos

cloudlet-log

time:1480640859.745537, bdrv_discard, sector_num:8951808, sector_size:8
time:1480640859.746831, bdrv_discard, sector_num:271064, sector_size:8
time:1480640859.747133, bdrv_discard, sector_num:271032, sector_size:8
time:1480640859.747500, bdrv_discard, sector_num:78880, sector_size:8
time:1480640859.747849, bdrv_discard, sector_num:94344, sector_size:8
time:1480640859.748264, bdrv_discard, sector_num:8830768, sector_size:8
time:1480640859.748595, bdrv_discard, sector_num:8830688, sector_size:8
time:1480640859.748962, bdrv_discard, sector_num:8830104, sector_size:8
time:1480640859.749315, bdrv_discard, sector_num:8820616, sector_size:8
time:1480640859.749701, bdrv_discard, sector_num:8653104, sector_size:16
time:1480640859.750016, bdrv_discard, sector_num:8652848, sector_size:16
time:1480640859.750430, bdrv_discard, sector_num:8951824, sector_size:8
time:1480640859.750775, bdrv_discard, sector_num:8679304, sector_size:8
time:1480640859.751153, bdrv_discard, sector_num:8657056, sector_size:8
time:1480640859.751460, bdrv_discard, sector_num:8830784, sector_size:8
time:1480640865.687360, bdrv_discard, sector_num:8951816, sector_size:8
time:1480640865.687910, bdrv_discard, sector_num:302704, sector_size:56
time:1480640865.688462, bdrv_discard, sector_num:813568, sector_size:288
time:1480640865.688986, bdrv_discard, sector_num:280216, sector_size:24
time:1480640876.48364, qmp_unrandomize_raw_live before
time:1480640876.48388, qmp_unrandomize_raw_live after
time:1480640886.64080, qmp_stop request ignored for raw_live mode
time:1480640886.69163, case CLOUDLET_RAW_LIVE
time:1480640886.69205, in qemu_fopen_ops: 0x39b7240
time:1480640886.69392, in qemu_fopen_ops: 0x7f2e30000b10
time:1480640886.69417, set_blob_pos: add_padding: 43
time:1480640886.69454, set_blob_pos: add_padding: 32
time:1480640886.69569, set_blob_pos: add_padding: 1834
time:1480640886.69605, set_blob_pos: add_padding: 199
time:1480640886.69626, set_blob_pos: add_padding: 32
time:1480640886.69712, set_blob_pos: add_padding: 1834
time:1480640886.69735, set_blob_pos: add_padding: 199
time:1480640886.69747, set_blob_pos: add_padding: 32
time:1480640886.69814, set_blob_pos: add_padding: 1834
time:1480640886.69832, set_blob_pos: add_padding: 199
time:1480640886.69843, set_blob_pos: add_padding: 32
time:1480640886.69909, set_blob_pos: add_padding: 1834
time:1480640886.69927, set_blob_pos: add_padding: 199
time:1480640886.69938, set_blob_pos: add_padding: 30
time:1480640886.69949, set_blob_pos: add_padding: 50
time:1480640886.69962, set_blob_pos: add_padding: 40
time:1480640886.69977, set_blob_pos: add_padding: 310
time:1480640886.69990, set_blob_pos: add_padding: 324
time:1480640886.70009, set_blob_pos: add_padding: 35
time:1480640886.70023, set_blob_pos: add_padding: 35
time:1480640886.70042, set_blob_pos: add_padding: 226
time:1480640886.70061, set_blob_pos: add_padding: 169
time:1480640886.70076, set_blob_pos: add_padding: 270
time:1480640886.70094, set_blob_pos: add_padding: 127
time:1480640886.70115, set_blob_pos: add_padding: 304
time:1480640886.70130, set_blob_pos: add_padding: 313
time:1480640886.70142, set_blob_pos: add_padding: 23
time:1480640886.70267, set_blob_pos: add_padding: 4126
time:1480640886.70283, set_blob_pos: add_padding: 21
time:1480640886.70301, set_blob_pos: add_padding: 92
time:1480640886.70318, set_blob_pos: add_padding: 92
time:1480640886.70396, set_blob_pos: add_padding: 562
time:1480640886.70431, set_blob_pos: add_padding: 972
time:1480640886.70443, set_blob_pos: add_padding: 22
time:1480640886.70460, set_blob_pos: add_padding: 347
time:1480640886.70475, set_blob_pos: add_padding: 346
time:1480640886.70492, set_blob_pos: add_padding: 1907
time:1480640886.366683, migration_thread in
time:1480640886.381120, first iteration
time:1480640886.381135, in qemu_savevm_state_iterate
time:1480640886.381140, ram_save_iterate in
time:1480640886.381203, ram_save_page update_block->blob_pos: pc.ram, 4096
time:1480640890.190463, ram_save_page update_block->blob_pos: vga.vram, 1073750016
time:1480640890.227164, ram_save_page update_block->blob_pos: pc.bios, 1082142720
time:1480640890.230177, ram_save_page update_block->blob_pos: pc.rom, 1082277888
time:1480640890.230249, ram_save_page update_block->blob_pos: 0000:00:03.0/virtio-net-pci.rom, 1082413056
time:1480640890.230295, ram_save_page update_block->blob_pos: 0000:00:02.0/cirrus_vga.rom, 1082482688
time:1480640890.230327, ram_save_iterate out
time:1480640890.230347, out qemu_savevm_state_iterate
time:1480640890.230350, qemu_savevm_state_iterate ret(264288): sequence(0)
time:1480640890.230352, return >= 0, but no stop signal
time:1480640890.230359, before check wait raw live iterate
time:1480640890.230361, check_wait_raw_live_iterate before
time:1480640890.230362, check_wait_raw_live_iterate cond wait
time:1480640896.69318, before raw_live_iterate
time:1480640896.69349, after raw_live_iterate
time:1480640896.69355, check_wait_raw_live_iterate cond wait pass
time:1480640896.69366, check_wait_raw_live_iterate finish waiting
time:1480640896.69372, check_wait_raw_live_iterate after
time:1480640896.69377, after check wait raw live iterate
time:1480640896.69382, in qemu_savevm_state_iterate
time:1480640896.69390, ram_save_iterate in
time:1480640896.69415, ram_save_iterate out
time:1480640896.69423, out qemu_savevm_state_iterate
time:1480640896.69429, qemu_savevm_state_iterate ret(0): sequence(1)
time:1480640896.69435, return >= 0, but no stop signal
time:1480640896.69446, before check wait raw live iterate
time:1480640896.69449, check_wait_raw_live_iterate before
time:1480640896.69452, check_wait_raw_live_iterate cond wait
time:1480640906.79617, before raw_live_iterate
time:1480640906.79667, after raw_live_iterate
time:1480640906.79700, check_wait_raw_live_iterate cond wait pass
time:1480640906.79721, check_wait_raw_live_iterate finish waiting
time:1480640906.79729, check_wait_raw_live_iterate after
time:1480640906.79738, after check wait raw live iterate
time:1480640906.79755, in qemu_savevm_state_iterate
time:1480640906.79765, ram_save_iterate in
time:1480640906.79792, ram_save_iterate out
time:1480640906.79815, out qemu_savevm_state_iterate
time:1480640906.79819, qemu_savevm_state_iterate ret(0): sequence(2)
time:1480640906.79825, return >= 0, but no stop signal
time:1480640906.79855, before check wait raw live iterate
time:1480640906.79859, check_wait_raw_live_iterate before
time:1480640906.79862, check_wait_raw_live_iterate cond wait
time:1480640916.89848, qmp_stop_raw_live
time:1480640916.89944, check_wait_raw_live_iterate cond wait pass
time:1480640916.89964, check_wait_raw_live_iterate finish waiting
time:1480640916.89971, check_wait_raw_live_iterate after
time:1480640916.89976, after check wait raw live iterate
time:1480640916.89994, in qemu_savevm_state_iterate
time:1480640916.90001, ram_save_iterate in
time:1480640916.90022, ram_save_iterate out
time:1480640916.90026, out qemu_savevm_state_iterate
time:1480640916.90029, qemu_savevm_state_iterate ret(0): sequence(3)
time:1480640916.90033, before migration_completion
time:1480640916.91470, qemu_savevm_state_complete_precopy looping: ram
time:1480640916.91489, ram_save_complete in
time:1480640916.91952, ram_save_page update_block->blob_pos: pc.ram, 4096
time:1480640916.182219, ram_save_page update_block->blob_pos: vga.vram, 1073750016
time:1480640916.182297, ram_save_complete out
time:1480640916.182302, reset_blob_pos old: 1074135048, new: 1082548224
time:1480640916.182304, set_blob_pos: add_padding: 1074135048
time:1480640916.182355, qemu_savevm_state_complete_precopy looping1 : timer at 1082548243
time:1480640916.182369, qemu_savevm_state_complete_precopy looping1 : slirp at 1082548286
time:1480640916.182377, qemu_savevm_state_complete_precopy looping1 : cpu_common at 1082548441
time:1480640916.182384, qemu_savevm_state_complete_precopy looping1 : cpu at 1082548466
time:1480640916.182584, qemu_savevm_state_complete_precopy looping1 : apic at 1082550437
time:1480640916.182612, qemu_savevm_state_complete_precopy looping1 : cpu_common at 1082550642
time:1480640916.182619, qemu_savevm_state_complete_precopy looping1 : cpu at 1082550667
time:1480640916.182801, qemu_savevm_state_complete_precopy looping1 : apic at 1082552600
time:1480640916.182838, qemu_savevm_state_complete_precopy looping1 : cpu_common at 1082552805
time:1480640916.182844, qemu_savevm_state_complete_precopy looping1 : cpu at 1082552830
time:1480640916.183040, qemu_savevm_state_complete_precopy looping1 : apic at 1082554763
time:1480640916.183066, qemu_savevm_state_complete_precopy looping1 : cpu_common at 1082554968
time:1480640916.183073, qemu_savevm_state_complete_precopy looping1 : cpu at 1082554993
time:1480640916.183254, qemu_savevm_state_complete_precopy looping1 : apic at 1082556926
time:1480640916.183280, qemu_savevm_state_complete_precopy looping1 : kvmclock at 1082557129
time:1480640916.183285, qemu_savevm_state_complete_precopy looping1 : fw_cfg at 1082557157
time:1480640916.183294, qemu_savevm_state_complete_precopy looping1 : PCIBUS at 1082557207
time:1480640916.183301, qemu_savevm_state_complete_precopy looping1 : 0000:00:00.0/I440FX at 1082557260
time:1480640916.183312, qemu_savevm_state_complete_precopy looping1 : 0000:00:01.0/PIIX3 at 1082557569
time:1480640916.183322, qemu_savevm_state_complete_precopy looping1 : i8259 at 1082557880
time:1480640916.183345, qemu_savevm_state_complete_precopy looping1 : i8259 at 1082557915
time:1480640916.183363, qemu_savevm_state_complete_precopy looping1 : ioapic at 1082557951
time:1480640916.183378, qemu_savevm_state_complete_precopy looping1 : hpet at 1082558175
time:1480640916.183399, qemu_savevm_state_complete_precopy looping1 : mc146818rtc at 1082558351
time:1480640916.183418, qemu_savevm_state_complete_precopy looping1 : i8254 at 1082558615
time:1480640916.183450, qemu_savevm_state_complete_precopy looping1 : ps2kbd at 1082558743
time:1480640916.183471, qemu_savevm_state_complete_precopy looping1 : ps2mouse at 1082559049
time:1480640916.183491, qemu_savevm_state_complete_precopy looping1 : pckbd at 1082559359
time:1480640916.183500, qemu_savevm_state_complete_precopy looping1 : vmmouse at 1082559384
time:1480640916.183597, qemu_savevm_state_complete_precopy looping1 : port92 at 1082563509
time:1480640916.183602, qemu_savevm_state_complete_precopy looping1 : dma at 1082563527
time:1480640916.183621, qemu_savevm_state_complete_precopy looping1 : dma at 1082563619
time:1480640916.183640, qemu_savevm_state_complete_precopy looping1 : fdc at 1082563711
time:1480640916.183702, qemu_savevm_state_complete_precopy looping1 : 0000:00:01.1/ide at 1082564286
time:1480640916.183843, qemu_savevm_state_complete_precopy looping1 : i2c_bus at 1082565249
time:1480640916.183852, qemu_savevm_state_complete_precopy looping1 : 0000:00:01.3/piix4_pm at 1082565285
time:1480640916.183885, qemu_savevm_state_complete_precopy looping1 : 0000:00:01.2/uhci at 1082565628
time:1480640916.183915, qemu_savevm_state_complete_precopy looping1 : 0000:00:03.0/virtio-net at 1082565980
time:1480640916.183946, qemu_savevm_state_complete_precopy looping1 : 0000:00:02.0/cirrus_vga at 1082566979
time:1480640916.184002, qemu_savevm_state_complete_precopy looping1 : 0000:00:04.0/virtio-balloon at 1082568890
time:1480640916.184030, add migration size padding : 112758247 from 1082569241 to 1195327488
time:1480640916.605309, after migration_completion
time:1480640916.605316, migration_thread after_loop