2018-04-04 03:05:33 +02:00
|
|
|
# Trace Events
|
2016-08-05 23:04:25 +02:00
|
|
|
|
2017-11-04 09:08:46 +01:00
|
|
|
<!--introduced_in=v7.7.0-->
|
|
|
|
|
2018-04-04 03:05:33 +02:00
|
|
|
> Stability: 1 - Experimental
|
|
|
|
|
2016-08-05 23:04:25 +02:00
|
|
|
Trace Event provides a mechanism to centralize tracing information generated by
|
2018-03-04 14:46:49 +01:00
|
|
|
V8, Node.js core, and userspace code.
|
2016-08-05 23:04:25 +02:00
|
|
|
|
2018-04-04 03:05:33 +02:00
|
|
|
Tracing can be enabled with the `--trace-event-categories` command-line flag
|
2018-04-29 19:46:41 +02:00
|
|
|
or by using the `trace_events` module. The `--trace-event-categories` flag
|
|
|
|
accepts a list of comma-separated category names.
|
2018-02-14 20:06:32 +01:00
|
|
|
|
|
|
|
The available categories are:
|
|
|
|
|
2019-10-24 06:28:42 +02:00
|
|
|
* `node`: An empty placeholder.
|
|
|
|
* `node.async_hooks`: Enables capture of detailed [`async_hooks`][] trace data.
|
2019-10-02 06:31:57 +02:00
|
|
|
The [`async_hooks`][] events have a unique `asyncId` and a special `triggerId`
|
2018-04-17 06:40:22 +02:00
|
|
|
`triggerAsyncId` property.
|
2019-10-24 06:28:42 +02:00
|
|
|
* `node.bootstrap`: Enables capture of Node.js bootstrap milestones.
|
|
|
|
* `node.console`: Enables capture of `console.time()` and `console.count()`
|
2018-10-16 22:57:24 +02:00
|
|
|
output.
|
2019-10-24 06:28:42 +02:00
|
|
|
* `node.dns.native`: Enables capture of trace data for DNS queries.
|
|
|
|
* `node.environment`: Enables capture of Node.js Environment milestones.
|
|
|
|
* `node.fs.sync`: Enables capture of trace data for file system sync methods.
|
|
|
|
* `node.perf`: Enables capture of [Performance API][] measurements.
|
|
|
|
* `node.perf.usertiming`: Enables capture of only Performance API User Timing
|
2018-02-14 20:06:32 +01:00
|
|
|
measures and marks.
|
2019-10-24 06:28:42 +02:00
|
|
|
* `node.perf.timerify`: Enables capture of only Performance API timerify
|
2018-02-14 20:06:32 +01:00
|
|
|
measurements.
|
2019-10-24 06:28:42 +02:00
|
|
|
* `node.promises.rejections`: Enables capture of trace data tracking the number
|
2018-08-02 04:01:14 +02:00
|
|
|
of unhandled Promise rejections and handled-after-rejections.
|
2019-10-24 06:28:42 +02:00
|
|
|
* `node.vm.script`: Enables capture of trace data for the `vm` module's
|
2018-05-14 23:24:58 +02:00
|
|
|
`runInNewContext()`, `runInContext()`, and `runInThisContext()` methods.
|
2019-10-24 06:28:42 +02:00
|
|
|
* `v8`: The [V8][] events are GC, compiling, and execution related.
|
2018-02-14 20:06:32 +01:00
|
|
|
|
|
|
|
By default the `node`, `node.async_hooks`, and `v8` categories are enabled.
|
2016-08-05 23:04:25 +02:00
|
|
|
|
|
|
|
```txt
|
2018-04-04 03:05:33 +02:00
|
|
|
node --trace-event-categories v8,node,node.async_hooks server.js
|
|
|
|
```
|
|
|
|
|
|
|
|
Prior versions of Node.js required the use of the `--trace-events-enabled`
|
|
|
|
flag to enable trace events. This requirement has been removed. However, the
|
|
|
|
`--trace-events-enabled` flag *may* still be used and will enable the
|
|
|
|
`node`, `node.async_hooks`, and `v8` trace event categories by default.
|
|
|
|
|
|
|
|
```txt
|
|
|
|
node --trace-events-enabled
|
|
|
|
|
|
|
|
// is equivalent to
|
|
|
|
|
|
|
|
node --trace-event-categories v8,node,node.async_hooks
|
|
|
|
```
|
|
|
|
|
|
|
|
Alternatively, trace events may be enabled using the `trace_events` module:
|
|
|
|
|
|
|
|
```js
|
|
|
|
const trace_events = require('trace_events');
|
|
|
|
const tracing = trace_events.createTracing({ categories: ['node.perf'] });
|
|
|
|
tracing.enable(); // Enable trace event capture for the 'node.perf' category
|
|
|
|
|
|
|
|
// do work
|
|
|
|
|
|
|
|
tracing.disable(); // Disable trace event capture for the 'node.perf' category
|
2016-08-05 23:04:25 +02:00
|
|
|
```
|
|
|
|
|
|
|
|
Running Node.js with tracing enabled will produce log files that can be opened
|
|
|
|
in the [`chrome://tracing`](https://www.chromium.org/developers/how-tos/trace-event-profiling-tool)
|
|
|
|
tab of Chrome.
|
2018-01-17 02:13:36 +01:00
|
|
|
|
2018-01-31 17:12:09 +01:00
|
|
|
The logging file is by default called `node_trace.${rotation}.log`, where
|
|
|
|
`${rotation}` is an incrementing log-rotation id. The filepath pattern can
|
|
|
|
be specified with `--trace-event-file-pattern` that accepts a template
|
2018-08-26 18:02:27 +02:00
|
|
|
string that supports `${rotation}` and `${pid}`:
|
2018-01-31 17:12:09 +01:00
|
|
|
|
|
|
|
```txt
|
2018-04-04 03:05:33 +02:00
|
|
|
node --trace-event-categories v8 --trace-event-file-pattern '${pid}-${rotation}.log' server.js
|
2018-01-31 17:12:09 +01:00
|
|
|
```
|
|
|
|
|
2018-03-04 14:46:49 +01:00
|
|
|
Starting with Node.js 10.0.0, the tracing system uses the same time source
|
|
|
|
as the one used by `process.hrtime()`
|
|
|
|
however the trace-event timestamps are expressed in microseconds,
|
|
|
|
unlike `process.hrtime()` which returns nanoseconds.
|
2018-02-14 20:06:32 +01:00
|
|
|
|
2018-10-20 11:51:29 +02:00
|
|
|
The features from this module are not available in [`Worker`][] threads.
|
|
|
|
|
2018-04-04 03:05:33 +02:00
|
|
|
## The `trace_events` module
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
### `Tracing` object
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
The `Tracing` object is used to enable or disable tracing for sets of
|
|
|
|
categories. Instances are created using the `trace_events.createTracing()`
|
|
|
|
method.
|
|
|
|
|
|
|
|
When created, the `Tracing` object is disabled. Calling the
|
|
|
|
`tracing.enable()` method adds the categories to the set of enabled trace event
|
|
|
|
categories. Calling `tracing.disable()` will remove the categories from the
|
|
|
|
set of enabled trace event categories.
|
|
|
|
|
|
|
|
#### `tracing.categories`
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
* {string}
|
|
|
|
|
|
|
|
A comma-separated list of the trace event categories covered by this
|
|
|
|
`Tracing` object.
|
|
|
|
|
|
|
|
#### `tracing.disable()`
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
Disables this `Tracing` object.
|
|
|
|
|
|
|
|
Only trace event categories *not* covered by other enabled `Tracing` objects
|
|
|
|
and *not* specified by the `--trace-event-categories` flag will be disabled.
|
|
|
|
|
|
|
|
```js
|
|
|
|
const trace_events = require('trace_events');
|
|
|
|
const t1 = trace_events.createTracing({ categories: ['node', 'v8'] });
|
|
|
|
const t2 = trace_events.createTracing({ categories: ['node.perf', 'node'] });
|
|
|
|
t1.enable();
|
|
|
|
t2.enable();
|
|
|
|
|
|
|
|
// Prints 'node,node.perf,v8'
|
|
|
|
console.log(trace_events.getEnabledCategories());
|
|
|
|
|
2018-12-10 13:27:32 +01:00
|
|
|
t2.disable(); // Will only disable emission of the 'node.perf' category
|
2018-04-04 03:05:33 +02:00
|
|
|
|
|
|
|
// Prints 'node,v8'
|
|
|
|
console.log(trace_events.getEnabledCategories());
|
|
|
|
```
|
|
|
|
|
|
|
|
#### `tracing.enable()`
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
Enables this `Tracing` object for the set of categories covered by the
|
|
|
|
`Tracing` object.
|
|
|
|
|
|
|
|
#### `tracing.enabled`
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
* {boolean} `true` only if the `Tracing` object has been enabled.
|
|
|
|
|
|
|
|
### `trace_events.createTracing(options)`
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
* `options` {Object}
|
|
|
|
* `categories` {string[]} An array of trace category names. Values included
|
|
|
|
in the array are coerced to a string when possible. An error will be
|
|
|
|
thrown if the value cannot be coerced.
|
|
|
|
* Returns: {Tracing}.
|
|
|
|
|
|
|
|
Creates and returns a `Tracing` object for the given set of `categories`.
|
|
|
|
|
|
|
|
```js
|
|
|
|
const trace_events = require('trace_events');
|
|
|
|
const categories = ['node.perf', 'node.async_hooks'];
|
|
|
|
const tracing = trace_events.createTracing({ categories });
|
|
|
|
tracing.enable();
|
|
|
|
// do stuff
|
|
|
|
tracing.disable();
|
|
|
|
```
|
|
|
|
|
|
|
|
### `trace_events.getEnabledCategories()`
|
|
|
|
<!-- YAML
|
2018-03-02 18:53:46 +01:00
|
|
|
added: v10.0.0
|
2018-04-04 03:05:33 +02:00
|
|
|
-->
|
|
|
|
|
|
|
|
* Returns: {string}
|
|
|
|
|
|
|
|
Returns a comma-separated list of all currently-enabled trace event
|
|
|
|
categories. The current set of enabled trace event categories is determined
|
|
|
|
by the *union* of all currently-enabled `Tracing` objects and any categories
|
|
|
|
enabled using the `--trace-event-categories` flag.
|
|
|
|
|
|
|
|
Given the file `test.js` below, the command
|
|
|
|
`node --trace-event-categories node.perf test.js` will print
|
|
|
|
`'node.async_hooks,node.perf'` to the console.
|
|
|
|
|
|
|
|
```js
|
|
|
|
const trace_events = require('trace_events');
|
|
|
|
const t1 = trace_events.createTracing({ categories: ['node.async_hooks'] });
|
|
|
|
const t2 = trace_events.createTracing({ categories: ['node.perf'] });
|
|
|
|
const t3 = trace_events.createTracing({ categories: ['v8'] });
|
|
|
|
|
|
|
|
t1.enable();
|
|
|
|
t2.enable();
|
|
|
|
|
|
|
|
console.log(trace_events.getEnabledCategories());
|
|
|
|
```
|
|
|
|
|
2018-02-14 20:06:32 +01:00
|
|
|
[Performance API]: perf_hooks.html
|
2018-04-17 06:40:22 +02:00
|
|
|
[V8]: v8.html
|
2018-10-20 11:51:29 +02:00
|
|
|
[`Worker`]: worker_threads.html#worker_threads_class_worker
|
2018-11-27 20:49:21 +01:00
|
|
|
[`async_hooks`]: async_hooks.html
|