xref: /linux/sound/soc/sof/debug.c (revision 177bf8620cf4ed290ee170a6c5966adc0924b336)
1 // SPDX-License-Identifier: (GPL-2.0-only OR BSD-3-Clause)
2 //
3 // This file is provided under a dual BSD/GPLv2 license.  When using or
4 // redistributing this file, you may do so under either license.
5 //
6 // Copyright(c) 2018 Intel Corporation
7 //
8 // Author: Liam Girdwood <liam.r.girdwood@linux.intel.com>
9 //
10 // Generic debug routines used to export DSP MMIO and memories to userspace
11 // for firmware debugging.
12 //
13 
14 #include <linux/debugfs.h>
15 #include <linux/io.h>
16 #include <linux/pm_runtime.h>
17 #include <sound/sof/ext_manifest.h>
18 #include <sound/sof/debug.h>
19 #include "sof-priv.h"
20 #include "ops.h"
21 
sof_dfsentry_read(struct file * file,char __user * buffer,size_t count,loff_t * ppos)22 static ssize_t sof_dfsentry_read(struct file *file, char __user *buffer,
23 				 size_t count, loff_t *ppos)
24 {
25 	struct snd_sof_dfsentry *dfse = file->private_data;
26 	struct snd_sof_dev *sdev = dfse->sdev;
27 	loff_t pos = *ppos;
28 	size_t size_ret;
29 	int skip = 0;
30 	int size;
31 	u8 *buf;
32 
33 	size = dfse->size;
34 
35 	/* validate position & count */
36 	if (pos < 0)
37 		return -EINVAL;
38 	if (pos >= size || !count)
39 		return 0;
40 	/* find the minimum. min() is not used since it adds sparse warnings */
41 	if (count > size - pos)
42 		count = size - pos;
43 
44 	/* align io read start to u32 multiple */
45 	pos = ALIGN_DOWN(pos, 4);
46 
47 	/* intermediate buffer size must be u32 multiple */
48 	size = ALIGN(count, 4);
49 
50 	/* if start position is unaligned, read extra u32 */
51 	if (unlikely(pos != *ppos)) {
52 		skip = *ppos - pos;
53 		if (pos + size + 4 < dfse->size)
54 			size += 4;
55 	}
56 
57 	buf = kzalloc(size, GFP_KERNEL);
58 	if (!buf)
59 		return -ENOMEM;
60 
61 	if (dfse->type == SOF_DFSENTRY_TYPE_IOMEM) {
62 #if IS_ENABLED(CONFIG_SND_SOC_SOF_DEBUG_ENABLE_DEBUGFS_CACHE)
63 		/*
64 		 * If the DSP is active: copy from IO.
65 		 * If the DSP is suspended:
66 		 *	- Copy from IO if the memory is always accessible.
67 		 *	- Otherwise, copy from cached buffer.
68 		 */
69 		if (pm_runtime_active(sdev->dev) ||
70 		    dfse->access_type == SOF_DEBUGFS_ACCESS_ALWAYS) {
71 			memcpy_fromio(buf, dfse->io_mem + pos, size);
72 		} else {
73 			dev_info(sdev->dev,
74 				 "Copying cached debugfs data\n");
75 			memcpy(buf, dfse->cache_buf + pos, size);
76 		}
77 #else
78 		/* if the DSP is in D3 */
79 		if (!pm_runtime_active(sdev->dev) &&
80 		    dfse->access_type == SOF_DEBUGFS_ACCESS_D0_ONLY) {
81 			dev_err(sdev->dev,
82 				"error: debugfs entry cannot be read in DSP D3\n");
83 			kfree(buf);
84 			return -EINVAL;
85 		}
86 
87 		memcpy_fromio(buf, dfse->io_mem + pos, size);
88 #endif
89 	} else {
90 		memcpy(buf, ((u8 *)(dfse->buf) + pos), size);
91 	}
92 
93 	/* copy to userspace */
94 	size_ret = copy_to_user(buffer, buf + skip, count);
95 
96 	kfree(buf);
97 
98 	/* update count & position if copy succeeded */
99 	if (size_ret)
100 		return -EFAULT;
101 
102 	*ppos = pos + count;
103 
104 	return count;
105 }
106 
107 static const struct file_operations sof_dfs_fops = {
108 	.open = simple_open,
109 	.read = sof_dfsentry_read,
110 	.llseek = default_llseek,
111 };
112 
113 /* create FS entry for debug files that can expose DSP memories, registers */
snd_sof_debugfs_io_item(struct snd_sof_dev * sdev,void __iomem * base,size_t size,const char * name,enum sof_debugfs_access_type access_type)114 static int snd_sof_debugfs_io_item(struct snd_sof_dev *sdev,
115 				   void __iomem *base, size_t size,
116 				   const char *name,
117 				   enum sof_debugfs_access_type access_type)
118 {
119 	struct snd_sof_dfsentry *dfse;
120 
121 	if (!sdev)
122 		return -EINVAL;
123 
124 	dfse = devm_kzalloc(sdev->dev, sizeof(*dfse), GFP_KERNEL);
125 	if (!dfse)
126 		return -ENOMEM;
127 
128 	dfse->type = SOF_DFSENTRY_TYPE_IOMEM;
129 	dfse->io_mem = base;
130 	dfse->size = size;
131 	dfse->sdev = sdev;
132 	dfse->access_type = access_type;
133 
134 #if IS_ENABLED(CONFIG_SND_SOC_SOF_DEBUG_ENABLE_DEBUGFS_CACHE)
135 	/*
136 	 * allocate cache buffer that will be used to save the mem window
137 	 * contents prior to suspend
138 	 */
139 	if (access_type == SOF_DEBUGFS_ACCESS_D0_ONLY) {
140 		dfse->cache_buf = devm_kzalloc(sdev->dev, size, GFP_KERNEL);
141 		if (!dfse->cache_buf)
142 			return -ENOMEM;
143 	}
144 #endif
145 
146 	debugfs_create_file(name, 0444, sdev->debugfs_root, dfse,
147 			    &sof_dfs_fops);
148 
149 	/* add to dfsentry list */
150 	list_add(&dfse->list, &sdev->dfsentry_list);
151 
152 	return 0;
153 }
154 
snd_sof_debugfs_add_region_item_iomem(struct snd_sof_dev * sdev,enum snd_sof_fw_blk_type blk_type,u32 offset,size_t size,const char * name,enum sof_debugfs_access_type access_type)155 int snd_sof_debugfs_add_region_item_iomem(struct snd_sof_dev *sdev,
156 					  enum snd_sof_fw_blk_type blk_type, u32 offset,
157 					  size_t size, const char *name,
158 					  enum sof_debugfs_access_type access_type)
159 {
160 	int bar = snd_sof_dsp_get_bar_index(sdev, blk_type);
161 
162 	if (bar < 0)
163 		return bar;
164 
165 	return snd_sof_debugfs_io_item(sdev, sdev->bar[bar] + offset, size, name,
166 				       access_type);
167 }
168 EXPORT_SYMBOL_GPL(snd_sof_debugfs_add_region_item_iomem);
169 
170 /* create FS entry for debug files to expose kernel memory */
snd_sof_debugfs_buf_item(struct snd_sof_dev * sdev,void * base,size_t size,const char * name,mode_t mode)171 int snd_sof_debugfs_buf_item(struct snd_sof_dev *sdev,
172 			     void *base, size_t size,
173 			     const char *name, mode_t mode)
174 {
175 	struct snd_sof_dfsentry *dfse;
176 
177 	if (!sdev)
178 		return -EINVAL;
179 
180 	dfse = devm_kzalloc(sdev->dev, sizeof(*dfse), GFP_KERNEL);
181 	if (!dfse)
182 		return -ENOMEM;
183 
184 	dfse->type = SOF_DFSENTRY_TYPE_BUF;
185 	dfse->buf = base;
186 	dfse->size = size;
187 	dfse->sdev = sdev;
188 
189 	debugfs_create_file(name, mode, sdev->debugfs_root, dfse,
190 			    &sof_dfs_fops);
191 	/* add to dfsentry list */
192 	list_add(&dfse->list, &sdev->dfsentry_list);
193 
194 	return 0;
195 }
196 EXPORT_SYMBOL_GPL(snd_sof_debugfs_buf_item);
197 
memory_info_update(struct snd_sof_dev * sdev,char * buf,size_t buff_size)198 static int memory_info_update(struct snd_sof_dev *sdev, char *buf, size_t buff_size)
199 {
200 	struct sof_ipc_cmd_hdr msg = {
201 		.size = sizeof(struct sof_ipc_cmd_hdr),
202 		.cmd = SOF_IPC_GLB_DEBUG | SOF_IPC_DEBUG_MEM_USAGE,
203 	};
204 	struct sof_ipc_dbg_mem_usage *reply;
205 	int len;
206 	int ret;
207 	int i;
208 
209 	reply = kmalloc(SOF_IPC_MSG_MAX_SIZE, GFP_KERNEL);
210 	if (!reply)
211 		return -ENOMEM;
212 
213 	ret = pm_runtime_resume_and_get(sdev->dev);
214 	if (ret < 0 && ret != -EACCES) {
215 		dev_err(sdev->dev, "error: enabling device failed: %d\n", ret);
216 		goto error;
217 	}
218 
219 	ret = sof_ipc_tx_message(sdev->ipc, &msg, msg.size, reply, SOF_IPC_MSG_MAX_SIZE);
220 	pm_runtime_put_autosuspend(sdev->dev);
221 	if (ret < 0 || reply->rhdr.error < 0) {
222 		ret = min(ret, reply->rhdr.error);
223 		dev_err(sdev->dev, "error: reading memory info failed, %d\n", ret);
224 		goto error;
225 	}
226 
227 	if (struct_size(reply, elems, reply->num_elems) != reply->rhdr.hdr.size) {
228 		dev_err(sdev->dev, "error: invalid memory info ipc struct size, %d\n",
229 			reply->rhdr.hdr.size);
230 		ret = -EINVAL;
231 		goto error;
232 	}
233 
234 	for (i = 0, len = 0; i < reply->num_elems; i++) {
235 		ret = scnprintf(buf + len, buff_size - len, "zone %d.%d used %#8x free %#8x\n",
236 				reply->elems[i].zone, reply->elems[i].id,
237 				reply->elems[i].used, reply->elems[i].free);
238 		if (ret < 0)
239 			goto error;
240 		len += ret;
241 	}
242 
243 	ret = len;
244 error:
245 	kfree(reply);
246 	return ret;
247 }
248 
memory_info_read(struct file * file,char __user * to,size_t count,loff_t * ppos)249 static ssize_t memory_info_read(struct file *file, char __user *to, size_t count, loff_t *ppos)
250 {
251 	struct snd_sof_dfsentry *dfse = file->private_data;
252 	struct snd_sof_dev *sdev = dfse->sdev;
253 	int data_length;
254 
255 	/* read memory info from FW only once for each file read */
256 	if (!*ppos) {
257 		dfse->buf_data_size = 0;
258 		data_length = memory_info_update(sdev, dfse->buf, dfse->size);
259 		if (data_length < 0)
260 			return data_length;
261 		dfse->buf_data_size = data_length;
262 	}
263 
264 	return simple_read_from_buffer(to, count, ppos, dfse->buf, dfse->buf_data_size);
265 }
266 
memory_info_open(struct inode * inode,struct file * file)267 static int memory_info_open(struct inode *inode, struct file *file)
268 {
269 	struct snd_sof_dfsentry *dfse = inode->i_private;
270 	struct snd_sof_dev *sdev = dfse->sdev;
271 
272 	file->private_data = dfse;
273 
274 	/* allocate buffer memory only in first open run, to save memory when unused */
275 	if (!dfse->buf) {
276 		dfse->buf = devm_kmalloc(sdev->dev, PAGE_SIZE, GFP_KERNEL);
277 		if (!dfse->buf)
278 			return -ENOMEM;
279 		dfse->size = PAGE_SIZE;
280 	}
281 
282 	return 0;
283 }
284 
285 static const struct file_operations memory_info_fops = {
286 	.open = memory_info_open,
287 	.read = memory_info_read,
288 	.llseek = default_llseek,
289 };
290 
snd_sof_dbg_memory_info_init(struct snd_sof_dev * sdev)291 int snd_sof_dbg_memory_info_init(struct snd_sof_dev *sdev)
292 {
293 	struct snd_sof_dfsentry *dfse;
294 
295 	dfse = devm_kzalloc(sdev->dev, sizeof(*dfse), GFP_KERNEL);
296 	if (!dfse)
297 		return -ENOMEM;
298 
299 	/* don't allocate buffer before first usage, to save memory when unused */
300 	dfse->type = SOF_DFSENTRY_TYPE_BUF;
301 	dfse->sdev = sdev;
302 
303 	debugfs_create_file("memory_info", 0444, sdev->debugfs_root, dfse, &memory_info_fops);
304 
305 	/* add to dfsentry list */
306 	list_add(&dfse->list, &sdev->dfsentry_list);
307 	return 0;
308 }
309 EXPORT_SYMBOL_GPL(snd_sof_dbg_memory_info_init);
310 
snd_sof_dbg_init(struct snd_sof_dev * sdev)311 int snd_sof_dbg_init(struct snd_sof_dev *sdev)
312 {
313 	const struct snd_sof_dsp_ops *ops = sof_ops(sdev);
314 	struct snd_sof_pdata *plat_data = sdev->pdata;
315 	const struct snd_sof_debugfs_map *map;
316 	struct dentry *fw_profile;
317 	int i;
318 	int err;
319 
320 	/* use "sof" as top level debugFS dir */
321 	sdev->debugfs_root = debugfs_create_dir("sof", NULL);
322 
323 	/* expose firmware/topology prefix/names for test purposes */
324 	fw_profile = debugfs_create_dir("fw_profile", sdev->debugfs_root);
325 
326 	debugfs_create_str("fw_path", 0444, fw_profile,
327 			   (char **)&plat_data->fw_filename_prefix);
328 	/* library path is not valid for IPC3 */
329 	if (plat_data->ipc_type != SOF_IPC_TYPE_3) {
330 		/*
331 		 * fw_lib_prefix can be NULL if the vendor/platform does not
332 		 * support loadable libraries
333 		 */
334 		if (plat_data->fw_lib_prefix) {
335 			debugfs_create_str("fw_lib_path", 0444, fw_profile,
336 					   (char **)&plat_data->fw_lib_prefix);
337 		} else {
338 			static char *fw_lib_path;
339 
340 			fw_lib_path = devm_kasprintf(sdev->dev, GFP_KERNEL,
341 						     "Not supported");
342 			if (!fw_lib_path)
343 				return -ENOMEM;
344 
345 			debugfs_create_str("fw_lib_path", 0444, fw_profile,
346 					   (char **)&fw_lib_path);
347 		}
348 	}
349 	debugfs_create_str("tplg_path", 0444, fw_profile,
350 			   (char **)&plat_data->tplg_filename_prefix);
351 	debugfs_create_str("fw_name", 0444, fw_profile,
352 			   (char **)&plat_data->fw_filename);
353 	debugfs_create_str("tplg_name", 0444, fw_profile,
354 			   (char **)&plat_data->tplg_filename);
355 	debugfs_create_u32("ipc_type", 0444, fw_profile,
356 			   (u32 *)&plat_data->ipc_type);
357 
358 	/* init dfsentry list */
359 	INIT_LIST_HEAD(&sdev->dfsentry_list);
360 
361 	/* create debugFS files for platform specific MMIO/DSP memories */
362 	for (i = 0; i < ops->debug_map_count; i++) {
363 		map = &ops->debug_map[i];
364 
365 		err = snd_sof_debugfs_io_item(sdev, sdev->bar[map->bar] +
366 					      map->offset, map->size,
367 					      map->name, map->access_type);
368 		/* errors are only due to memory allocation, not debugfs */
369 		if (err < 0)
370 			return err;
371 	}
372 
373 	return snd_sof_debugfs_buf_item(sdev, &sdev->fw_state,
374 					sizeof(sdev->fw_state),
375 					"fw_state", 0444);
376 }
377 EXPORT_SYMBOL_GPL(snd_sof_dbg_init);
378 
snd_sof_free_debug(struct snd_sof_dev * sdev)379 void snd_sof_free_debug(struct snd_sof_dev *sdev)
380 {
381 	debugfs_remove_recursive(sdev->debugfs_root);
382 }
383 EXPORT_SYMBOL_GPL(snd_sof_free_debug);
384 
385 static const struct soc_fw_state_info {
386 	enum sof_fw_state state;
387 	const char *name;
388 } fw_state_dbg[] = {
389 	{SOF_FW_BOOT_NOT_STARTED, "SOF_FW_BOOT_NOT_STARTED"},
390 	{SOF_DSPLESS_MODE, "SOF_DSPLESS_MODE"},
391 	{SOF_FW_BOOT_PREPARE, "SOF_FW_BOOT_PREPARE"},
392 	{SOF_FW_BOOT_IN_PROGRESS, "SOF_FW_BOOT_IN_PROGRESS"},
393 	{SOF_FW_BOOT_FAILED, "SOF_FW_BOOT_FAILED"},
394 	{SOF_FW_BOOT_READY_FAILED, "SOF_FW_BOOT_READY_FAILED"},
395 	{SOF_FW_BOOT_READY_OK, "SOF_FW_BOOT_READY_OK"},
396 	{SOF_FW_BOOT_COMPLETE, "SOF_FW_BOOT_COMPLETE"},
397 	{SOF_FW_CRASHED, "SOF_FW_CRASHED"},
398 };
399 
snd_sof_dbg_print_fw_state(struct snd_sof_dev * sdev,const char * level)400 static void snd_sof_dbg_print_fw_state(struct snd_sof_dev *sdev, const char *level)
401 {
402 	int i;
403 
404 	for (i = 0; i < ARRAY_SIZE(fw_state_dbg); i++) {
405 		if (sdev->fw_state == fw_state_dbg[i].state) {
406 			dev_printk(level, sdev->dev, "fw_state: %s (%d)\n",
407 				   fw_state_dbg[i].name, i);
408 			return;
409 		}
410 	}
411 
412 	dev_printk(level, sdev->dev, "fw_state: UNKNOWN (%d)\n", sdev->fw_state);
413 }
414 
snd_sof_dsp_dbg_dump(struct snd_sof_dev * sdev,const char * msg,u32 flags)415 void snd_sof_dsp_dbg_dump(struct snd_sof_dev *sdev, const char *msg, u32 flags)
416 {
417 	char *level = (flags & SOF_DBG_DUMP_OPTIONAL) ? KERN_DEBUG : KERN_ERR;
418 	bool print_all = sof_debug_check_flag(SOF_DBG_PRINT_ALL_DUMPS);
419 
420 	if (flags & SOF_DBG_DUMP_OPTIONAL && !print_all)
421 		return;
422 
423 	if (sof_ops(sdev)->dbg_dump && !sdev->dbg_dump_printed) {
424 		dev_printk(level, sdev->dev,
425 			   "------------[ DSP dump start ]------------\n");
426 		if (msg)
427 			dev_printk(level, sdev->dev, "%s\n", msg);
428 		snd_sof_dbg_print_fw_state(sdev, level);
429 		sof_ops(sdev)->dbg_dump(sdev, flags);
430 		dev_printk(level, sdev->dev,
431 			   "------------[ DSP dump end ]------------\n");
432 		if (!print_all)
433 			sdev->dbg_dump_printed = true;
434 	} else if (msg) {
435 		dev_printk(level, sdev->dev, "%s\n", msg);
436 	}
437 }
438 EXPORT_SYMBOL(snd_sof_dsp_dbg_dump);
439 
snd_sof_ipc_dump(struct snd_sof_dev * sdev)440 static void snd_sof_ipc_dump(struct snd_sof_dev *sdev)
441 {
442 	if (sof_ops(sdev)->ipc_dump  && !sdev->ipc_dump_printed) {
443 		dev_err(sdev->dev, "------------[ IPC dump start ]------------\n");
444 		sof_ops(sdev)->ipc_dump(sdev);
445 		dev_err(sdev->dev, "------------[ IPC dump end ]------------\n");
446 		if (!sof_debug_check_flag(SOF_DBG_PRINT_ALL_DUMPS))
447 			sdev->ipc_dump_printed = true;
448 	}
449 }
450 
snd_sof_handle_fw_exception(struct snd_sof_dev * sdev,const char * msg)451 void snd_sof_handle_fw_exception(struct snd_sof_dev *sdev, const char *msg)
452 {
453 	if ((IS_ENABLED(CONFIG_SND_SOC_SOF_DEBUG_RETAIN_DSP_CONTEXT) ||
454 	    sof_debug_check_flag(SOF_DBG_RETAIN_CTX)) && !sdev->d3_prevented) {
455 		/* should we prevent DSP entering D3 ? */
456 		if (!sdev->ipc_dump_printed)
457 			dev_info(sdev->dev,
458 				 "Attempting to prevent DSP from entering D3 state to preserve context\n");
459 
460 		if (pm_runtime_get_if_in_use(sdev->dev) == 1)
461 			sdev->d3_prevented = true;
462 	}
463 
464 	/* dump vital information to the logs */
465 	snd_sof_ipc_dump(sdev);
466 	snd_sof_dsp_dbg_dump(sdev, msg, SOF_DBG_DUMP_REGS | SOF_DBG_DUMP_MBOX);
467 	sof_fw_trace_fw_crashed(sdev);
468 }
469 EXPORT_SYMBOL(snd_sof_handle_fw_exception);
470