Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 1 | /* |
| 2 | * Simple trace backend |
| 3 | * |
| 4 | * Copyright IBM, Corp. 2010 |
| 5 | * |
| 6 | * This work is licensed under the terms of the GNU GPL, version 2. See |
| 7 | * the COPYING file in the top-level directory. |
| 8 | * |
| 9 | */ |
| 10 | |
Peter Maydell | d38ea87 | 2016-01-29 17:50:05 +0000 | [diff] [blame] | 11 | #include "qemu/osdep.h" |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 12 | #ifndef _WIN32 |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 13 | #include <pthread.h> |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 14 | #endif |
Paolo Bonzini | 1de7afc | 2012-12-17 18:20:00 +0100 | [diff] [blame] | 15 | #include "qemu/timer.h" |
Lluís | e485897 | 2011-08-31 20:31:03 +0200 | [diff] [blame] | 16 | #include "trace/control.h" |
Lluís Vilanova | b618c28 | 2014-01-14 16:52:55 +0100 | [diff] [blame] | 17 | #include "trace/simple.h" |
Alistair Francis | 2ab4b13 | 2017-09-11 12:52:50 -0700 | [diff] [blame] | 18 | #include "qemu/error-report.h" |
Markus Armbruster | ba4912c | 2019-04-17 21:17:50 +0200 | [diff] [blame] | 19 | #include "qemu/qemu-print.h" |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 20 | |
Daniel P. Berrange | ef4c9fc | 2016-10-04 14:35:49 +0100 | [diff] [blame] | 21 | /** Trace file header event ID, picked to avoid conflict with real event IDs */ |
| 22 | #define HEADER_EVENT_ID (~(uint64_t)0) |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 23 | |
| 24 | /** Trace file magic number */ |
| 25 | #define HEADER_MAGIC 0xf2b177cb0aa429b4ULL |
| 26 | |
| 27 | /** Trace file version number, bump if format changes */ |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 28 | #define HEADER_VERSION 4 |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 29 | |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 30 | /** Records were dropped event ID */ |
| 31 | #define DROPPED_EVENT_ID (~(uint64_t)0 - 1) |
| 32 | |
| 33 | /** Trace record is valid */ |
| 34 | #define TRACE_RECORD_VALID ((uint64_t)1 << 63) |
| 35 | |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 36 | /* |
| 37 | * Trace records are written out by a dedicated thread. The thread waits for |
| 38 | * records to become available, writes them out, and then waits again. |
| 39 | */ |
Daniel P. Berrangé | e7b3af8 | 2018-05-04 15:34:46 +0100 | [diff] [blame] | 40 | static GMutex trace_lock; |
| 41 | static GCond trace_available_cond; |
| 42 | static GCond trace_empty_cond; |
Stefan Hajnoczi | 4a0e671 | 2013-02-12 14:34:05 +0100 | [diff] [blame] | 43 | |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 44 | static bool trace_available; |
| 45 | static bool trace_writeout_enabled; |
| 46 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 47 | enum { |
| 48 | TRACE_BUF_LEN = 4096 * 64, |
| 49 | TRACE_BUF_FLUSH_THRESHOLD = TRACE_BUF_LEN / 4, |
| 50 | }; |
| 51 | |
| 52 | uint8_t trace_buf[TRACE_BUF_LEN]; |
Stefan Hajnoczi | 30d9408 | 2013-02-12 14:34:04 +0100 | [diff] [blame] | 53 | static volatile gint trace_idx; |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 54 | static unsigned int writeout_idx; |
Stefan Hajnoczi | 30d9408 | 2013-02-12 14:34:04 +0100 | [diff] [blame] | 55 | static volatile gint dropped_events; |
Stefan Hajnoczi | 26896cb | 2014-05-07 19:24:10 +0200 | [diff] [blame] | 56 | static uint32_t trace_pid; |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 57 | static FILE *trace_fp; |
Stefan Weil | 4552e41 | 2012-08-13 21:51:16 +0200 | [diff] [blame] | 58 | static char *trace_file_name; |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 59 | |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 60 | #define TRACE_RECORD_TYPE_MAPPING 0 |
| 61 | #define TRACE_RECORD_TYPE_EVENT 1 |
| 62 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 63 | /* * Trace buffer entry */ |
| 64 | typedef struct { |
Daniel P. Berrange | ef4c9fc | 2016-10-04 14:35:49 +0100 | [diff] [blame] | 65 | uint64_t event; /* event ID value */ |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 66 | uint64_t timestamp_ns; |
| 67 | uint32_t length; /* in bytes */ |
Stefan Hajnoczi | 26896cb | 2014-05-07 19:24:10 +0200 | [diff] [blame] | 68 | uint32_t pid; |
Markus Armbruster | fb3a508 | 2013-01-25 16:43:37 +0100 | [diff] [blame] | 69 | uint64_t arguments[]; |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 70 | } TraceRecord; |
| 71 | |
| 72 | typedef struct { |
| 73 | uint64_t header_event_id; /* HEADER_EVENT_ID */ |
| 74 | uint64_t header_magic; /* HEADER_MAGIC */ |
| 75 | uint64_t header_version; /* HEADER_VERSION */ |
Harsh Prateek Bora | 8ae601e | 2012-07-20 18:52:12 +0530 | [diff] [blame] | 76 | } TraceLogHeader; |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 77 | |
| 78 | |
| 79 | static void read_from_buffer(unsigned int idx, void *dataptr, size_t size); |
| 80 | static unsigned int write_to_buffer(unsigned int idx, void *dataptr, size_t size); |
| 81 | |
| 82 | static void clear_buffer_range(unsigned int idx, size_t len) |
| 83 | { |
| 84 | uint32_t num = 0; |
| 85 | while (num < len) { |
| 86 | if (idx >= TRACE_BUF_LEN) { |
| 87 | idx = idx % TRACE_BUF_LEN; |
| 88 | } |
| 89 | trace_buf[idx++] = 0; |
| 90 | num++; |
| 91 | } |
| 92 | } |
Stefan Hajnoczi | c5ceb52 | 2010-07-13 09:26:33 +0100 | [diff] [blame] | 93 | /** |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 94 | * Read a trace record from the trace buffer |
| 95 | * |
| 96 | * @idx Trace buffer index |
| 97 | * @record Trace record to fill |
| 98 | * |
| 99 | * Returns false if the record is not valid. |
Stefan Hajnoczi | c5ceb52 | 2010-07-13 09:26:33 +0100 | [diff] [blame] | 100 | */ |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 101 | static bool get_trace_record(unsigned int idx, TraceRecord **recordptr) |
Prerna Saxena | 9410b56 | 2010-07-13 09:26:32 +0100 | [diff] [blame] | 102 | { |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 103 | uint64_t event_flag = 0; |
| 104 | TraceRecord record; |
| 105 | /* read the event flag to see if its a valid record */ |
| 106 | read_from_buffer(idx, &record, sizeof(event_flag)); |
| 107 | |
| 108 | if (!(record.event & TRACE_RECORD_VALID)) { |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 109 | return false; |
Prerna Saxena | 9410b56 | 2010-07-13 09:26:32 +0100 | [diff] [blame] | 110 | } |
| 111 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 112 | smp_rmb(); /* read memory barrier before accessing record */ |
| 113 | /* read the record header to know record length */ |
| 114 | read_from_buffer(idx, &record, sizeof(TraceRecord)); |
Stefan Weil | cb8d4c8 | 2016-03-23 15:59:57 +0100 | [diff] [blame] | 115 | *recordptr = malloc(record.length); /* don't use g_malloc, can deadlock when traced */ |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 116 | /* make a copy of record to avoid being overwritten */ |
| 117 | read_from_buffer(idx, *recordptr, record.length); |
| 118 | smp_rmb(); /* memory barrier before clearing valid flag */ |
| 119 | (*recordptr)->event &= ~TRACE_RECORD_VALID; |
| 120 | /* clear the trace buffer range for consumed record otherwise any byte |
| 121 | * with its MSB set may be considered as a valid event id when the writer |
| 122 | * thread crosses this range of buffer again. |
| 123 | */ |
| 124 | clear_buffer_range(idx, record.length); |
Stefan Hajnoczi | c5ceb52 | 2010-07-13 09:26:33 +0100 | [diff] [blame] | 125 | return true; |
Prerna Saxena | 9410b56 | 2010-07-13 09:26:32 +0100 | [diff] [blame] | 126 | } |
| 127 | |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 128 | /** |
| 129 | * Kick writeout thread |
| 130 | * |
| 131 | * @wait Whether to wait for writeout thread to complete |
| 132 | */ |
| 133 | static void flush_trace_file(bool wait) |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 134 | { |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 135 | g_mutex_lock(&trace_lock); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 136 | trace_available = true; |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 137 | g_cond_signal(&trace_available_cond); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 138 | |
| 139 | if (wait) { |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 140 | g_cond_wait(&trace_empty_cond, &trace_lock); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 141 | } |
| 142 | |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 143 | g_mutex_unlock(&trace_lock); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 144 | } |
| 145 | |
| 146 | static void wait_for_trace_records_available(void) |
| 147 | { |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 148 | g_mutex_lock(&trace_lock); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 149 | while (!(trace_available && trace_writeout_enabled)) { |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 150 | g_cond_signal(&trace_empty_cond); |
| 151 | g_cond_wait(&trace_available_cond, &trace_lock); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 152 | } |
| 153 | trace_available = false; |
Michael Tokarev | 86946a2 | 2014-05-08 12:30:46 +0400 | [diff] [blame] | 154 | g_mutex_unlock(&trace_lock); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 155 | } |
| 156 | |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 157 | static gpointer writeout_thread(gpointer opaque) |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 158 | { |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 159 | TraceRecord *recordptr; |
| 160 | union { |
| 161 | TraceRecord rec; |
| 162 | uint8_t bytes[sizeof(TraceRecord) + sizeof(uint64_t)]; |
| 163 | } dropped; |
| 164 | unsigned int idx = 0; |
Markus Armbruster | fb3a508 | 2013-01-25 16:43:37 +0100 | [diff] [blame] | 165 | int dropped_count; |
Blue Swirl | 0caf448 | 2011-07-23 21:21:14 +0000 | [diff] [blame] | 166 | size_t unused __attribute__ ((unused)); |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 167 | uint64_t type = TRACE_RECORD_TYPE_EVENT; |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 168 | |
| 169 | for (;;) { |
| 170 | wait_for_trace_records_available(); |
| 171 | |
Markus Armbruster | e722d70 | 2013-01-25 16:43:38 +0100 | [diff] [blame] | 172 | if (g_atomic_int_get(&dropped_events)) { |
Larry Dewey | 7ff5920 | 2018-11-27 12:08:49 -0700 | [diff] [blame] | 173 | dropped.rec.event = DROPPED_EVENT_ID; |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 174 | dropped.rec.timestamp_ns = get_clock(); |
Larry Dewey | 7ff5920 | 2018-11-27 12:08:49 -0700 | [diff] [blame] | 175 | dropped.rec.length = sizeof(TraceRecord) + sizeof(uint64_t); |
Stefan Hajnoczi | 26896cb | 2014-05-07 19:24:10 +0200 | [diff] [blame] | 176 | dropped.rec.pid = trace_pid; |
Markus Armbruster | b6b2c96 | 2013-01-25 16:43:39 +0100 | [diff] [blame] | 177 | do { |
Markus Armbruster | e722d70 | 2013-01-25 16:43:38 +0100 | [diff] [blame] | 178 | dropped_count = g_atomic_int_get(&dropped_events); |
Markus Armbruster | b6b2c96 | 2013-01-25 16:43:39 +0100 | [diff] [blame] | 179 | } while (!g_atomic_int_compare_and_exchange(&dropped_events, |
| 180 | dropped_count, 0)); |
Markus Armbruster | fb3a508 | 2013-01-25 16:43:37 +0100 | [diff] [blame] | 181 | dropped.rec.arguments[0] = dropped_count; |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 182 | unused = fwrite(&type, sizeof(type), 1, trace_fp); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 183 | unused = fwrite(&dropped.rec, dropped.rec.length, 1, trace_fp); |
Stefan Hajnoczi | c5ceb52 | 2010-07-13 09:26:33 +0100 | [diff] [blame] | 184 | } |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 185 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 186 | while (get_trace_record(idx, &recordptr)) { |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 187 | unused = fwrite(&type, sizeof(type), 1, trace_fp); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 188 | unused = fwrite(recordptr, recordptr->length, 1, trace_fp); |
| 189 | writeout_idx += recordptr->length; |
Stefan Weil | cb8d4c8 | 2016-03-23 15:59:57 +0100 | [diff] [blame] | 190 | free(recordptr); /* don't use g_free, can deadlock when traced */ |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 191 | idx = writeout_idx % TRACE_BUF_LEN; |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 192 | } |
| 193 | |
| 194 | fflush(trace_fp); |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 195 | } |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 196 | return NULL; |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 197 | } |
| 198 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 199 | void trace_record_write_u64(TraceBufferRecord *rec, uint64_t val) |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 200 | { |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 201 | rec->rec_off = write_to_buffer(rec->rec_off, &val, sizeof(uint64_t)); |
| 202 | } |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 203 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 204 | void trace_record_write_str(TraceBufferRecord *rec, const char *s, uint32_t slen) |
| 205 | { |
| 206 | /* Write string length first */ |
| 207 | rec->rec_off = write_to_buffer(rec->rec_off, &slen, sizeof(slen)); |
| 208 | /* Write actual string now */ |
| 209 | rec->rec_off = write_to_buffer(rec->rec_off, (void*)s, slen); |
| 210 | } |
| 211 | |
Daniel P. Berrange | ef4c9fc | 2016-10-04 14:35:49 +0100 | [diff] [blame] | 212 | int trace_record_start(TraceBufferRecord *rec, uint32_t event, size_t datasize) |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 213 | { |
| 214 | unsigned int idx, rec_off, old_idx, new_idx; |
| 215 | uint32_t rec_len = sizeof(TraceRecord) + datasize; |
Lluís Vilanova | 60481e2 | 2013-03-05 14:47:55 +0100 | [diff] [blame] | 216 | uint64_t event_u64 = event; |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 217 | uint64_t timestamp_ns = get_clock(); |
| 218 | |
Markus Armbruster | b6b2c96 | 2013-01-25 16:43:39 +0100 | [diff] [blame] | 219 | do { |
Markus Armbruster | e722d70 | 2013-01-25 16:43:38 +0100 | [diff] [blame] | 220 | old_idx = g_atomic_int_get(&trace_idx); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 221 | smp_rmb(); |
| 222 | new_idx = old_idx + rec_len; |
| 223 | |
| 224 | if (new_idx - writeout_idx > TRACE_BUF_LEN) { |
| 225 | /* Trace Buffer Full, Event dropped ! */ |
Markus Armbruster | fb3a508 | 2013-01-25 16:43:37 +0100 | [diff] [blame] | 226 | g_atomic_int_inc(&dropped_events); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 227 | return -ENOSPC; |
| 228 | } |
Markus Armbruster | b6b2c96 | 2013-01-25 16:43:39 +0100 | [diff] [blame] | 229 | } while (!g_atomic_int_compare_and_exchange(&trace_idx, old_idx, new_idx)); |
Prerna Saxena | 22890ab | 2010-06-24 17:04:53 +0530 | [diff] [blame] | 230 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 231 | idx = old_idx % TRACE_BUF_LEN; |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 232 | |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 233 | rec_off = idx; |
Lluís Vilanova | 60481e2 | 2013-03-05 14:47:55 +0100 | [diff] [blame] | 234 | rec_off = write_to_buffer(rec_off, &event_u64, sizeof(event_u64)); |
Harsh Prateek Bora | 83d35d3 | 2012-07-20 18:52:13 +0530 | [diff] [blame] | 235 | rec_off = write_to_buffer(rec_off, ×tamp_ns, sizeof(timestamp_ns)); |
| 236 | rec_off = write_to_buffer(rec_off, &rec_len, sizeof(rec_len)); |
Stefan Hajnoczi | 26896cb | 2014-05-07 19:24:10 +0200 | [diff] [blame] | 237 | rec_off = write_to_buffer(rec_off, &trace_pid, sizeof(trace_pid)); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 238 | |
| 239 | rec->tbuf_idx = idx; |
| 240 | rec->rec_off = (idx + sizeof(TraceRecord)) % TRACE_BUF_LEN; |
| 241 | return 0; |
| 242 | } |
| 243 | |
| 244 | static void read_from_buffer(unsigned int idx, void *dataptr, size_t size) |
| 245 | { |
| 246 | uint8_t *data_ptr = dataptr; |
| 247 | uint32_t x = 0; |
| 248 | while (x < size) { |
| 249 | if (idx >= TRACE_BUF_LEN) { |
| 250 | idx = idx % TRACE_BUF_LEN; |
| 251 | } |
| 252 | data_ptr[x++] = trace_buf[idx++]; |
| 253 | } |
| 254 | } |
| 255 | |
| 256 | static unsigned int write_to_buffer(unsigned int idx, void *dataptr, size_t size) |
| 257 | { |
| 258 | uint8_t *data_ptr = dataptr; |
| 259 | uint32_t x = 0; |
| 260 | while (x < size) { |
| 261 | if (idx >= TRACE_BUF_LEN) { |
| 262 | idx = idx % TRACE_BUF_LEN; |
| 263 | } |
| 264 | trace_buf[idx++] = data_ptr[x++]; |
| 265 | } |
| 266 | return idx; /* most callers wants to know where to write next */ |
| 267 | } |
| 268 | |
| 269 | void trace_record_finish(TraceBufferRecord *rec) |
| 270 | { |
Harsh Prateek Bora | db8894f | 2012-07-20 18:52:15 +0530 | [diff] [blame] | 271 | TraceRecord record; |
| 272 | read_from_buffer(rec->tbuf_idx, &record, sizeof(TraceRecord)); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 273 | smp_wmb(); /* write barrier before marking as valid */ |
Harsh Prateek Bora | db8894f | 2012-07-20 18:52:15 +0530 | [diff] [blame] | 274 | record.event |= TRACE_RECORD_VALID; |
| 275 | write_to_buffer(rec->tbuf_idx, &record, sizeof(TraceRecord)); |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 276 | |
Stefan Hajnoczi | 30d9408 | 2013-02-12 14:34:04 +0100 | [diff] [blame] | 277 | if (((unsigned int)g_atomic_int_get(&trace_idx) - writeout_idx) |
Markus Armbruster | e722d70 | 2013-01-25 16:43:38 +0100 | [diff] [blame] | 278 | > TRACE_BUF_FLUSH_THRESHOLD) { |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 279 | flush_trace_file(false); |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 280 | } |
| 281 | } |
| 282 | |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 283 | static int st_write_event_mapping(void) |
| 284 | { |
| 285 | uint64_t type = TRACE_RECORD_TYPE_MAPPING; |
| 286 | TraceEventIter iter; |
| 287 | TraceEvent *ev; |
| 288 | |
| 289 | trace_event_iter_init(&iter, NULL); |
| 290 | while ((ev = trace_event_iter_next(&iter)) != NULL) { |
| 291 | uint64_t id = trace_event_get_id(ev); |
| 292 | const char *name = trace_event_get_name(ev); |
| 293 | uint32_t len = strlen(name); |
| 294 | if (fwrite(&type, sizeof(type), 1, trace_fp) != 1 || |
| 295 | fwrite(&id, sizeof(id), 1, trace_fp) != 1 || |
| 296 | fwrite(&len, sizeof(len), 1, trace_fp) != 1 || |
| 297 | fwrite(name, len, 1, trace_fp) != 1) { |
| 298 | return -1; |
| 299 | } |
| 300 | } |
| 301 | |
| 302 | return 0; |
| 303 | } |
| 304 | |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 305 | void st_set_trace_file_enabled(bool enable) |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 306 | { |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 307 | if (enable == !!trace_fp) { |
| 308 | return; /* no change */ |
| 309 | } |
| 310 | |
| 311 | /* Halt trace writeout */ |
| 312 | flush_trace_file(true); |
| 313 | trace_writeout_enabled = false; |
| 314 | flush_trace_file(true); |
| 315 | |
| 316 | if (enable) { |
Harsh Prateek Bora | 8ae601e | 2012-07-20 18:52:12 +0530 | [diff] [blame] | 317 | static const TraceLogHeader header = { |
Harsh Prateek Bora | 62bab73 | 2012-07-18 15:15:59 +0530 | [diff] [blame] | 318 | .header_event_id = HEADER_EVENT_ID, |
| 319 | .header_magic = HEADER_MAGIC, |
| 320 | /* Older log readers will check for version at next location */ |
| 321 | .header_version = HEADER_VERSION, |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 322 | }; |
| 323 | |
Stefan Hajnoczi | 6c2a407 | 2011-09-05 18:31:21 +0100 | [diff] [blame] | 324 | trace_fp = fopen(trace_file_name, "wb"); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 325 | if (!trace_fp) { |
| 326 | return; |
| 327 | } |
| 328 | |
Daniel P. Berrange | 7f1b588 | 2016-10-04 14:35:50 +0100 | [diff] [blame] | 329 | if (fwrite(&header, sizeof header, 1, trace_fp) != 1 || |
| 330 | st_write_event_mapping() < 0) { |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 331 | fclose(trace_fp); |
| 332 | trace_fp = NULL; |
| 333 | return; |
| 334 | } |
| 335 | |
| 336 | /* Resume trace writeout */ |
| 337 | trace_writeout_enabled = true; |
| 338 | flush_trace_file(false); |
| 339 | } else { |
| 340 | fclose(trace_fp); |
| 341 | trace_fp = NULL; |
| 342 | } |
| 343 | } |
| 344 | |
| 345 | /** |
| 346 | * Set the name of a trace file |
| 347 | * |
| 348 | * @file The trace file name or NULL for the default name-<pid> set at |
| 349 | * config time |
| 350 | */ |
Paolo Bonzini | 41fc57e | 2016-01-07 16:55:24 +0300 | [diff] [blame] | 351 | void st_set_trace_file(const char *file) |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 352 | { |
| 353 | st_set_trace_file_enabled(false); |
| 354 | |
Stefan Weil | 4552e41 | 2012-08-13 21:51:16 +0200 | [diff] [blame] | 355 | g_free(trace_file_name); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 356 | |
| 357 | if (!file) { |
Stefan Weil | 857a0e3 | 2015-03-11 22:08:56 +0100 | [diff] [blame] | 358 | /* Type cast needed for Windows where getpid() returns an int. */ |
| 359 | trace_file_name = g_strdup_printf(CONFIG_TRACE_FILE, (pid_t)getpid()); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 360 | } else { |
Stefan Weil | 4552e41 | 2012-08-13 21:51:16 +0200 | [diff] [blame] | 361 | trace_file_name = g_strdup_printf("%s", file); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 362 | } |
| 363 | |
| 364 | st_set_trace_file_enabled(true); |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 365 | } |
| 366 | |
Markus Armbruster | ba4912c | 2019-04-17 21:17:50 +0200 | [diff] [blame] | 367 | void st_print_trace_file_status(void) |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 368 | { |
Markus Armbruster | ba4912c | 2019-04-17 21:17:50 +0200 | [diff] [blame] | 369 | qemu_printf("Trace file \"%s\" %s.\n", |
| 370 | trace_file_name, trace_fp ? "on" : "off"); |
Stefan Hajnoczi | 26f7227 | 2010-05-22 19:24:51 +0100 | [diff] [blame] | 371 | } |
Prerna Saxena | 22890ab | 2010-06-24 17:04:53 +0530 | [diff] [blame] | 372 | |
Lluís | fc76410 | 2011-08-31 20:31:18 +0200 | [diff] [blame] | 373 | void st_flush_trace_buffer(void) |
| 374 | { |
| 375 | flush_trace_file(true); |
| 376 | } |
| 377 | |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 378 | /* Helper function to create a thread with signals blocked. Use glib's |
| 379 | * portable threads since QEMU abstractions cannot be used due to reentrancy in |
| 380 | * the tracer. Also note the signal masking on POSIX hosts so that the thread |
| 381 | * does not steal signals when the rest of the program wants them blocked. |
| 382 | */ |
| 383 | static GThread *trace_thread_create(GThreadFunc fn) |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 384 | { |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 385 | GThread *thread; |
| 386 | #ifndef _WIN32 |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 387 | sigset_t set, oldset; |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 388 | |
| 389 | sigfillset(&set); |
| 390 | pthread_sigmask(SIG_SETMASK, &set, &oldset); |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 391 | #endif |
Stefan Hajnoczi | 4a0e671 | 2013-02-12 14:34:05 +0100 | [diff] [blame] | 392 | |
Stefan Hajnoczi | 4a0e671 | 2013-02-12 14:34:05 +0100 | [diff] [blame] | 393 | thread = g_thread_new("trace-thread", fn, NULL); |
Stefan Hajnoczi | 4a0e671 | 2013-02-12 14:34:05 +0100 | [diff] [blame] | 394 | |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 395 | #ifndef _WIN32 |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 396 | pthread_sigmask(SIG_SETMASK, &oldset, NULL); |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 397 | #endif |
Stefan Hajnoczi | 0b5538c | 2011-02-26 18:38:39 +0000 | [diff] [blame] | 398 | |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 399 | return thread; |
| 400 | } |
| 401 | |
Paolo Bonzini | 41fc57e | 2016-01-07 16:55:24 +0300 | [diff] [blame] | 402 | bool st_init(void) |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 403 | { |
| 404 | GThread *thread; |
| 405 | |
Stefan Hajnoczi | 26896cb | 2014-05-07 19:24:10 +0200 | [diff] [blame] | 406 | trace_pid = getpid(); |
| 407 | |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 408 | thread = trace_thread_create(writeout_thread); |
| 409 | if (!thread) { |
Alistair Francis | 2ab4b13 | 2017-09-11 12:52:50 -0700 | [diff] [blame] | 410 | warn_report("unable to initialize simple trace backend"); |
Stefan Hajnoczi | 85aff15 | 2011-09-05 08:30:17 +0100 | [diff] [blame] | 411 | return false; |
| 412 | } |
| 413 | |
| 414 | atexit(st_flush_trace_buffer); |
Stefan Hajnoczi | 31d3c9b | 2011-03-13 20:14:30 +0000 | [diff] [blame] | 415 | return true; |
Prerna Saxena | 22890ab | 2010-06-24 17:04:53 +0530 | [diff] [blame] | 416 | } |