Commit | Line | Data |
---|---|---|
f9cda048 MW |
1 | /* |
2 | * Copyright © 2014-2017 Intel Corporation | |
3 | * | |
4 | * Permission is hereby granted, free of charge, to any person obtaining a | |
5 | * copy of this software and associated documentation files (the "Software"), | |
6 | * to deal in the Software without restriction, including without limitation | |
7 | * the rights to use, copy, modify, merge, publish, distribute, sublicense, | |
8 | * and/or sell copies of the Software, and to permit persons to whom the | |
9 | * Software is furnished to do so, subject to the following conditions: | |
10 | * | |
11 | * The above copyright notice and this permission notice (including the next | |
12 | * paragraph) shall be included in all copies or substantial portions of the | |
13 | * Software. | |
14 | * | |
15 | * THE SOFTWARE IS PROVIDED "AS IS", WITHOUT WARRANTY OF ANY KIND, EXPRESS OR | |
16 | * IMPLIED, INCLUDING BUT NOT LIMITED TO THE WARRANTIES OF MERCHANTABILITY, | |
17 | * FITNESS FOR A PARTICULAR PURPOSE AND NONINFRINGEMENT. IN NO EVENT SHALL | |
18 | * THE AUTHORS OR COPYRIGHT HOLDERS BE LIABLE FOR ANY CLAIM, DAMAGES OR OTHER | |
19 | * LIABILITY, WHETHER IN AN ACTION OF CONTRACT, TORT OR OTHERWISE, ARISING | |
20 | * FROM, OUT OF OR IN CONNECTION WITH THE SOFTWARE OR THE USE OR OTHER DEALINGS | |
21 | * IN THE SOFTWARE. | |
22 | * | |
23 | */ | |
d62e2bf3 | 24 | |
f9cda048 MW |
25 | #include <linux/debugfs.h> |
26 | #include <linux/relay.h> | |
d62e2bf3 MW |
27 | |
28 | #include "intel_guc_log.h" | |
f9cda048 MW |
29 | #include "i915_drv.h" |
30 | ||
31 | static void guc_log_capture_logs(struct intel_guc *guc); | |
32 | ||
33 | /** | |
34 | * DOC: GuC firmware log | |
35 | * | |
0ed87953 | 36 | * Firmware log is enabled by setting i915.guc_log_level to the positive level. |
f9cda048 MW |
37 | * Log data is printed out via reading debugfs i915_guc_log_dump. Reading from |
38 | * i915_guc_load_status will print out firmware loading status and scratch | |
39 | * registers value. | |
f9cda048 MW |
40 | */ |
41 | ||
42 | static int guc_log_flush_complete(struct intel_guc *guc) | |
43 | { | |
44 | u32 action[] = { | |
45 | INTEL_GUC_ACTION_LOG_BUFFER_FILE_FLUSH_COMPLETE | |
46 | }; | |
47 | ||
48 | return intel_guc_send(guc, action, ARRAY_SIZE(action)); | |
49 | } | |
50 | ||
51 | static int guc_log_flush(struct intel_guc *guc) | |
52 | { | |
53 | u32 action[] = { | |
54 | INTEL_GUC_ACTION_FORCE_LOG_BUFFER_FLUSH, | |
55 | 0 | |
56 | }; | |
57 | ||
58 | return intel_guc_send(guc, action, ARRAY_SIZE(action)); | |
59 | } | |
60 | ||
35fe703c | 61 | static int guc_log_control(struct intel_guc *guc, bool enable, u32 verbosity) |
f9cda048 | 62 | { |
35fe703c | 63 | union guc_log_control control_val = { |
401d0ae3 AM |
64 | { |
65 | .logging_enabled = enable, | |
66 | .verbosity = verbosity, | |
67 | }, | |
35fe703c | 68 | }; |
f9cda048 MW |
69 | u32 action[] = { |
70 | INTEL_GUC_ACTION_UK_LOG_ENABLE_LOGGING, | |
35fe703c | 71 | control_val.value |
f9cda048 MW |
72 | }; |
73 | ||
74 | return intel_guc_send(guc, action, ARRAY_SIZE(action)); | |
75 | } | |
76 | ||
f9cda048 MW |
77 | /* |
78 | * Sub buffer switch callback. Called whenever relay has to switch to a new | |
79 | * sub buffer, relay stays on the same sub buffer if 0 is returned. | |
80 | */ | |
81 | static int subbuf_start_callback(struct rchan_buf *buf, | |
82 | void *subbuf, | |
83 | void *prev_subbuf, | |
84 | size_t prev_padding) | |
85 | { | |
2fcf0680 SAK |
86 | /* |
87 | * Use no-overwrite mode by default, where relay will stop accepting | |
f9cda048 MW |
88 | * new data if there are no empty sub buffers left. |
89 | * There is no strict synchronization enforced by relay between Consumer | |
90 | * and Producer. In overwrite mode, there is a possibility of getting | |
91 | * inconsistent/garbled data, the producer could be writing on to the | |
92 | * same sub buffer from which Consumer is reading. This can't be avoided | |
93 | * unless Consumer is fast enough and can always run in tandem with | |
94 | * Producer. | |
95 | */ | |
96 | if (relay_buf_full(buf)) | |
97 | return 0; | |
98 | ||
99 | return 1; | |
100 | } | |
101 | ||
102 | /* | |
103 | * file_create() callback. Creates relay file in debugfs. | |
104 | */ | |
105 | static struct dentry *create_buf_file_callback(const char *filename, | |
106 | struct dentry *parent, | |
107 | umode_t mode, | |
108 | struct rchan_buf *buf, | |
109 | int *is_global) | |
110 | { | |
111 | struct dentry *buf_file; | |
112 | ||
2fcf0680 SAK |
113 | /* |
114 | * This to enable the use of a single buffer for the relay channel and | |
f9cda048 MW |
115 | * correspondingly have a single file exposed to User, through which |
116 | * it can collect the logs in order without any post-processing. | |
117 | * Need to set 'is_global' even if parent is NULL for early logging. | |
118 | */ | |
119 | *is_global = 1; | |
120 | ||
121 | if (!parent) | |
122 | return NULL; | |
123 | ||
2fcf0680 SAK |
124 | /* |
125 | * Not using the channel filename passed as an argument, since for each | |
f9cda048 MW |
126 | * channel relay appends the corresponding CPU number to the filename |
127 | * passed in relay_open(). This should be fine as relay just needs a | |
128 | * dentry of the file associated with the channel buffer and that file's | |
129 | * name need not be same as the filename passed as an argument. | |
130 | */ | |
131 | buf_file = debugfs_create_file("guc_log", mode, | |
132 | parent, buf, &relay_file_operations); | |
133 | return buf_file; | |
134 | } | |
135 | ||
136 | /* | |
137 | * file_remove() default callback. Removes relay file in debugfs. | |
138 | */ | |
139 | static int remove_buf_file_callback(struct dentry *dentry) | |
140 | { | |
141 | debugfs_remove(dentry); | |
142 | return 0; | |
143 | } | |
144 | ||
145 | /* relay channel callbacks */ | |
146 | static struct rchan_callbacks relay_callbacks = { | |
147 | .subbuf_start = subbuf_start_callback, | |
148 | .create_buf_file = create_buf_file_callback, | |
149 | .remove_buf_file = remove_buf_file_callback, | |
150 | }; | |
151 | ||
3950bf3d | 152 | static int guc_log_relay_file_create(struct intel_guc *guc) |
f9cda048 MW |
153 | { |
154 | struct drm_i915_private *dev_priv = guc_to_i915(guc); | |
155 | struct dentry *log_dir; | |
156 | int ret; | |
157 | ||
0ed87953 | 158 | if (!i915_modparams.guc_log_level) |
3950bf3d OM |
159 | return 0; |
160 | ||
70deeadd SAK |
161 | mutex_lock(&guc->log.runtime.relay_lock); |
162 | ||
f9cda048 MW |
163 | /* For now create the log file in /sys/kernel/debug/dri/0 dir */ |
164 | log_dir = dev_priv->drm.primary->debugfs_root; | |
165 | ||
2fcf0680 SAK |
166 | /* |
167 | * If /sys/kernel/debug/dri/0 location do not exist, then debugfs is | |
f9cda048 MW |
168 | * not mounted and so can't create the relay file. |
169 | * The relay API seems to fit well with debugfs only, for availing relay | |
170 | * there are 3 requirements which can be met for debugfs file only in a | |
171 | * straightforward/clean manner :- | |
172 | * i) Need the associated dentry pointer of the file, while opening the | |
173 | * relay channel. | |
174 | * ii) Should be able to use 'relay_file_operations' fops for the file. | |
175 | * iii) Set the 'i_private' field of file's inode to the pointer of | |
176 | * relay channel buffer. | |
177 | */ | |
178 | if (!log_dir) { | |
179 | DRM_ERROR("Debugfs dir not available yet for GuC log file\n"); | |
70deeadd SAK |
180 | ret = -ENODEV; |
181 | goto out_unlock; | |
f9cda048 MW |
182 | } |
183 | ||
e7465473 | 184 | ret = relay_late_setup_files(guc->log.runtime.relay_chan, "guc_log", log_dir); |
3950bf3d | 185 | if (ret < 0 && ret != -EEXIST) { |
f9cda048 | 186 | DRM_ERROR("Couldn't associate relay chan with file %d\n", ret); |
70deeadd | 187 | goto out_unlock; |
f9cda048 MW |
188 | } |
189 | ||
b1852d36 SAK |
190 | ret = 0; |
191 | ||
70deeadd SAK |
192 | out_unlock: |
193 | mutex_unlock(&guc->log.runtime.relay_lock); | |
194 | return ret; | |
195 | } | |
196 | ||
197 | static bool guc_log_has_relay(struct intel_guc *guc) | |
198 | { | |
199 | lockdep_assert_held(&guc->log.runtime.relay_lock); | |
200 | ||
201 | return guc->log.runtime.relay_chan != NULL; | |
f9cda048 MW |
202 | } |
203 | ||
204 | static void guc_move_to_next_buf(struct intel_guc *guc) | |
205 | { | |
2fcf0680 SAK |
206 | /* |
207 | * Make sure the updates made in the sub buffer are visible when | |
f9cda048 MW |
208 | * Consumer sees the following update to offset inside the sub buffer. |
209 | */ | |
210 | smp_wmb(); | |
211 | ||
70deeadd SAK |
212 | if (!guc_log_has_relay(guc)) |
213 | return; | |
214 | ||
f9cda048 | 215 | /* All data has been written, so now move the offset of sub buffer. */ |
e7465473 | 216 | relay_reserve(guc->log.runtime.relay_chan, guc->log.vma->obj->base.size); |
f9cda048 MW |
217 | |
218 | /* Switch to the next sub buffer */ | |
e7465473 | 219 | relay_flush(guc->log.runtime.relay_chan); |
f9cda048 MW |
220 | } |
221 | ||
222 | static void *guc_get_write_buffer(struct intel_guc *guc) | |
223 | { | |
70deeadd | 224 | if (!guc_log_has_relay(guc)) |
f9cda048 MW |
225 | return NULL; |
226 | ||
2fcf0680 SAK |
227 | /* |
228 | * Just get the base address of a new sub buffer and copy data into it | |
f9cda048 MW |
229 | * ourselves. NULL will be returned in no-overwrite mode, if all sub |
230 | * buffers are full. Could have used the relay_write() to indirectly | |
231 | * copy the data, but that would have been bit convoluted, as we need to | |
232 | * write to only certain locations inside a sub buffer which cannot be | |
233 | * done without using relay_reserve() along with relay_write(). So its | |
234 | * better to use relay_reserve() alone. | |
235 | */ | |
e7465473 | 236 | return relay_reserve(guc->log.runtime.relay_chan, 0); |
f9cda048 MW |
237 | } |
238 | ||
239 | static bool guc_check_log_buf_overflow(struct intel_guc *guc, | |
240 | enum guc_log_buffer_type type, | |
241 | unsigned int full_cnt) | |
242 | { | |
243 | unsigned int prev_full_cnt = guc->log.prev_overflow_count[type]; | |
244 | bool overflow = false; | |
245 | ||
246 | if (full_cnt != prev_full_cnt) { | |
247 | overflow = true; | |
248 | ||
249 | guc->log.prev_overflow_count[type] = full_cnt; | |
250 | guc->log.total_overflow_count[type] += full_cnt - prev_full_cnt; | |
251 | ||
252 | if (full_cnt < prev_full_cnt) { | |
253 | /* buffer_full_cnt is a 4 bit counter */ | |
254 | guc->log.total_overflow_count[type] += 16; | |
255 | } | |
256 | DRM_ERROR_RATELIMITED("GuC log buffer overflow\n"); | |
257 | } | |
258 | ||
259 | return overflow; | |
260 | } | |
261 | ||
262 | static unsigned int guc_get_log_buffer_size(enum guc_log_buffer_type type) | |
263 | { | |
264 | switch (type) { | |
265 | case GUC_ISR_LOG_BUFFER: | |
266 | return (GUC_LOG_ISR_PAGES + 1) * PAGE_SIZE; | |
267 | case GUC_DPC_LOG_BUFFER: | |
268 | return (GUC_LOG_DPC_PAGES + 1) * PAGE_SIZE; | |
269 | case GUC_CRASH_DUMP_LOG_BUFFER: | |
270 | return (GUC_LOG_CRASH_PAGES + 1) * PAGE_SIZE; | |
271 | default: | |
272 | MISSING_CASE(type); | |
273 | } | |
274 | ||
275 | return 0; | |
276 | } | |
277 | ||
278 | static void guc_read_update_log_buffer(struct intel_guc *guc) | |
279 | { | |
280 | unsigned int buffer_size, read_offset, write_offset, bytes_to_copy, full_cnt; | |
281 | struct guc_log_buffer_state *log_buf_state, *log_buf_snapshot_state; | |
282 | struct guc_log_buffer_state log_buf_state_local; | |
283 | enum guc_log_buffer_type type; | |
284 | void *src_data, *dst_data; | |
285 | bool new_overflow; | |
286 | ||
e7465473 | 287 | if (WARN_ON(!guc->log.runtime.buf_addr)) |
f9cda048 MW |
288 | return; |
289 | ||
290 | /* Get the pointer to shared GuC log buffer */ | |
e7465473 | 291 | log_buf_state = src_data = guc->log.runtime.buf_addr; |
f9cda048 | 292 | |
70deeadd SAK |
293 | mutex_lock(&guc->log.runtime.relay_lock); |
294 | ||
f9cda048 MW |
295 | /* Get the pointer to local buffer to store the logs */ |
296 | log_buf_snapshot_state = dst_data = guc_get_write_buffer(guc); | |
297 | ||
70deeadd | 298 | if (unlikely(!log_buf_snapshot_state)) { |
2fcf0680 SAK |
299 | /* |
300 | * Used rate limited to avoid deluge of messages, logs might be | |
70deeadd SAK |
301 | * getting consumed by User at a slow rate. |
302 | */ | |
303 | DRM_ERROR_RATELIMITED("no sub-buffer to capture logs\n"); | |
304 | guc->log.capture_miss_count++; | |
305 | mutex_unlock(&guc->log.runtime.relay_lock); | |
306 | ||
307 | return; | |
308 | } | |
309 | ||
f9cda048 MW |
310 | /* Actual logs are present from the 2nd page */ |
311 | src_data += PAGE_SIZE; | |
312 | dst_data += PAGE_SIZE; | |
313 | ||
314 | for (type = GUC_ISR_LOG_BUFFER; type < GUC_MAX_LOG_BUFFER; type++) { | |
2fcf0680 SAK |
315 | /* |
316 | * Make a copy of the state structure, inside GuC log buffer | |
f9cda048 MW |
317 | * (which is uncached mapped), on the stack to avoid reading |
318 | * from it multiple times. | |
319 | */ | |
320 | memcpy(&log_buf_state_local, log_buf_state, | |
321 | sizeof(struct guc_log_buffer_state)); | |
322 | buffer_size = guc_get_log_buffer_size(type); | |
323 | read_offset = log_buf_state_local.read_ptr; | |
324 | write_offset = log_buf_state_local.sampled_write_ptr; | |
325 | full_cnt = log_buf_state_local.buffer_full_cnt; | |
326 | ||
327 | /* Bookkeeping stuff */ | |
328 | guc->log.flush_count[type] += log_buf_state_local.flush_to_file; | |
329 | new_overflow = guc_check_log_buf_overflow(guc, type, full_cnt); | |
330 | ||
331 | /* Update the state of shared log buffer */ | |
332 | log_buf_state->read_ptr = write_offset; | |
333 | log_buf_state->flush_to_file = 0; | |
334 | log_buf_state++; | |
335 | ||
f9cda048 MW |
336 | /* First copy the state structure in snapshot buffer */ |
337 | memcpy(log_buf_snapshot_state, &log_buf_state_local, | |
338 | sizeof(struct guc_log_buffer_state)); | |
339 | ||
2fcf0680 SAK |
340 | /* |
341 | * The write pointer could have been updated by GuC firmware, | |
f9cda048 MW |
342 | * after sending the flush interrupt to Host, for consistency |
343 | * set write pointer value to same value of sampled_write_ptr | |
344 | * in the snapshot buffer. | |
345 | */ | |
346 | log_buf_snapshot_state->write_ptr = write_offset; | |
347 | log_buf_snapshot_state++; | |
348 | ||
349 | /* Now copy the actual logs. */ | |
350 | if (unlikely(new_overflow)) { | |
351 | /* copy the whole buffer in case of overflow */ | |
352 | read_offset = 0; | |
353 | write_offset = buffer_size; | |
354 | } else if (unlikely((read_offset > buffer_size) || | |
355 | (write_offset > buffer_size))) { | |
356 | DRM_ERROR("invalid log buffer state\n"); | |
357 | /* copy whole buffer as offsets are unreliable */ | |
358 | read_offset = 0; | |
359 | write_offset = buffer_size; | |
360 | } | |
361 | ||
362 | /* Just copy the newly written data */ | |
363 | if (read_offset > write_offset) { | |
364 | i915_memcpy_from_wc(dst_data, src_data, write_offset); | |
365 | bytes_to_copy = buffer_size - read_offset; | |
366 | } else { | |
367 | bytes_to_copy = write_offset - read_offset; | |
368 | } | |
369 | i915_memcpy_from_wc(dst_data + read_offset, | |
370 | src_data + read_offset, bytes_to_copy); | |
371 | ||
372 | src_data += buffer_size; | |
373 | dst_data += buffer_size; | |
374 | } | |
375 | ||
70deeadd SAK |
376 | guc_move_to_next_buf(guc); |
377 | ||
378 | mutex_unlock(&guc->log.runtime.relay_lock); | |
f9cda048 MW |
379 | } |
380 | ||
f9cda048 MW |
381 | static void capture_logs_work(struct work_struct *work) |
382 | { | |
383 | struct intel_guc *guc = | |
e7465473 | 384 | container_of(work, struct intel_guc, log.runtime.flush_work); |
f9cda048 MW |
385 | |
386 | guc_log_capture_logs(guc); | |
387 | } | |
388 | ||
e7465473 | 389 | static bool guc_log_has_runtime(struct intel_guc *guc) |
3950bf3d | 390 | { |
e7465473 | 391 | return guc->log.runtime.buf_addr != NULL; |
3950bf3d OM |
392 | } |
393 | ||
e7465473 | 394 | static int guc_log_runtime_create(struct intel_guc *guc) |
f9cda048 MW |
395 | { |
396 | struct drm_i915_private *dev_priv = guc_to_i915(guc); | |
397 | void *vaddr; | |
e22d8e3c | 398 | int ret; |
f9cda048 MW |
399 | |
400 | lockdep_assert_held(&dev_priv->drm.struct_mutex); | |
401 | ||
28647b52 MW |
402 | if (!guc->log.vma) |
403 | return -ENODEV; | |
404 | ||
e7465473 | 405 | GEM_BUG_ON(guc_log_has_runtime(guc)); |
f9cda048 | 406 | |
e22d8e3c CW |
407 | ret = i915_gem_object_set_to_wc_domain(guc->log.vma->obj, true); |
408 | if (ret) | |
409 | return ret; | |
410 | ||
2fcf0680 SAK |
411 | /* |
412 | * Create a WC (Uncached for read) vmalloc mapping of log | |
3950bf3d OM |
413 | * buffer pages, so that we can directly get the data |
414 | * (up-to-date) from memory. | |
415 | */ | |
416 | vaddr = i915_gem_object_pin_map(guc->log.vma->obj, I915_MAP_WC); | |
417 | if (IS_ERR(vaddr)) { | |
418 | DRM_ERROR("Couldn't map log buffer pages %d\n", ret); | |
419 | return PTR_ERR(vaddr); | |
f9cda048 MW |
420 | } |
421 | ||
e7465473 | 422 | guc->log.runtime.buf_addr = vaddr; |
f9cda048 | 423 | |
70deeadd SAK |
424 | return 0; |
425 | } | |
426 | ||
427 | static void guc_log_runtime_destroy(struct intel_guc *guc) | |
428 | { | |
429 | /* | |
430 | * It's possible that the runtime stuff was never allocated because | |
431 | * GuC log was disabled at the boot time. | |
432 | */ | |
433 | if (!guc_log_has_runtime(guc)) | |
434 | return; | |
435 | ||
436 | i915_gem_object_unpin_map(guc->log.vma->obj); | |
437 | guc->log.runtime.buf_addr = NULL; | |
438 | } | |
439 | ||
440 | void intel_guc_log_init_early(struct intel_guc *guc) | |
441 | { | |
442 | mutex_init(&guc->log.runtime.relay_lock); | |
443 | INIT_WORK(&guc->log.runtime.flush_work, capture_logs_work); | |
444 | } | |
445 | ||
950724ba | 446 | static int guc_log_relay_create(struct intel_guc *guc) |
70deeadd SAK |
447 | { |
448 | struct drm_i915_private *dev_priv = guc_to_i915(guc); | |
449 | struct rchan *guc_log_relay_chan; | |
450 | size_t n_subbufs, subbuf_size; | |
451 | int ret; | |
452 | ||
453 | if (!i915_modparams.guc_log_level) | |
454 | return 0; | |
455 | ||
456 | mutex_lock(&guc->log.runtime.relay_lock); | |
457 | ||
458 | GEM_BUG_ON(guc_log_has_relay(guc)); | |
459 | ||
3950bf3d | 460 | /* Keep the size of sub buffers same as shared log buffer */ |
70deeadd | 461 | subbuf_size = GUC_LOG_SIZE; |
f9cda048 | 462 | |
2fcf0680 SAK |
463 | /* |
464 | * Store up to 8 snapshots, which is large enough to buffer sufficient | |
3950bf3d OM |
465 | * boot time logs and provides enough leeway to User, in terms of |
466 | * latency, for consuming the logs from relay. Also doesn't take | |
467 | * up too much memory. | |
468 | */ | |
469 | n_subbufs = 8; | |
f9cda048 | 470 | |
2fcf0680 SAK |
471 | /* |
472 | * Create a relay channel, so that we have buffers for storing | |
3950bf3d OM |
473 | * the GuC firmware logs, the channel will be linked with a file |
474 | * later on when debugfs is registered. | |
475 | */ | |
476 | guc_log_relay_chan = relay_open(NULL, NULL, subbuf_size, | |
477 | n_subbufs, &relay_callbacks, dev_priv); | |
478 | if (!guc_log_relay_chan) { | |
479 | DRM_ERROR("Couldn't create relay chan for GuC logging\n"); | |
f9cda048 | 480 | |
3950bf3d | 481 | ret = -ENOMEM; |
70deeadd | 482 | goto err; |
3950bf3d | 483 | } |
f9cda048 | 484 | |
3950bf3d | 485 | GEM_BUG_ON(guc_log_relay_chan->subbuf_size < subbuf_size); |
e7465473 | 486 | guc->log.runtime.relay_chan = guc_log_relay_chan; |
f9cda048 | 487 | |
70deeadd SAK |
488 | mutex_unlock(&guc->log.runtime.relay_lock); |
489 | ||
3950bf3d | 490 | return 0; |
f9cda048 | 491 | |
70deeadd SAK |
492 | err: |
493 | mutex_unlock(&guc->log.runtime.relay_lock); | |
494 | /* logging will be off */ | |
495 | i915_modparams.guc_log_level = 0; | |
3950bf3d OM |
496 | return ret; |
497 | } | |
f9cda048 | 498 | |
950724ba | 499 | static void guc_log_relay_destroy(struct intel_guc *guc) |
3950bf3d | 500 | { |
70deeadd SAK |
501 | mutex_lock(&guc->log.runtime.relay_lock); |
502 | ||
3950bf3d | 503 | /* |
70deeadd | 504 | * It's possible that the relay was never allocated because |
0ed87953 MW |
505 | * GuC log was disabled at the boot time. |
506 | */ | |
70deeadd SAK |
507 | if (!guc_log_has_relay(guc)) |
508 | goto out_unlock; | |
f9cda048 | 509 | |
e7465473 | 510 | relay_close(guc->log.runtime.relay_chan); |
70deeadd SAK |
511 | guc->log.runtime.relay_chan = NULL; |
512 | ||
513 | out_unlock: | |
514 | mutex_unlock(&guc->log.runtime.relay_lock); | |
f9cda048 MW |
515 | } |
516 | ||
f9cda048 MW |
517 | static void guc_log_capture_logs(struct intel_guc *guc) |
518 | { | |
519 | struct drm_i915_private *dev_priv = guc_to_i915(guc); | |
520 | ||
521 | guc_read_update_log_buffer(guc); | |
522 | ||
2fcf0680 SAK |
523 | /* |
524 | * Generally device is expected to be active only at this | |
f9cda048 MW |
525 | * time, so get/put should be really quick. |
526 | */ | |
527 | intel_runtime_pm_get(dev_priv); | |
528 | guc_log_flush_complete(guc); | |
529 | intel_runtime_pm_put(dev_priv); | |
530 | } | |
531 | ||
532 | static void guc_flush_logs(struct intel_guc *guc) | |
533 | { | |
534 | struct drm_i915_private *dev_priv = guc_to_i915(guc); | |
535 | ||
2fcf0680 SAK |
536 | /* |
537 | * Before initiating the forceful flush, wait for any pending/ongoing | |
f9cda048 MW |
538 | * flush to complete otherwise forceful flush may not actually happen. |
539 | */ | |
e7465473 | 540 | flush_work(&guc->log.runtime.flush_work); |
f9cda048 MW |
541 | |
542 | /* Ask GuC to update the log buffer state */ | |
70deeadd | 543 | intel_runtime_pm_get(dev_priv); |
f9cda048 | 544 | guc_log_flush(guc); |
70deeadd | 545 | intel_runtime_pm_put(dev_priv); |
f9cda048 MW |
546 | |
547 | /* GuC would have updated log buffer by now, so capture it */ | |
548 | guc_log_capture_logs(guc); | |
549 | } | |
550 | ||
3950bf3d OM |
551 | int intel_guc_log_create(struct intel_guc *guc) |
552 | { | |
553 | struct i915_vma *vma; | |
554 | unsigned long offset; | |
faf65486 | 555 | u32 flags; |
3950bf3d OM |
556 | int ret; |
557 | ||
558 | GEM_BUG_ON(guc->log.vma); | |
559 | ||
2fcf0680 SAK |
560 | /* |
561 | * We require SSE 4.1 for fast reads from the GuC log buffer and | |
3950bf3d OM |
562 | * it should be present on the chipsets supporting GuC based |
563 | * submisssions. | |
564 | */ | |
565 | if (WARN_ON(!i915_has_memcpy_from_wc())) { | |
566 | ret = -EINVAL; | |
567 | goto err; | |
568 | } | |
569 | ||
70deeadd | 570 | vma = intel_guc_allocate_vma(guc, GUC_LOG_SIZE); |
3950bf3d OM |
571 | if (IS_ERR(vma)) { |
572 | ret = PTR_ERR(vma); | |
573 | goto err; | |
574 | } | |
575 | ||
576 | guc->log.vma = vma; | |
577 | ||
3950bf3d OM |
578 | /* each allocated unit is a page */ |
579 | flags = GUC_LOG_VALID | GUC_LOG_NOTIFY_ON_HALF_FULL | | |
580 | (GUC_LOG_DPC_PAGES << GUC_LOG_DPC_SHIFT) | | |
581 | (GUC_LOG_ISR_PAGES << GUC_LOG_ISR_SHIFT) | | |
582 | (GUC_LOG_CRASH_PAGES << GUC_LOG_CRASH_SHIFT); | |
583 | ||
584 | offset = guc_ggtt_offset(vma) >> PAGE_SHIFT; /* in pages */ | |
585 | guc->log.flags = (offset << GUC_LOG_BUF_ADDR_SHIFT) | flags; | |
586 | ||
587 | return 0; | |
588 | ||
3950bf3d OM |
589 | err: |
590 | /* logging will be off */ | |
0ed87953 | 591 | i915_modparams.guc_log_level = 0; |
3950bf3d OM |
592 | return ret; |
593 | } | |
594 | ||
595 | void intel_guc_log_destroy(struct intel_guc *guc) | |
596 | { | |
e7465473 | 597 | guc_log_runtime_destroy(guc); |
3950bf3d OM |
598 | i915_vma_unpin_and_release(&guc->log.vma); |
599 | } | |
600 | ||
86aa8247 MW |
601 | int intel_guc_log_control_get(struct intel_guc *guc) |
602 | { | |
603 | GEM_BUG_ON(!guc->log.vma); | |
604 | GEM_BUG_ON(i915_modparams.guc_log_level < 0); | |
605 | ||
606 | return i915_modparams.guc_log_level; | |
607 | } | |
608 | ||
609 | #define GUC_LOG_LEVEL_DISABLED 0 | |
610 | #define LOG_LEVEL_TO_ENABLED(x) ((x) > 0) | |
611 | #define LOG_LEVEL_TO_VERBOSITY(x) ({ \ | |
612 | typeof(x) _x = (x); \ | |
613 | LOG_LEVEL_TO_ENABLED(_x) ? _x - 1 : 0; \ | |
614 | }) | |
615 | #define VERBOSITY_TO_LOG_LEVEL(x) ((x) + 1) | |
616 | int intel_guc_log_control_set(struct intel_guc *guc, u64 val) | |
f9cda048 | 617 | { |
065dd5ad | 618 | struct drm_i915_private *dev_priv = guc_to_i915(guc); |
86aa8247 | 619 | bool enabled = LOG_LEVEL_TO_ENABLED(val); |
f9cda048 MW |
620 | int ret; |
621 | ||
86aa8247 MW |
622 | BUILD_BUG_ON(GUC_LOG_VERBOSITY_MIN != 0); |
623 | GEM_BUG_ON(!guc->log.vma); | |
624 | GEM_BUG_ON(i915_modparams.guc_log_level < 0); | |
065dd5ad | 625 | |
86aa8247 MW |
626 | /* |
627 | * GuC is recognizing log levels starting from 0 to max, we're using 0 | |
628 | * as indication that logging should be disabled. | |
629 | */ | |
630 | if (val < GUC_LOG_LEVEL_DISABLED || | |
631 | val > VERBOSITY_TO_LOG_LEVEL(GUC_LOG_VERBOSITY_MAX)) | |
f9cda048 MW |
632 | return -EINVAL; |
633 | ||
86aa8247 | 634 | mutex_lock(&dev_priv->drm.struct_mutex); |
f9cda048 | 635 | |
86aa8247 MW |
636 | if (i915_modparams.guc_log_level == val) { |
637 | ret = 0; | |
638 | goto out_unlock; | |
639 | } | |
70deeadd | 640 | |
70deeadd | 641 | intel_runtime_pm_get(dev_priv); |
86aa8247 | 642 | ret = guc_log_control(guc, enabled, LOG_LEVEL_TO_VERBOSITY(val)); |
70deeadd | 643 | intel_runtime_pm_put(dev_priv); |
86aa8247 MW |
644 | if (ret) { |
645 | DRM_DEBUG_DRIVER("guc_log_control action failed %d\n", ret); | |
646 | goto out_unlock; | |
f9cda048 MW |
647 | } |
648 | ||
86aa8247 | 649 | i915_modparams.guc_log_level = val; |
f9cda048 | 650 | |
86aa8247 MW |
651 | mutex_unlock(&dev_priv->drm.struct_mutex); |
652 | ||
653 | if (enabled && !guc_log_has_runtime(guc)) { | |
950724ba | 654 | ret = intel_guc_log_register(guc); |
86aa8247 | 655 | if (ret) { |
950724ba MW |
656 | /* logging will remain off */ |
657 | i915_modparams.guc_log_level = 0; | |
86aa8247 | 658 | goto out; |
3950bf3d | 659 | } |
86aa8247 | 660 | } else if (!enabled && guc_log_has_runtime(guc)) { |
950724ba | 661 | intel_guc_log_unregister(guc); |
f9cda048 MW |
662 | } |
663 | ||
86aa8247 MW |
664 | return 0; |
665 | ||
666 | out_unlock: | |
667 | mutex_unlock(&dev_priv->drm.struct_mutex); | |
668 | out: | |
f9cda048 MW |
669 | return ret; |
670 | } | |
671 | ||
950724ba | 672 | int intel_guc_log_register(struct intel_guc *guc) |
f9cda048 | 673 | { |
950724ba MW |
674 | struct drm_i915_private *i915 = guc_to_i915(guc); |
675 | int ret; | |
676 | ||
677 | GEM_BUG_ON(guc_log_has_runtime(guc)); | |
678 | ||
679 | /* | |
680 | * If log was disabled at boot time, then setup needed to handle | |
681 | * log buffer flush interrupts would not have been done yet, so | |
682 | * do that now. | |
683 | */ | |
684 | ret = guc_log_relay_create(guc); | |
685 | if (ret) | |
686 | goto err; | |
687 | ||
688 | mutex_lock(&i915->drm.struct_mutex); | |
689 | ret = guc_log_runtime_create(guc); | |
690 | mutex_unlock(&i915->drm.struct_mutex); | |
691 | ||
692 | if (ret) | |
693 | goto err_relay; | |
694 | ||
695 | ret = guc_log_relay_file_create(guc); | |
696 | if (ret) | |
697 | goto err_runtime; | |
698 | ||
699 | /* GuC logging is currently the only user of Guc2Host interrupts */ | |
700 | mutex_lock(&i915->drm.struct_mutex); | |
701 | intel_runtime_pm_get(i915); | |
702 | gen9_enable_guc_interrupts(i915); | |
703 | intel_runtime_pm_put(i915); | |
704 | mutex_unlock(&i915->drm.struct_mutex); | |
705 | ||
706 | return 0; | |
f9cda048 | 707 | |
950724ba MW |
708 | err_runtime: |
709 | mutex_lock(&i915->drm.struct_mutex); | |
710 | guc_log_runtime_destroy(guc); | |
711 | mutex_unlock(&i915->drm.struct_mutex); | |
712 | err_relay: | |
713 | guc_log_relay_destroy(guc); | |
714 | err: | |
715 | return ret; | |
f9cda048 MW |
716 | } |
717 | ||
950724ba | 718 | void intel_guc_log_unregister(struct intel_guc *guc) |
f9cda048 | 719 | { |
950724ba | 720 | struct drm_i915_private *i915 = guc_to_i915(guc); |
70deeadd | 721 | |
950724ba MW |
722 | /* |
723 | * Once logging is disabled, GuC won't generate logs & send an | |
724 | * interrupt. But there could be some data in the log buffer | |
725 | * which is yet to be captured. So request GuC to update the log | |
726 | * buffer state and then collect the left over logs. | |
727 | */ | |
728 | guc_flush_logs(guc); | |
f9cda048 | 729 | |
950724ba | 730 | mutex_lock(&i915->drm.struct_mutex); |
3950bf3d | 731 | /* GuC logging is currently the only user of Guc2Host interrupts */ |
950724ba MW |
732 | intel_runtime_pm_get(i915); |
733 | gen9_disable_guc_interrupts(i915); | |
734 | intel_runtime_pm_put(i915); | |
1be333d3 | 735 | |
70deeadd | 736 | guc_log_runtime_destroy(guc); |
950724ba | 737 | mutex_unlock(&i915->drm.struct_mutex); |
70deeadd | 738 | |
950724ba | 739 | guc_log_relay_destroy(guc); |
f9cda048 | 740 | } |