Skip to content

Conversation

@thaystg
Copy link
Member

@thaystg thaystg commented Sep 9, 2021

@thaystg thaystg requested review from lewing and radical September 9, 2021 21:08
@thaystg thaystg requested a review from marek-safar as a code owner September 9, 2021 21:08
@ghost ghost added the area-Debugger-mono label Sep 9, 2021
@ghost
Copy link

ghost commented Sep 9, 2021

Tagging subscribers to this area: @thaystg
See info in area-owners.md if you want to be subscribed.

Issue Details

Fixes https://devdiv.visualstudio.com/DevDiv/_workitems/edit/1390250?src=WorkItemMention&src-action=artifact_link

@lewing I also would like to merge to RC2.

Author: thaystg
Assignees: -
Labels:

area-Debugger-mono

Milestone: -

@lewing
Copy link
Member

lewing commented Sep 9, 2021

/backport to release/6.0

@github-actions
Copy link
Contributor

github-actions bot commented Sep 9, 2021

Started backporting to release/6.0: https://github.com/dotnet/runtime/actions/runs/1219052186

@lewing
Copy link
Member

lewing commented Sep 10, 2021

Attempting to collect crash dump: /cores/crashdump_68264.dmp
Invoking: sudo /tmp/helix/working/AF51093A/p/createdump --name "/cores/crashdump_68264.dmp" 68264 --withheap
createdump stdout:
Gathering state for process 68264 

createdump stderr:
CLRDataCreateInstance(ICLRDataEnumMemoryRegions) FAILED 80131c4f

Failed to collect crash dump
Attempting to collect crash dump: /cores/crashdump_68257.dmp
Invoking: sudo /tmp/helix/working/AF51093A/p/createdump --name "/cores/crashdump_68257.dmp" 68257 --withheap
createdump stdout:
Gathering state for process 68257 

createdump stderr:
CLRDataCreateInstance(ICLRDataEnumMemoryRegions) FAILED 80131c4f

Failed to collect crash dump
    tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh [FAIL]
      
      cmdLine:/private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 9:01:40 PM, end: 9/9/2021 9:11:40 PM)
      
      Return code:      -100
      Raw output file:      /tmp/helix/working/AF51093A/w/C14C0A73/uploads/Reports/tracing.eventpipe/pauseonstart/pauseonstart/pauseonstart.output.txt
      Raw output:
      BEGIN EXECUTION
      /tmp/helix/working/AF51093A/p/corerun -p System.Reflection.Metadata.MetadataUpdater.IsSupported=false pauseonstart.dll ''
        0.0s: Attempting to delete the zombied pipe: /var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/dotnet-diagnostic-68231-1631235700-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/dotnet-diagnostic-68253-1631235700-socket
        0.0s: Deleted
        0.0s: ::== Running test: TEST_RuntimeResumesExecutionWithCommand
        0.0s: Server name is '/var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/DOTNET_TRACE_TESTS_wscx4b1b.0if'
        0.0s: running sub-process: /private/tmp/helix/working/AF51093A/p/corerun /private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.dll 0
        0.2s: subprocess started: True
        0.2s: subprocess PID: 68262
        0.2s: { Magic=System.Byte[]; ClrInstanceId=e75becee-104a-483e-83c4-f48750f9ce14; ProcessId=68262; Unused=0; }
        0.2s: Sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
        0.2s: received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
        0.2s: Sending 'exit' to subprocess stdin
        0.3s: ================= Subprocess Exited =================
        0.3s: SubProcess exited - Exit code: 0
        0.3s: ----------------------------------------
        0.3s: Subprocess stdout: 
        0.2s: Subprocess started!  Waiting for input...
        0.2s: Received 'exit'.  Exiting...
        0.3s: Subprocess stderr: 
        0.3s: ----------------------------------------
        0.3s: Test passed: True
        0.3s: 
        0.3s: ::== Running test: TEST_TracesHaveRelevantEvents
        0.3s: Server name is '/var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/DOTNET_TRACE_TESTS_0uvp0q2f.j0j'
        0.3s: running sub-process: /private/tmp/helix/working/AF51093A/p/corerun /private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.dll 0
        0.3s: subprocess started: True
        0.3s: subprocess PID: 68264
        0.3s: Standard Diagnostics Server connection not created yet -> try again in 100 ms
        0.5s: { Magic=System.Byte[]; ClrInstanceId=9ea0fd1b-3a7e-4aed-b1ef-63a9f36c04ba; ProcessId=68264; Unused=0; }
        0.5s: Starting EventPipeSession over standard connection
        0.5s: Started EventPipeSession over standard connection with session id: 0x7fa756d00110
        0.5s: Send ResumeRuntime Diagnostics IPC Command
        0.5s: Sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
        0.5s: received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
        2.6s: Stopping EventPipeSession over standard connection
      
      cmdLine:/private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 9:01:40 PM, end: 9/9/2021 9:11:40 PM)
      Test Harness Exitcode is : -100
      To run the test:
      > set CORE_ROOT=/tmp/helix/working/AF51093A/p
      > /private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh
      Expected: True
      Actual:   False
      Stack Trace:
           at tracing_eventpipe._pauseonstart_pauseonstart_pauseonstart_._pauseonstart_pauseonstart_pauseonstart_sh()
      Output:
        
        cmdLine:/private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 9:01:40 PM, end: 9/9/2021 9:11:40 PM)
        
        Return code:      -100
        Raw output file:      /tmp/helix/working/AF51093A/w/C14C0A73/uploads/Reports/tracing.eventpipe/pauseonstart/pauseonstart/pauseonstart.output.txt
        Raw output:
        BEGIN EXECUTION
        /tmp/helix/working/AF51093A/p/corerun -p System.Reflection.Metadata.MetadataUpdater.IsSupported=false pauseonstart.dll ''
          0.0s: Attempting to delete the zombied pipe: /var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/dotnet-diagnostic-68231-1631235700-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/dotnet-diagnostic-68253-1631235700-socket
          0.0s: Deleted
          0.0s: ::== Running test: TEST_RuntimeResumesExecutionWithCommand
          0.0s: Server name is '/var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/DOTNET_TRACE_TESTS_wscx4b1b.0if'
          0.0s: running sub-process: /private/tmp/helix/working/AF51093A/p/corerun /private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.dll 0
          0.2s: subprocess started: True
          0.2s: subprocess PID: 68262
          0.2s: { Magic=System.Byte[]; ClrInstanceId=e75becee-104a-483e-83c4-f48750f9ce14; ProcessId=68262; Unused=0; }
          0.2s: Sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
          0.2s: received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
          0.2s: Sending 'exit' to subprocess stdin
          0.3s: ================= Subprocess Exited =================
          0.3s: SubProcess exited - Exit code: 0
          0.3s: ----------------------------------------
          0.3s: Subprocess stdout: 
          0.2s: Subprocess started!  Waiting for input...
          0.2s: Received 'exit'.  Exiting...
          0.3s: Subprocess stderr: 
          0.3s: ----------------------------------------
          0.3s: Test passed: True
          0.3s: 
          0.3s: ::== Running test: TEST_TracesHaveRelevantEvents
          0.3s: Server name is '/var/folders/5r/_xdj8ybx1cs61yyd9fmcg3gw0000gy/T/DOTNET_TRACE_TESTS_0uvp0q2f.j0j'
          0.3s: running sub-process: /private/tmp/helix/working/AF51093A/p/corerun /private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.dll 0
          0.3s: subprocess started: True
          0.3s: subprocess PID: 68264
          0.3s: Standard Diagnostics Server connection not created yet -> try again in 100 ms
          0.5s: { Magic=System.Byte[]; ClrInstanceId=9ea0fd1b-3a7e-4aed-b1ef-63a9f36c04ba; ProcessId=68264; Unused=0; }
          0.5s: Starting EventPipeSession over standard connection
          0.5s: Started EventPipeSession over standard connection with session id: 0x7fa756d00110
          0.5s: Send ResumeRuntime Diagnostics IPC Command
          0.5s: Sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
          0.5s: received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
          2.6s: Stopping EventPipeSession over standard connection
        
        cmdLine:/private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 9:01:40 PM, end: 9/9/2021 9:11:40 PM)
        Test Harness Exitcode is : -100
        To run the test:
        > set CORE_ROOT=/tmp/helix/working/AF51093A/p
        > /private/tmp/helix/working/AF51093A/w/C14C0A73/e/tracing/eventpipe/pauseonstart/pauseonstart/pauseonstart.sh
  Finished:    tracing.eventpipe.XUnitWrapper
Attempting to collect crash dump: /cores/crashdump_44018.dmp
Invoking: sudo /tmp/helix/working/A3A908C3/p/createdump --name "/cores/crashdump_44018.dmp" 44018 --withheap
createdump stdout:
Gathering state for process 44018 

createdump stderr:
CLRDataCreateInstance(ICLRDataEnumMemoryRegions) FAILED 80131c4f

Failed to collect crash dump
Attempting to collect crash dump: /cores/crashdump_44003.dmp
Invoking: sudo /tmp/helix/working/A3A908C3/p/createdump --name "/cores/crashdump_44003.dmp" 44003 --withheap
createdump stdout:
Gathering state for process 44003 

createdump stderr:
CLRDataCreateInstance(ICLRDataEnumMemoryRegions) FAILED 80131c4f

Failed to collect crash dump
    tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh [FAIL]
      
      cmdLine:/private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 5:55:44 PM, end: 9/9/2021 6:05:45 PM)
      
      Return code:      -100
      Raw output file:      /tmp/helix/working/A3A908C3/w/C3CA09C3/uploads/Reports/tracing.eventpipe/diagnosticport/diagnosticport/diagnosticport.output.txt
      Raw output:
      BEGIN EXECUTION
      /tmp/helix/working/A3A908C3/p/corerun -p System.Reflection.Metadata.MetadataUpdater.IsSupported=false diagnosticport.dll ''
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40817-1631234565-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24666-1631230447-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24586-1631230365-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40059-1631233750-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40475-1631234269-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40099-1631233790-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-28398-1631231679-socket
        0.0s: Deleted
        0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40230-1631233939-socket
        0.1s: Deleted
        0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24706-1631230484-socket
        0.1s: Deleted
        0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24626-1631230413-socket
        0.1s: Deleted
        0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40857-1631234593-socket
        0.1s: Deleted
        0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-38884-1631233173-socket
        0.1s: Deleted
        0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-35191-1631232671-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40777-1631234536-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40897-1631234621-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40555-1631234427-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-38844-1631233142-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-29267-1631231999-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-43866-1631235117-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-35948-1631232751-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-28896-1631231966-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-37810-1631233004-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24506-1631230303-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-37730-1631232947-socket
        0.2s: Deleted
        0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40343-1631234159-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-37770-1631232976-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24546-1631230332-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-25962-1631230872-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40515-1631234297-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40423-1631234239-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-35577-1631232719-socket
        0.3s: Deleted
        0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40383-1631234212-socket
        0.3s: Deleted
        0.3s: ::== Running test: TEST_MultipleConnectPortsNoSuspend
        0.4s: Server 0 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_wtwfofle.cho'
        0.4s: Server 1 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_li55xbgm.smv'
        0.4s: Server 2 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_n4wxyeac.kxk'
        0.4s: Server 3 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_whgefg5g.tvf'
        0.4s: export DOTNET_DiagnosticPorts=/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_wtwfofle.cho,nosuspend;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_li55xbgm.smv,nosuspend;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_n4wxyeac.kxk,nosuspend;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_whgefg5g.tvf,nosuspend;
        0.4s: running sub-process: /private/tmp/helix/working/A3A908C3/p/corerun /private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.dll 0
        0.7s: subprocess started: True
        0.7s: subprocess PID: 44016
        0.8s: Server 0 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
        0.8s: Server 3 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
        0.8s: Server 2 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
        0.8s: Server 1 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
        0.8s: Sending 'exit' to subprocess stdin
        0.8s: ================= Subprocess Exited =================
        0.8s: SubProcess exited - Exit code: 0
        0.8s: ----------------------------------------
        0.8s: Subprocess stdout: 
        0.4s: Subprocess started!  Waiting for input...
        0.4s: Received 'exit'.  Exiting...
        0.8s: Subprocess stderr: 
        0.8s: ----------------------------------------
        0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_wtwfofle.cho
        0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_li55xbgm.smv
        0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_n4wxyeac.kxk
        0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_whgefg5g.tvf
        0.8s: Test passed: True
        0.8s: 
        0.8s: ::== Running test: TEST_MultipleConnectPortsSuspend
        0.8s: Server 0 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_1wvlzrzn.dnf'
        0.8s: Server 1 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_i1mk1z2d.ofi'
        0.8s: Server 2 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_fxvj2fxx.y1k'
        0.8s: Server 3 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_ca1wluga.wef'
        0.8s: export DOTNET_DiagnosticPorts=/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_1wvlzrzn.dnf;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_i1mk1z2d.ofi;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_fxvj2fxx.y1k;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_ca1wluga.wef;
        0.8s: running sub-process: /private/tmp/helix/working/A3A908C3/p/corerun /private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.dll 0
        0.8s: subprocess started: True
        0.8s: subprocess PID: 44018
        0.9s: Standard Diagnostics Server connection not created yet -> try again in 100 ms
        1.2s: Starting EventPipeSession over standard connection
        1.2s: Started EventPipeSession over standard connection with session id: 0x7ffa31508cb0
        1.2s: Runtime HAS NOT resumed (expects: true): True
        1.2s: Server 0 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
        1.2s: Creating EventPipeEventSource
        1.2s: Port 0 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
        1.3s: Port 0 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
        1.3s: Runtime HAS NOT resumed (expects: true): True
        1.3s: Server 1 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
        1.3s: Port 1 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
        1.3s: Port 1 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
        1.3s: Runtime HAS NOT resumed (expects: true): True
        1.3s: Server 2 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
        1.3s: Port 2 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
        1.3s: Port 2 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
        1.3s: Runtime HAS NOT resumed (expects: true): True
        1.3s: Server 3 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
        1.3s: Port 3 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
        1.3s: Port 3 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
        1.3s: Waiting on EEStartupStarted event
        1.3s: Created EventPipeEventSource
        1.3s: Starting processing
        1.5s: Saw EEStartupStarted Event
        1.5s: Stopping EventPipeSession
      
      cmdLine:/private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 5:55:44 PM, end: 9/9/2021 6:05:45 PM)
      Test Harness Exitcode is : -100
      To run the test:
      > set CORE_ROOT=/tmp/helix/working/A3A908C3/p
      > /private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh
      Expected: True
      Actual:   False
      Stack Trace:
           at tracing_eventpipe._diagnosticport_diagnosticport_diagnosticport_._diagnosticport_diagnosticport_diagnosticport_sh()
      Output:
        
        cmdLine:/private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 5:55:44 PM, end: 9/9/2021 6:05:45 PM)
        
        Return code:      -100
        Raw output file:      /tmp/helix/working/A3A908C3/w/C3CA09C3/uploads/Reports/tracing.eventpipe/diagnosticport/diagnosticport/diagnosticport.output.txt
        Raw output:
        BEGIN EXECUTION
        /tmp/helix/working/A3A908C3/p/corerun -p System.Reflection.Metadata.MetadataUpdater.IsSupported=false diagnosticport.dll ''
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40817-1631234565-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24666-1631230447-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24586-1631230365-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40059-1631233750-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40475-1631234269-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40099-1631233790-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-28398-1631231679-socket
          0.0s: Deleted
          0.0s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40230-1631233939-socket
          0.1s: Deleted
          0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24706-1631230484-socket
          0.1s: Deleted
          0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24626-1631230413-socket
          0.1s: Deleted
          0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40857-1631234593-socket
          0.1s: Deleted
          0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-38884-1631233173-socket
          0.1s: Deleted
          0.1s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-35191-1631232671-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40777-1631234536-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40897-1631234621-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40555-1631234427-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-38844-1631233142-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-29267-1631231999-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-43866-1631235117-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-35948-1631232751-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-28896-1631231966-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-37810-1631233004-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24506-1631230303-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-37730-1631232947-socket
          0.2s: Deleted
          0.2s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40343-1631234159-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-37770-1631232976-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-24546-1631230332-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-25962-1631230872-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40515-1631234297-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40423-1631234239-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-35577-1631232719-socket
          0.3s: Deleted
          0.3s: Attempting to delete the zombied pipe: /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/dotnet-diagnostic-40383-1631234212-socket
          0.3s: Deleted
          0.3s: ::== Running test: TEST_MultipleConnectPortsNoSuspend
          0.4s: Server 0 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_wtwfofle.cho'
          0.4s: Server 1 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_li55xbgm.smv'
          0.4s: Server 2 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_n4wxyeac.kxk'
          0.4s: Server 3 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_whgefg5g.tvf'
          0.4s: export DOTNET_DiagnosticPorts=/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_wtwfofle.cho,nosuspend;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_li55xbgm.smv,nosuspend;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_n4wxyeac.kxk,nosuspend;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_whgefg5g.tvf,nosuspend;
          0.4s: running sub-process: /private/tmp/helix/working/A3A908C3/p/corerun /private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.dll 0
          0.7s: subprocess started: True
          0.7s: subprocess PID: 44016
          0.8s: Server 0 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
          0.8s: Server 3 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
          0.8s: Server 2 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
          0.8s: Server 1 got advertise { Magic=System.Byte[]; ClrInstanceId=391eb463-0aa5-4b3d-a6b4-f98af3913d3b; ProcessId=44016; Unused=0; }
          0.8s: Sending 'exit' to subprocess stdin
          0.8s: ================= Subprocess Exited =================
          0.8s: SubProcess exited - Exit code: 0
          0.8s: ----------------------------------------
          0.8s: Subprocess stdout: 
          0.4s: Subprocess started!  Waiting for input...
          0.4s: Received 'exit'.  Exiting...
          0.8s: Subprocess stderr: 
          0.8s: ----------------------------------------
          0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_wtwfofle.cho
          0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_li55xbgm.smv
          0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_n4wxyeac.kxk
          0.8s: Shutting down Reverse Server at /var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_whgefg5g.tvf
          0.8s: Test passed: True
          0.8s: 
          0.8s: ::== Running test: TEST_MultipleConnectPortsSuspend
          0.8s: Server 0 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_1wvlzrzn.dnf'
          0.8s: Server 1 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_i1mk1z2d.ofi'
          0.8s: Server 2 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_fxvj2fxx.y1k'
          0.8s: Server 3 address is '/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_ca1wluga.wef'
          0.8s: export DOTNET_DiagnosticPorts=/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_1wvlzrzn.dnf;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_i1mk1z2d.ofi;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_fxvj2fxx.y1k;/var/folders/rb/9k6f88dd7h72my8wttsbnms00000gy/T/DOTNET_TRACE_TESTS_ca1wluga.wef;
          0.8s: running sub-process: /private/tmp/helix/working/A3A908C3/p/corerun /private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.dll 0
          0.8s: subprocess started: True
          0.8s: subprocess PID: 44018
          0.9s: Standard Diagnostics Server connection not created yet -> try again in 100 ms
          1.2s: Starting EventPipeSession over standard connection
          1.2s: Started EventPipeSession over standard connection with session id: 0x7ffa31508cb0
          1.2s: Runtime HAS NOT resumed (expects: true): True
          1.2s: Server 0 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
          1.2s: Creating EventPipeEventSource
          1.2s: Port 0 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
          1.3s: Port 0 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
          1.3s: Runtime HAS NOT resumed (expects: true): True
          1.3s: Server 1 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
          1.3s: Port 1 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
          1.3s: Port 1 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
          1.3s: Runtime HAS NOT resumed (expects: true): True
          1.3s: Server 2 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
          1.3s: Port 2 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
          1.3s: Port 2 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
          1.3s: Runtime HAS NOT resumed (expects: true): True
          1.3s: Server 3 got advertise { Magic=System.Byte[]; ClrInstanceId=73936867-70d3-4677-8339-d7540fb3ca99; ProcessId=44018; Unused=0; }
          1.3s: Port 3 sent: { Header={ Magic=System.Byte[]; Size=20; CommandSet=4; CommandId=1; Reserved=0 }; }
          1.3s: Port 3 received: { Header={ Magic=System.Byte[]; Size=24; CommandSet=255; CommandId=0; Reserved=0 }; Payload=[ 0x00 0x00 0x00 0x00  ]}
          1.3s: Waiting on EEStartupStarted event
          1.3s: Created EventPipeEventSource
          1.3s: Starting processing
          1.5s: Saw EEStartupStarted Event
          1.5s: Stopping EventPipeSession
        
        cmdLine:/private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh Timed Out (timeout in milliseconds: 600000 from variable __TestTimeout, start: 9/9/2021 5:55:44 PM, end: 9/9/2021 6:05:45 PM)
        Test Harness Exitcode is : -100
        To run the test:
        > set CORE_ROOT=/tmp/helix/working/A3A908C3/p
        > /private/tmp/helix/working/A3A908C3/w/C3CA09C3/e/tracing/eventpipe/diagnosticport/diagnosticport/diagnosticport.sh
  Finished:    tracing.eventpipe.XUnitWrapper

@thaystg
Copy link
Member Author

thaystg commented Sep 10, 2021

/backport to release/6.0

@github-actions
Copy link
Contributor

Started backporting to release/6.0: https://github.com/dotnet/runtime/actions/runs/1221866621

@thaystg thaystg merged commit 271e129 into dotnet:main Sep 11, 2021
@ghost ghost locked as resolved and limited conversation to collaborators Oct 11, 2021
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.

Projects

None yet

Development

Successfully merging this pull request may close these issues.

3 participants