Back to build
Raw log | Switch to full mode | Login

[==========] Running 2 tests from 1 test suite. [----------] Global test environment set-up. [----------] 2 tests from ReturnChildResult [ RUN ] ReturnChildResult.False [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [VERBOSE1:trace_manager.cc(202)] Starting configured provider: cpuperf [VERBOSE2:trace_manager.cc(203)] URL: fuchsia-pkg://fuchsia.com/cpuperf_provider#meta/cpuperf_provider.cmx [VERBOSE2:trace_manager.cc(210)] Args: [VERBOSE1:trace_manager.cc(202)] Starting configured provider: ktrace [VERBOSE2:trace_manager.cc(203)] URL: fuchsia-pkg://fuchsia.com/ktrace_provider#meta/ktrace_provider.cmx [VERBOSE2:trace_manager.cc(210)] Args: [INFO:trace_manager.cc(96)] Starting trace with 4 MB buffers, buffering mode=oneshot [VERBOSE2:trace_manager.cc(156)] Registering provider {396452:cpuperf_provider} [VERBOSE1:trace_session.cc(56)] Adding provider #1 {396452:cpuperf_provider}, buffer size 4MB [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from ready to start pending [VERBOSE2:trace_session.cc(253)] Transitioning from ready to started [VERBOSE2:trace_manager.cc(156)] Registering provider {396552:ktrace_provider} [VERBOSE1:trace_session.cc(56)] Adding provider #2 {396552:ktrace_provider}, buffer size 4MB [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from ready to start pending [VERBOSE2:trace_session.cc(253)] Transitioning from started to started [VERBOSE2:tracee.cc(147)] #1 {396452:cpuperf_provider}: pending=0x3 [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from start pending to started [VERBOSE2:trace_session.cc(152)] tracee #1 {396452:cpuperf_provider} ready [VERBOSE2:trace_session.cc(152)] tracee #2 {396552:ktrace_provider} not ready [VERBOSE2:tracee.cc(147)] #2 {396552:ktrace_provider}: pending=0x3 [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from start pending to started [VERBOSE2:trace_session.cc(152)] tracee #1 {396452:cpuperf_provider} ready [VERBOSE2:trace_session.cc(152)] tracee #2 {396552:ktrace_provider} ready [VERBOSE2:trace_session.cc(158)] All providers reporting started [VERBOSE1:trace_session.cc(109)] Marking session as having started [INFO:record.cc(838)] Spawning: /pkg/bin/return_1234 Application exited with return code 1234 Stopping trace... [INFO:trace_manager.cc(135)] Stopping trace [VERBOSE1:trace_session.cc(93)] Stopping trace [VERBOSE2:trace_session.cc(253)] Transitioning from started to stopping [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from started to stopping [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from started to stopping Starting trace; will stop in 10[VERBOSE2:tracee.cc(147)] #1 {396452:cpuperf_provider}: pending=0x4 seconds... [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from stopping to stopped [VERBOSE2:tracee.cc(579)] #1 {396452:cpuperf_provider}: writing provider info record [VERBOSE2:tracee.cc(271)] #1 {396452:cpuperf_provider}: Writing chunk for rolling buffer 0: vmo offset 0x80, size 0x3fff80, by-record [VERBOSE2:tracee.cc(147)] #2 {396552:ktrace_provider}: pending=0x4 [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from stopping to stopped [VERBOSE2:tracee.cc(579)] #2 {396552:ktrace_provider}: writing provider info record [VERBOSE2:tracee.cc(271)] #2 {396552:ktrace_provider}: Writing chunk for rolling buffer 0: vmo offset 0x80, size 0x3fff80, by-record [INFO:tracee.cc(435)] #2 {396552:ktrace_provider} trace stats [INFO:tracee.cc(436)] Wrapped count: 0 [INFO:tracee.cc(437)] # records dropped: 0 [INFO:tracee.cc(438)] Durable buffer: 0x0, size 0x0 [INFO:tracee.cc(441)] Non-durable buffer: 0x240d8,0x0, size 0x3fff80 [VERBOSE1:trace_session.cc(204)] Marking session as stopped, no more tracees [VERBOSE2:trace_session.cc(253)] Transitioning from stopping to stopped [INFO:trace_manager.cc(138)] Stopped trace Trace file written to /data/trace.json [ OK ] ReturnChildResult.False (167 ms) [ RUN ] ReturnChildResult.True [INFO:trace_manager.cc(96)] Starting trace with 4 MB buffers, buffering mode=oneshot [VERBOSE1:trace_session.cc(56)] Adding provider #1 {396452:cpuperf_provider}, buffer size 4MB [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from ready to start pending [VERBOSE2:trace_session.cc(253)] Transitioning from ready to started [VERBOSE1:trace_session.cc(56)] Adding provider #2 {396552:ktrace_provider}, buffer size 4MB [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from ready to start pending [VERBOSE2:trace_session.cc(253)] Transitioning from started to started [VERBOSE2:tracee.cc(147)] #1 {396452:cpuperf_provider}: pending=0x3 [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from start pending to started [VERBOSE2:trace_session.cc(152)] tracee #1 {396452:cpuperf_provider} ready [VERBOSE2:trace_session.cc(152)] tracee #2 {396552:ktrace_provider} not ready [VERBOSE2:tracee.cc(147)] #2 {396552:ktrace_provider}: pending=0x3 [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from start pending to started [VERBOSE2:trace_session.cc(152)] tracee #1 {396452:cpuperf_provider} ready [VERBOSE2:trace_session.cc(152)] tracee #2 {396552:ktrace_provider} ready [VERBOSE2:trace_session.cc(158)] All providers reporting started [VERBOSE1:trace_session.cc(109)] Marking session as having started [INFO:record.cc(838)] Spawning: /pkg/bin/return_1234 Application exited with return code 1234 Stopping trace... Starting trace; will stop in 10 seconds... [INFO:trace_manager.cc(135)] Stopping trace [VERBOSE1:trace_session.cc(93)] Stopping trace [VERBOSE2:trace_session.cc(253)] Transitioning from started to stopping [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from started to stopping [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from started to stopping [VERBOSE2:tracee.cc(147)] #1 {396452:cpuperf_provider}: pending=0x4 [VERBOSE2:tracee.cc(133)] #1 {396452:cpuperf_provider}: Transitioning from stopping to stopped [VERBOSE2:tracee.cc(579)] #1 {396452:cpuperf_provider}: writing provider info record [VERBOSE2:tracee.cc(271)] #1 {396452:cpuperf_provider}: Writing chunk for rolling buffer 0: vmo offset 0x80, size 0x3fff80, by-record [VERBOSE2:tracee.cc(147)] #2 {396552:ktrace_provider}: pending=0x4 [VERBOSE2:tracee.cc(133)] #2 {396552:ktrace_provider}: Transitioning from stopping to stopped [VERBOSE2:tracee.cc(579)] #2 {396552:ktrace_provider}: writing provider info record [VERBOSE2:tracee.cc(271)] #2 {396552:ktrace_provider}: Writing chunk for rolling buffer 0: vmo offset 0x80, size 0x3fff80, by-record [INFO:tracee.cc(435)] #2 {396552:ktrace_provider} trace stats [INFO:tracee.cc(436)] Wrapped count: 0 [INFO:tracee.cc(437)] # records dropped: 0 [INFO:tracee.cc(438)] Durable buffer: 0x0, size 0x0 [INFO:tracee.cc(441)] Non-durable buffer: 0x30238,0x0, size 0x3fff80 [VERBOSE1:trace_session.cc(204)] Marking session as stopped, no more tracees [VERBOSE2:trace_session.cc(253)] Transitioning from stopping to stopped [INFO:trace_manager.cc(138)] Stopped trace Trace file written to /data/trace.json [ERROR:garnet/bin/trace/tests/run_test.cc(175)] /bin/trace exited with exit code 1234 [ OK ] ReturnChildResult.True (118 ms) [----------] 2 tests from ReturnChildResult (285 ms total) [----------] Global test environment tear-down [==========] 2 tests from 1 test suite ran. (286 ms total) [ PASSED ] 2 tests.