migration/trace-events

   1 # See docs/devel/tracing.txt for syntax documentation.
   2
   3 # savevm.c
   4 qemu_loadvm_state_section(unsigned int section_type) "%d"
   5 qemu_loadvm_state_section_command(int ret) "%d"
   6 qemu_loadvm_state_section_partend(uint32_t section_id) "%u"
   7 qemu_loadvm_state_post_main(int ret) "%d"
   8 qemu_loadvm_state_section_startfull(uint32_t section_id, const char *idstr, uint32_t instance_id, uint32_t version_id) "%u(%s) %u %u"
   9 qemu_savevm_send_packaged(void) ""
  10 loadvm_state_setup(void) ""
  11 loadvm_state_cleanup(void) ""
  12 loadvm_handle_cmd_packaged(unsigned int length) "%u"
  13 loadvm_handle_cmd_packaged_main(int ret) "%d"
  14 loadvm_handle_cmd_packaged_received(int ret) "%d"
  15 loadvm_handle_recv_bitmap(char *s) "%s"
  16 loadvm_postcopy_handle_advise(void) ""
  17 loadvm_postcopy_handle_listen(void) ""
  18 loadvm_postcopy_handle_run(void) ""
  19 loadvm_postcopy_handle_run_cpu_sync(void) ""
  20 loadvm_postcopy_handle_run_vmstart(void) ""
  21 loadvm_postcopy_handle_resume(void) ""
  22 loadvm_postcopy_ram_handle_discard(void) ""
  23 loadvm_postcopy_ram_handle_discard_end(void) ""
  24 loadvm_postcopy_ram_handle_discard_header(const char *ramid, uint16_t len) "%s: %ud"
  25 loadvm_process_command(uint16_t com, uint16_t len) "com=0x%x len=%d"
  26 loadvm_process_command_ping(uint32_t val) "0x%x"
  27 postcopy_ram_listen_thread_exit(void) ""
  28 postcopy_ram_listen_thread_start(void) ""
  29 qemu_savevm_send_postcopy_advise(void) ""
  30 qemu_savevm_send_postcopy_ram_discard(const char *id, uint16_t len) "%s: %ud"
  31 savevm_command_send(uint16_t command, uint16_t len) "com=0x%x len=%d"
  32 savevm_section_start(const char *id, unsigned int section_id) "%s, section_id %u"
  33 savevm_section_end(const char *id, unsigned int section_id, int ret) "%s, section_id %u -> %d"
  34 savevm_section_skip(const char *id, unsigned int section_id) "%s, section_id %u"
  35 savevm_send_open_return_path(void) ""
  36 savevm_send_ping(uint32_t val) "0x%x"
  37 savevm_send_postcopy_listen(void) ""
  38 savevm_send_postcopy_run(void) ""
  39 savevm_send_postcopy_resume(void) ""
  40 savevm_send_colo_enable(void) ""
  41 savevm_send_recv_bitmap(char *name) "%s"
  42 savevm_state_setup(void) ""
  43 savevm_state_resume_prepare(void) ""
  44 savevm_state_header(void) ""
  45 savevm_state_iterate(void) ""
  46 savevm_state_cleanup(void) ""
  47 savevm_state_complete_precopy(void) ""
  48 vmstate_save(const char *idstr, const char *vmsd_name) "%s, %s"
  49 vmstate_load(const char *idstr, const char *vmsd_name) "%s, %s"
  50 postcopy_pause_incoming(void) ""
  51 postcopy_pause_incoming_continued(void) ""
  52
  53 # vmstate.c
  54 vmstate_load_field_error(const char *field, int ret) "field \"%s\" load failed, ret = %d"
  55 vmstate_load_state(const char *name, int version_id) "%s v%d"
  56 vmstate_load_state_end(const char *name, const char *reason, int val) "%s %s/%d"
  57 vmstate_load_state_field(const char *name, const char *field) "%s:%s"
  58 vmstate_n_elems(const char *name, int n_elems) "%s: %d"
  59 vmstate_subsection_load(const char *parent) "%s"
  60 vmstate_subsection_load_bad(const char *parent,  const char *sub, const char *sub2) "%s: %s/%s"
  61 vmstate_subsection_load_good(const char *parent) "%s"
  62 vmstate_save_state_pre_save_res(const char *name, int res) "%s/%d"
  63 vmstate_save_state_loop(const char *name, const char *field, int n_elems) "%s/%s[%d]"
  64 vmstate_save_state_top(const char *idstr) "%s"
  65 vmstate_subsection_save_loop(const char *name, const char *sub) "%s/%s"
  66 vmstate_subsection_save_top(const char *idstr) "%s"
  67
  68 # vmstate-types.c
  69 get_qtailq(const char *name, int version_id) "%s v%d"
  70 get_qtailq_end(const char *name, const char *reason, int val) "%s %s/%d"
  71 put_qtailq(const char *name, int version_id) "%s v%d"
  72 put_qtailq_end(const char *name, const char *reason) "%s %s"
  73
  74 # qemu-file.c
  75 qemu_file_fclose(void) ""
  76
  77 # ram.c
  78 get_queued_page(const char *block_name, uint64_t tmp_offset, unsigned long page_abs) "%s/0x%" PRIx64 " page_abs=0x%lx"
  79 get_queued_page_not_dirty(const char *block_name, uint64_t tmp_offset, unsigned long page_abs, int sent) "%s/0x%" PRIx64 " page_abs=0x%lx (sent=%d)"
  80 migration_bitmap_sync_start(void) ""
  81 migration_bitmap_sync_end(uint64_t dirty_pages) "dirty_pages %" PRIu64
  82 migration_throttle(void) ""
  83 multifd_recv(uint8_t id, uint64_t packet_num, uint32_t used, uint32_t flags, uint32_t next_packet_size) "channel %d packet number %" PRIu64 " pages %d flags 0x%x next packet size %d"
  84 multifd_recv_sync_main(long packet_num) "packet num %ld"
  85 multifd_recv_sync_main_signal(uint8_t id) "channel %d"
  86 multifd_recv_sync_main_wait(uint8_t id) "channel %d"
  87 multifd_recv_thread_end(uint8_t id, uint64_t packets, uint64_t pages) "channel %d packets %" PRIu64 " pages %" PRIu64
  88 multifd_recv_thread_start(uint8_t id) "%d"
  89 multifd_send(uint8_t id, uint64_t packet_num, uint32_t used, uint32_t flags, uint32_t next_packet_size) "channel %d packet_num %" PRIu64 " pages %d flags 0x%x next packet size %d"
  90 multifd_send_sync_main(long packet_num) "packet num %ld"
  91 multifd_send_sync_main_signal(uint8_t id) "channel %d"
  92 multifd_send_sync_main_wait(uint8_t id) "channel %d"
  93 multifd_send_thread_end(uint8_t id, uint64_t packets, uint64_t pages) "channel %d packets %" PRIu64 " pages %"  PRIu64
  94 multifd_send_thread_start(uint8_t id) "%d"
  95 ram_discard_range(const char *rbname, uint64_t start, size_t len) "%s: start: %" PRIx64 " %zx"
  96 ram_load_loop(const char *rbname, uint64_t addr, int flags, void *host) "%s: addr: 0x%" PRIx64 " flags: 0x%x host: %p"
  97 ram_load_postcopy_loop(uint64_t addr, int flags) "@%" PRIx64 " %x"
  98 ram_postcopy_send_discard_bitmap(void) ""
  99 ram_save_page(const char *rbname, uint64_t offset, void *host) "%s: offset: 0x%" PRIx64 " host: %p"
 100 ram_save_queue_pages(const char *rbname, size_t start, size_t len) "%s: start: 0x%zx len: 0x%zx"
 101 ram_dirty_bitmap_request(char *str) "%s"
 102 ram_dirty_bitmap_reload_begin(char *str) "%s"
 103 ram_dirty_bitmap_reload_complete(char *str) "%s"
 104 ram_dirty_bitmap_sync_start(void) ""
 105 ram_dirty_bitmap_sync_wait(void) ""
 106 ram_dirty_bitmap_sync_complete(void) ""
 107 ram_state_resume_prepare(uint64_t v) "%" PRId64
 108 colo_flush_ram_cache_begin(uint64_t dirty_pages) "dirty_pages %" PRIu64
 109 colo_flush_ram_cache_end(void) ""
 110 save_xbzrle_page_skipping(void) ""
 111 save_xbzrle_page_overflow(void) ""
 112 ram_save_iterate_big_wait(uint64_t milliconds, int iterations) "big wait: %" PRIu64 " milliseconds, %d iterations"
 113 ram_load_complete(int ret, uint64_t seq_iter) "exit_code %d seq iteration %" PRIu64
 114
 115 # migration.c
 116 await_return_path_close_on_source_close(void) ""
 117 await_return_path_close_on_source_joining(void) ""
 118 migrate_set_state(const char *new_state) "new state %s"
 119 migrate_fd_cleanup(void) ""
 120 migrate_fd_error(const char *error_desc) "error=%s"
 121 migrate_fd_cancel(void) ""
 122 migrate_handle_rp_req_pages(const char *rbname, size_t start, size_t len) "in %s at 0x%zx len 0x%zx"
 123 migrate_pending(uint64_t size, uint64_t max, uint64_t pre, uint64_t compat, uint64_t post) "pending size %" PRIu64 " max %" PRIu64 " (pre = %" PRIu64 " compat=%" PRIu64 " post=%" PRIu64 ")"
 124 migrate_send_rp_message(int msg_type, uint16_t len) "%d: len %d"
 125 migrate_send_rp_recv_bitmap(char *name, int64_t size) "block '%s' size 0x%"PRIi64
 126 migration_completion_file_err(void) ""
 127 migration_completion_postcopy_end(void) ""
 128 migration_completion_postcopy_end_after_complete(void) ""
 129 migration_return_path_end_before(void) ""
 130 migration_return_path_end_after(int rp_error) "%d"
 131 migration_thread_after_loop(void) ""
 132 migration_thread_file_err(void) ""
 133 migration_thread_ratelimit_pre(int ms) "%d ms"
 134 migration_thread_ratelimit_post(int urgent) "urgent: %d"
 135 migration_thread_setup_complete(void) ""
 136 open_return_path_on_source(void) ""
 137 open_return_path_on_source_continue(void) ""
 138 postcopy_start(void) ""
 139 postcopy_pause_return_path(void) ""
 140 postcopy_pause_return_path_continued(void) ""
 141 postcopy_pause_continued(void) ""
 142 postcopy_start_set_run(void) ""
 143 source_return_path_thread_bad_end(void) ""
 144 source_return_path_thread_end(void) ""
 145 source_return_path_thread_entry(void) ""
 146 source_return_path_thread_loop_top(void) ""
 147 source_return_path_thread_pong(uint32_t val) "0x%x"
 148 source_return_path_thread_shut(uint32_t val) "0x%x"
 149 source_return_path_thread_resume_ack(uint32_t v) "%"PRIu32
 150 migration_thread_low_pending(uint64_t pending) "%" PRIu64
 151 migrate_transferred(uint64_t tranferred, uint64_t time_spent, uint64_t bandwidth, uint64_t size) "transferred %" PRIu64 " time_spent %" PRIu64 " bandwidth %" PRIu64 " max_size %" PRId64
 152 process_incoming_migration_co_end(int ret, int ps) "ret=%d postcopy-state=%d"
 153 process_incoming_migration_co_postcopy_end_main(void) ""
 154
 155 # channel.c
 156 migration_set_incoming_channel(void *ioc, const char *ioctype) "ioc=%p ioctype=%s"
 157 migration_set_outgoing_channel(void *ioc, const char *ioctype, const char *hostname, void *err)  "ioc=%p ioctype=%s hostname=%s err=%p"
 158
 159 # global_state.c
 160 migrate_state_too_big(void) ""
 161 migrate_global_state_post_load(const char *state) "loaded state: %s"
 162 migrate_global_state_pre_save(const char *state) "saved state: %s"
 163
 164 # rdma.c
 165 qemu_rdma_accept_incoming_migration(void) ""
 166 qemu_rdma_accept_incoming_migration_accepted(void) ""
 167 qemu_rdma_accept_pin_state(bool pin) "%d"
 168 qemu_rdma_accept_pin_verbsc(void *verbs) "Verbs context after listen: %p"
 169 qemu_rdma_block_for_wrid_miss(const char *wcompstr, int wcomp, const char *gcompstr, uint64_t req) "A Wanted wrid %s (%d) but got %s (%" PRIu64 ")"
 170 qemu_rdma_cleanup_disconnect(void) ""
 171 qemu_rdma_close(void) ""
 172 qemu_rdma_connect_pin_all_requested(void) ""
 173 qemu_rdma_connect_pin_all_outcome(bool pin) "%d"
 174 qemu_rdma_dest_init_trying(const char *host, const char *ip) "%s => %s"
 175 qemu_rdma_dump_gid(const char *who, const char *src, const char *dst) "%s Source GID: %s, Dest GID: %s"
 176 qemu_rdma_exchange_get_response_start(const char *desc) "CONTROL: %s receiving..."
 177 qemu_rdma_exchange_get_response_none(const char *desc, int type) "Surprise: got %s (%d)"
 178 qemu_rdma_exchange_send_issue_callback(void) ""
 179 qemu_rdma_exchange_send_waiting(const char *desc) "Waiting for response %s"
 180 qemu_rdma_exchange_send_received(const char *desc) "Response %s received."
 181 qemu_rdma_fill(size_t control_len, size_t size) "RDMA %zd of %zd bytes already in buffer"
 182 qemu_rdma_init_ram_blocks(int blocks) "Allocated %d local ram block structures"
 183 qemu_rdma_poll_recv(const char *compstr, int64_t comp, int64_t id, int sent) "completion %s #%" PRId64 " received (%" PRId64 ") left %d"
 184 qemu_rdma_poll_write(const char *compstr, int64_t comp, int left, uint64_t block, uint64_t chunk, void *local, void *remote) "completions %s (%" PRId64 ") left %d, block %" PRIu64 ", chunk: %" PRIu64 " %p %p"
 185 qemu_rdma_poll_other(const char *compstr, int64_t comp, int left) "other completion %s (%" PRId64 ") received left %d"
 186 qemu_rdma_post_send_control(const char *desc) "CONTROL: sending %s.."
 187 qemu_rdma_register_and_get_keys(uint64_t len, void *start) "Registering %" PRIu64 " bytes @ %p"
 188 qemu_rdma_registration_handle_compress(int64_t length, int index, int64_t offset) "Zapping zero chunk: %" PRId64 " bytes, index %d, offset %" PRId64
 189 qemu_rdma_registration_handle_finished(void) ""
 190 qemu_rdma_registration_handle_ram_blocks(void) ""
 191 qemu_rdma_registration_handle_ram_blocks_loop(const char *name, uint64_t offset, uint64_t length, void *local_host_addr, unsigned int src_index) "%s: @0x%" PRIx64 "/%" PRIu64 " host:@%p src_index: %u"
 192 qemu_rdma_registration_handle_register(int requests) "%d requests"
 193 qemu_rdma_registration_handle_register_loop(int req, int index, uint64_t addr, uint64_t chunks) "Registration request (%d): index %d, current_addr %" PRIu64 " chunks: %" PRIu64
 194 qemu_rdma_registration_handle_register_rkey(int rkey) "0x%x"
 195 qemu_rdma_registration_handle_unregister(int requests) "%d requests"
 196 qemu_rdma_registration_handle_unregister_loop(int count, int index, uint64_t chunk) "Unregistration request (%d): index %d, chunk %" PRIu64
 197 qemu_rdma_registration_handle_unregister_success(uint64_t chunk) "%" PRIu64
 198 qemu_rdma_registration_handle_wait(void) ""
 199 qemu_rdma_registration_start(uint64_t flags) "%" PRIu64
 200 qemu_rdma_registration_stop(uint64_t flags) "%" PRIu64
 201 qemu_rdma_registration_stop_ram(void) ""
 202 qemu_rdma_resolve_host_trying(const char *host, const char *ip) "Trying %s => %s"
 203 qemu_rdma_signal_unregister_append(uint64_t chunk, int pos) "Appending unregister chunk %" PRIu64 " at position %d"
 204 qemu_rdma_signal_unregister_already(uint64_t chunk) "Unregister chunk %" PRIu64 " already in queue"
 205 qemu_rdma_unregister_waiting_inflight(uint64_t chunk) "Cannot unregister inflight chunk: %" PRIu64
 206 qemu_rdma_unregister_waiting_proc(uint64_t chunk, int pos) "Processing unregister for chunk: %" PRIu64 " at position %d"
 207 qemu_rdma_unregister_waiting_send(uint64_t chunk) "Sending unregister for chunk: %" PRIu64
 208 qemu_rdma_unregister_waiting_complete(uint64_t chunk) "Unregister for chunk: %" PRIu64 " complete."
 209 qemu_rdma_write_flush(int sent) "sent total: %d"
 210 qemu_rdma_write_one_block(int count, int block, uint64_t chunk, uint64_t current, uint64_t len, int nb_sent, int nb_chunks) "(%d) Not clobbering: block: %d chunk %" PRIu64 " current %" PRIu64 " len %" PRIu64 " %d %d"
 211 qemu_rdma_write_one_post(uint64_t chunk, long addr, long remote, uint32_t len) "Posting chunk: %" PRIu64 ", addr: 0x%lx remote: 0x%lx, bytes %" PRIu32
 212 qemu_rdma_write_one_queue_full(void) ""
 213 qemu_rdma_write_one_recvregres(int mykey, int theirkey, uint64_t chunk) "Received registration result: my key: 0x%x their key 0x%x, chunk %" PRIu64
 214 qemu_rdma_write_one_sendreg(uint64_t chunk, int len, int index, int64_t offset) "Sending registration request chunk %" PRIu64 " for %d bytes, index: %d, offset: %" PRId64
 215 qemu_rdma_write_one_top(uint64_t chunks, uint64_t size) "Writing %" PRIu64 " chunks, (%" PRIu64 " MB)"
 216 qemu_rdma_write_one_zero(uint64_t chunk, int len, int index, int64_t offset) "Entire chunk is zero, sending compress: %" PRIu64 " for %d bytes, index: %d, offset: %" PRId64
 217 rdma_add_block(const char *block_name, int block, uint64_t addr, uint64_t offset, uint64_t len, uint64_t end, uint64_t bits, int chunks) "Added Block: '%s':%d, addr: %" PRIu64 ", offset: %" PRIu64 " length: %" PRIu64 " end: %" PRIu64 " bits %" PRIu64 " chunks %d"
 218 rdma_block_notification_handle(const char *name, int index) "%s at %d"
 219 rdma_delete_block(void *block, uint64_t addr, uint64_t offset, uint64_t len, uint64_t end, uint64_t bits, int chunks) "Deleted Block: %p, addr: %" PRIu64 ", offset: %" PRIu64 " length: %" PRIu64 " end: %" PRIu64 " bits %" PRIu64 " chunks %d"
 220 rdma_start_incoming_migration(void) ""
 221 rdma_start_incoming_migration_after_dest_init(void) ""
 222 rdma_start_incoming_migration_after_rdma_listen(void) ""
 223 rdma_start_outgoing_migration_after_rdma_connect(void) ""
 224 rdma_start_outgoing_migration_after_rdma_source_init(void) ""
 225
 226 # postcopy-ram.c
 227 postcopy_discard_send_finish(const char *ramblock, int nwords, int ncmds) "%s mask words sent=%d in %d commands"
 228 postcopy_discard_send_range(const char *ramblock, unsigned long start, unsigned long length) "%s:%lx/%lx"
 229 postcopy_cleanup_range(const char *ramblock, void *host_addr, size_t offset, size_t length) "%s: %p offset=0x%zx length=0x%zx"
 230 postcopy_init_range(const char *ramblock, void *host_addr, size_t offset, size_t length) "%s: %p offset=0x%zx length=0x%zx"
 231 postcopy_nhp_range(const char *ramblock, void *host_addr, size_t offset, size_t length) "%s: %p offset=0x%zx length=0x%zx"
 232 postcopy_place_page(void *host_addr) "host=%p"
 233 postcopy_place_page_zero(void *host_addr) "host=%p"
 234 postcopy_ram_enable_notify(void) ""
 235 mark_postcopy_blocktime_begin(uint64_t addr, void *dd, uint32_t time, int cpu, int received) "addr: 0x%" PRIx64 ", dd: %p, time: %u, cpu: %d, already_received: %d"
 236 mark_postcopy_blocktime_end(uint64_t addr, void *dd, uint32_t time, int affected_cpu) "addr: 0x%" PRIx64 ", dd: %p, time: %u, affected_cpu: %d"
 237 postcopy_pause_fault_thread(void) ""
 238 postcopy_pause_fault_thread_continued(void) ""
 239 postcopy_ram_fault_thread_entry(void) ""
 240 postcopy_ram_fault_thread_exit(void) ""
 241 postcopy_ram_fault_thread_fds_core(int baseufd, int quitfd) "ufd: %d quitfd: %d"
 242 postcopy_ram_fault_thread_fds_extra(size_t index, const char *name, int fd) "%zd/%s: %d"
 243 postcopy_ram_fault_thread_quit(void) ""
 244 postcopy_ram_fault_thread_request(uint64_t hostaddr, const char *ramblock, size_t offset, uint32_t pid) "Request for HVA=0x%" PRIx64 " rb=%s offset=0x%zx pid=%u"
 245 postcopy_ram_incoming_cleanup_closeuf(void) ""
 246 postcopy_ram_incoming_cleanup_entry(void) ""
 247 postcopy_ram_incoming_cleanup_exit(void) ""
 248 postcopy_ram_incoming_cleanup_join(void) ""
 249 postcopy_ram_incoming_cleanup_blocktime(uint64_t total) "total blocktime %" PRIu64
 250 postcopy_request_shared_page(const char *sharer, const char *rb, uint64_t rb_offset) "for %s in %s offset 0x%"PRIx64
 251 postcopy_request_shared_page_present(const char *sharer, const char *rb, uint64_t rb_offset) "%s already %s offset 0x%"PRIx64
 252 postcopy_wake_shared(uint64_t client_addr, const char *rb) "at 0x%"PRIx64" in %s"
 253
 254 get_mem_fault_cpu_index(int cpu, uint32_t pid) "cpu: %d, pid: %u"
 255
 256 # exec.c
 257 migration_exec_outgoing(const char *cmd) "cmd=%s"
 258 migration_exec_incoming(const char *cmd) "cmd=%s"
 259
 260 # fd.c
 261 migration_fd_outgoing(int fd) "fd=%d"
 262 migration_fd_incoming(int fd) "fd=%d"
 263
 264 # socket.c
 265 migration_socket_incoming_accepted(void) ""
 266 migration_socket_outgoing_connected(const char *hostname) "hostname=%s"
 267 migration_socket_outgoing_error(const char *err) "error=%s"
 268
 269 # tls.c
 270 migration_tls_outgoing_handshake_start(const char *hostname) "hostname=%s"
 271 migration_tls_outgoing_handshake_error(const char *err) "err=%s"
 272 migration_tls_outgoing_handshake_complete(void) ""
 273 migration_tls_incoming_handshake_start(void) ""
 274 migration_tls_incoming_handshake_error(const char *err) "err=%s"
 275 migration_tls_incoming_handshake_complete(void) ""
 276
 277 # colo.c
 278 colo_vm_state_change(const char *old, const char *new) "Change '%s' => '%s'"
 279 colo_send_message(const char *msg) "Send '%s' message"
 280 colo_receive_message(const char *msg) "Receive '%s' message"
 281
 282 # colo-failover.c
 283 colo_failover_set_state(const char *new_state) "new state %s"
 284
 285 # block-dirty-bitmap.c
 286 send_bitmap_header_enter(void) ""
 287 send_bitmap_bits(uint32_t flags, uint64_t start_sector, uint32_t nr_sectors, uint64_t data_size) "flags: 0x%x, start_sector: %" PRIu64 ", nr_sectors: %" PRIu32 ", data_size: %" PRIu64
 288 dirty_bitmap_save_iterate(int in_postcopy) "in postcopy: %d"
 289 dirty_bitmap_save_complete_enter(void) ""
 290 dirty_bitmap_save_complete_finish(void) ""
 291 dirty_bitmap_save_pending(uint64_t pending, uint64_t max_size) "pending %" PRIu64 " max: %" PRIu64
 292 dirty_bitmap_load_complete(void) ""
 293 dirty_bitmap_load_bits_enter(uint64_t first_sector, uint32_t nr_sectors) "chunk: %" PRIu64 " %" PRIu32
 294 dirty_bitmap_load_bits_zeroes(void) ""
 295 dirty_bitmap_load_header(uint32_t flags) "flags 0x%x"
 296 dirty_bitmap_load_enter(void) ""
 297 dirty_bitmap_load_success(void) ""