Skip to content

DAP debug_extensions_test is taking 16 minutes and timing out #53315

Open
@whesse

Description

@whesse

The slowest test on my CQ run, which took 16 minutes and then timed out (but didn't fail), is

pkg/dds/test/dap/integration/debug_exceptions_test

This test should be skipped, or its timeout decreased to an appropriate value, like 4 minutes.
Here is the log from the shard running this test:

swarming_bot_logs: 2023-08-23 11:27:37.679: run_command(['/usr/bin/xvfb-run', '-a', '--server-args=-screen 0 1024x768x24', 'python3', '-u', 'tools/test.py', '--progress=status', '--report', '--time', '--silent-failures', '--write-results', '--write-logs', '--clean-exit', '-nunittest-asserts-release-linux', 'pkg/pkg/(?!(analyzer*|analysis_server|compiler|dev_compiler|js_runtime|front_end|kernel|nnbd_migration|dartdev/test/native_assets)/)', '--copy-coredumps', '--chrome=third_party/browsers/chrome/chrome/google-chrome', '--shards=3', '--shard=1', '--output-directory=/b/s/w/io7n2aq949'], /b/s/w/ir, 3600.0, 30.0, False, Containment<NONE, 0, 0>)
INFO: Core dump archiving is activated
No build targets found.
Test configuration:
unittest-asserts-release-linux(architecture: x64, compiler: dartk, mode: release, runtime: vm, system: linux, timeout: 240, enable-asserts, use-sdk)
Suites tested: pkg
Total: 111 tests

  • 3 tests will be skipped (3 skipped by design)
  • 0 tests are expected to be flaky but not crash
  • 0 tests are expected to flaky crash
  • 108 tests are expected to pass
  • 0 tests are expected to fail that we won't fix
  • 0 tests are expected to fail that we should fix
  • 0 tests are expected to crash that we should fix
  • 0 tests are allowed to timeout
  • 0 could not be categorized or are in multiple categories

103 out of 108 completed
Tests running for 5 minutes
107 out of 108 completed
Tests running for 10 minutes
107 out of 108 completed
Tests running for 15 minutes
The debug timer of test.dart expired. Please report this issue to dart-engprod@ and provide the following information:

Graph is sealed: true

Count[Initialized] = 0
Count[Enqueuing] = 0
Count[Running] = 1
Count[Successful] = 106
Count[Failed] = 1

Commands in state 'Running':

Command: DART_CONFIGURATION=ReleaseX64 CHROME_PATH=/b/s/w/ir/third_party/browsers/chrome/chrome/google-chrome out/ReleaseX64/dart-sdk/bin/dart --enable_asserts --sound-null-safety -Dtest_runner.configuration=unittest-asserts-release-linux --ignore-unrecognized-flags --packages=/b/s/w/ir/.dart_tool/package_config.json /b/s/w/ir/pkg/dds/test/dap/integration/debug_exceptions_test.dart
Enqueued by: dartk-vm release_x64 -- pkg/dds/test/dap/integration/debug_exceptions_test

CommandQueue state:
Processes: used: 1 max: 8
BrowserProcesses: used: 0 max: 8
Finishing: false
Queue (length = 0):

--- Total time: 19:06 ---
0:16:00.654562 - vm - dartk-vm release_x64/pkg/dds/test/dap/integration/debug_exceptions_test
0:07:37.228733 - vm - dartk-vm release_x64/pkg/dartdev/test/commands/doc_test
0:06:22.278124 - vm - dartk-vm release_x64/pkg/dartdev/test/commands/test_test
0:04:17.670617 - vm - dartk-vm release_x64/pkg/vm/test/transformations/type_flow/transformer_test
0:03:14.694085 - vm - dartk-vm release_x64/pkg/vm_snapshot_analysis/test/instruction_sizes_test
0:01:45.363379 - vm - dartk-vm release_x64/pkg/vm/test/transformations/protobuf_aware_treeshaker/treeshaker_test
0:01:17.481158 - vm - dartk-vm release_x64/pkg/dartdev/test/analytics_test
0:01:17.256736 - vm - dartk-vm release_x64/pkg/vm/test/unlinked_ast_to_text_test
0:00:43.700035 - vm - dartk-vm release_x64/pkg/dartdev/test/analysis_server_test
0:00:42.291557 - vm - dartk-vm release_x64/pkg/frontend_server/test/native_assets_test
0:00:41.523064 - vm - dartk-vm release_x64/pkg/dartdev/test/commands/fix_test
0:00:40.353166 - vm - dartk-vm release_x64/pkg/vm/test/obfuscation_test
0:00:39.757974 - vm - dartk-vm release_x64/pkg/dds/test/devtools_server/devtools_server_path_strategy_test
0:00:31.464696 - vm - dartk-vm release_x64/pkg/vm_snapshot_analysis/test/precompiler_trace_test
0:00:31.359065 - vm - dartk-vm release_x64/pkg/dds/test/devtools_server/bind_test
0:00:28.005080 - vm - dartk-vm release_x64/pkg/dds/test/dap/integration/debug_stack_test
0:00:27.737994 - vm - dartk-vm release_x64/pkg/dds/test/regress_45569_test
0:00:26.680138 - vm - dartk-vm release_x64/pkg/vm_service/test/branch_coverage_test
0:00:22.327030 - vm - dartk-vm release_x64/pkg/frontend_server/test/src/javascript_bundle_test
0:00:21.417327 - vm - dartk-vm release_x64/pkg/vm/test/transformations/deferred_loading_test

=== 106 tests passed, 2 failed ===
INFO: No unexpected crashes recorded
I0823 04:46:47.643723 84300 client_context.go:81] Generated action_id 2939b67b-126b-4d1c-ace7-5196506e0d7f for cas
I0823 04:46:47.643770 84300 client_context.go:86] Generated invocation_id d490c34d-edae-4978-afbe-7063e565ce56 for cas 2939b67b-126b-4d1c-ace7-5196506e0d7f
[I2023-08-23T04:46:47.644505-07:00 84300 0 client.go:213] context metadata: client.ContextMetadata{ActionID:"2939b67b-126b-4d1c-ace7-5196506e0d7f", InvocationID:"d490c34d-edae-4978-afbe-7063e565ce56", CorrelatedInvocationID:"", ToolName:"cas", ToolVersion:""}
I0823 04:46:47.649944 84300 client.go:712] Connecting to remote execution instance projects/chromium-swarm/instances/default_instance
I0823 04:46:47.650176 84300 client.go:713] Connecting to remote execution service remotebuildexecution.googleapis.com:443
[I2023-08-23T04:46:47.863576-07:00 84300 0 archive.go:161] ComputeMerkleTree took 605.177µs
I0823 04:46:47.863963 84300 cas.go:121] Created query batch of 3 blobs
I0823 04:46:47.864029 84300 cas_upload.go:63] Waiting for remaining query jobs
I0823 04:46:47.864073 84300 cas_upload.go:38] 1 missing batches left to query
I0823 04:46:47.886215 84300 cas_upload.go:65] Done
I0823 04:46:47.886480 84300 cas_upload.go:509] 3 items to store
I0823 04:46:47.886586 84300 cas.go:73] Batching 3 digests
I0823 04:46:47.886681 84300 cas.go:102] Created batch of 3 blobs with total size 30573
I0823 04:46:47.886782 84300 cas.go:105] 1 batches created
I0823 04:46:47.886866 84300 cas_upload.go:580] Waiting for remaining jobs
I0823 04:46:47.886941 84300 cas_upload.go:532] 1 batches left to store
I0823 04:46:47.887008 84300 cas_upload.go:535] Uploading batch of 3 blobs
I0823 04:46:47.920409 84300 cas_upload.go:582] Done
[I2023-08-23T04:46:47.920432-07:00 84300 0 archive.go:171] UploadIfMissing took 56.506845ms, moved 30287 bytes

@sortie

Metadata

Metadata

Assignees

No one assigned

    Labels

    area-testCross-cutting test issues (use area- labels for specific failures; not used for package:test).dds-dapDDS issues related to the Debug Adapter Protocol (DAP) implementation

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions