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