<?xml version="1.0" encoding="UTF-16"?>
<WTT-Logger>
  <RTI ID="3356986142" Machine="DESKTOP-PDMIGNN" ProcessName="C:\Data\test\bin\WlanAcceptance\TE.exe" ProcessID="12936" ThreadID="3936" BaseTime="2026:9:28 10:46:53:285" Frequency="10000000" />
  <CTX ID="384048256" Current="WTTLOG" Parent="ROOT" />
  <Msg UserText="Device string in use: $LocalPub:WTTLogPFMode=user($LogFile:file=&quot;C:\Data\test\bin\WlanAcceptance\Te.wtl&quot;,writemode=overwrite,encoding=unicode,EnableLvl=WexStartTest|WexEndTest|WexXml|WexProperty|WexCreateContext|WexCloseContext|*)" CA="5991" LA="7536">
    <rti id="3356986142" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="WTTLogger_CPP_GitEnlistment(IMProdBldA); Version: 2.7.3483.0" CA="6487" LA="31941">
    <rti id="3356986142" />
    <ctx id="384048256" />
  </Msg>
  <Machine Build="26100.00" Platform="AMD64" OS="Windows 10 IoT Enterprise LTSC 2024 Evaluation" ServicePack="" Version="6.3" BuildLab="ge_release" BuildDate="240331-1435" Language="English (US) (REDMOND)" Config="n/a" CA="25517" LA="33321">
    <rti id="3356986142" />
    <ctx id="384048256" />
  </Machine>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Taef:Title" AttributeValue="Test Authoring and Execution Framework v10.104k for x64" CA="94789" LA="96622">
    <Data>
      <WexTraceInfo ThreadId="3936" ProcessId="12936" TimeStamp="116855748781" LogSessionId="0" SessionTraceCount="1" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="3356986142" />
    <ctx id="384048256" />
  </MetadataRef>
  <RTI ID="7294411" Machine="DESKTOP-PDMIGNN" ProcessName="C:\Data\test\bin\WlanAcceptance\TE.exe" ProcessID="12936" ThreadID="9124" BaseTime="2026:9:28 10:46:53:396" Frequency="10000000" />
  <Msg UserText="CoreClrProfile metadata was found to be present. TAEF will load CoreClr from &quot;C:\Data\test\bin\WlanAcceptance\TestNetv8.0&quot;." CA="913" LA="2411">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116856831276" LogSessionId="1" SessionTraceCount="1" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="C:\Data\test\bin\WlanAcceptance\Microsoft.Test.Networking.Wireless.RoamingTests.dll" CA="2449" LA="5833">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116856834730" LogSessionId="1" SessionTraceCount="2" />
      <WexContext><![CDATA[ModuleScope]]></WexContext>
      <StartGroup />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Architecture" AttributeValue="x64" CA="5389" LA="7171">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116856836020" LogSessionId="1" SessionTraceCount="3" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="CoreClrProfile" AttributeValue="TestNetv8.0" CA="6667" LA="7605">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116856838958" LogSessionId="1" SessionTraceCount="4" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="TaefTestType" AttributeValue="Managed" CA="8296" LA="9135">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116856840083" LogSessionId="1" SessionTraceCount="5" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </MetadataRef>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests.RunModuleSetup" CA="1561164" LA="1562127">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116858394038" LogSessionId="1" SessionTraceCount="6" />
      <WexContext><![CDATA[Setup]]></WexContext>
      <StartGroup />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests.RunModuleSetup" CA="1668676" LA="1669721">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116858501485" LogSessionId="1" SessionTraceCount="7" />
      <WexContext><![CDATA[Setup]]></WexContext>
      <EndGroup Result="Passed" />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests" CA="1668999" LA="1671275">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116858502186" LogSessionId="1" SessionTraceCount="8" />
      <WexContext><![CDATA[ClassScope]]></WexContext>
      <StartGroup />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests" CA="1669165" LA="1671780">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116858502371" LogSessionId="1" SessionTraceCount="9" />
      <WexContext><![CDATA[Create]]></WexContext>
      <StartGroup />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests" CA="1680973" LA="1682033">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116858514004" LogSessionId="1" SessionTraceCount="10" />
      <WexContext><![CDATA[Create]]></WexContext>
      <EndGroup Result="Passed" />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="ClassSetup" CA="1681188" LA="1682514">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116858514384" LogSessionId="1" SessionTraceCount="11" />
      <WexContext><![CDATA[Setup]]></WexContext>
      <StartGroup />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="ClassSetup" CA="2977462" LA="2978314">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859810173" LogSessionId="1" SessionTraceCount="12" />
      <WexContext><![CDATA[Setup]]></WexContext>
      <EndGroup Result="Passed" />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests.Roam_Custom" CA="2977805" LA="2978941">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859810969" LogSessionId="1" SessionTraceCount="13" />
      <WexContext><![CDATA[TestScope]]></WexContext>
      <StartGroup />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Description" AttributeValue="Roams between APs associated using custom authentication" CA="2979798" LA="2980400">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859811202" LogSessionId="1" SessionTraceCount="14" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Priority" AttributeValue="2" CA="2980077" LA="2982237">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859813275" LogSessionId="1" SessionTraceCount="15" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="ThreadingModel" AttributeValue="MTA" CA="2982155" LA="2982732">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859813443" LogSessionId="1" SessionTraceCount="16" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </MetadataRef>
  <CTX ID="42145314" Current="HlkTest.RoamingTests.RoamingTests.Roam_Custom" Parent="WTTLOG" />
  <StartTest Title="HlkTest.RoamingTests.RoamingTests.Roam_Custom" TUID="" CA="2987306" LA="2988717">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859815942" LogSessionId="1" SessionTraceCount="17" />
      <TestGroup TestId="30728752-4ACA-4E11-B2AA-0E6325B5AA64" />
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </StartTest>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Architecture" AttributeValue="x64" CA="2988084" LA="2989185">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859821171" LogSessionId="1" SessionTraceCount="18" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="CoreClrProfile" AttributeValue="TestNetv8.0" CA="2988258" LA="2990100">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859821461" LogSessionId="1" SessionTraceCount="19" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="TaefTestType" AttributeValue="Managed" CA="2988403" LA="2990361">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859821624" LogSessionId="1" SessionTraceCount="20" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Description" AttributeValue="Roams between APs associated using custom authentication" CA="2992059" LA="2992991">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859821768" LogSessionId="1" SessionTraceCount="21" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="Priority" AttributeValue="2" CA="2992349" LA="2993679">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859825506" LogSessionId="1" SessionTraceCount="22" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </MetadataRef>
  <MetadataRef Id="WexLogger" Inherit="False" AttributeName="ThreadingModel" AttributeValue="MTA" CA="2992552" LA="2994097">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="116859825731" LogSessionId="1" SessionTraceCount="23" />
      <WexContext><![CDATA[TAEF]]></WexContext>
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </MetadataRef>
  <RTI ID="65771941" Machine="DESKTOP-PDMIGNN" ProcessName="C:\Data\test\bin\WlanAcceptance\TE.exe" ProcessID="12936" ThreadID="8612" BaseTime="2026:9:28 10:46:53:964" Frequency="10000000" />
  <Msg UserText="16:16:53.959 Connecting to server" CA="1723" LA="3126">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116862447835" LogSessionId="1" SessionTraceCount="24" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.352 Added Client 7f60fe09-bce0-4737-8468-834b642dd1b7 to Reservation Queue" CA="3875705" LA="3877043">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116866321257" LogSessionId="1" SessionTraceCount="25" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.434 Client owns lease, starting heartbeat" CA="4699320" LA="4700708">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116867144526" LogSessionId="1" SessionTraceCount="26" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.436 Connected to AP controller successfully" CA="4716241" LA="4717606">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116867165300" LogSessionId="1" SessionTraceCount="27" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.439 Sending heartbeat" CA="4749481" LA="4750132">
    <Data>
      <WexTraceInfo ThreadId="13960" ProcessId="10464" TimeStamp="116867198824" LogSessionId="1" SessionTraceCount="28" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.440 Enum Routers" CA="4753400" LA="4754548">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116867203172" LogSessionId="1" SessionTraceCount="29" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.586 GetRouterCollection : routers.Count is 2" CA="6216584" LA="6218092">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116868662562" LogSessionId="1" SessionTraceCount="30" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:54.587 GetRouterCollection found Routers : " CA="6229327" LA="6230969">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116868670399" LogSessionId="1" SessionTraceCount="31" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.207 ========================================" CA="12430939" LA="12432371">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116874876826" LogSessionId="1" SessionTraceCount="32" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.208 - Starting Variation: Roaming SuddenLossOfSignal" CA="12445019" LA="12446486">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116874884895" LogSessionId="1" SessionTraceCount="33" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.209 -     2.4GHz radio" CA="12449260" LA="12450550">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116874898736" LogSessionId="1" SessionTraceCount="34" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.210 ----------------------------------------" CA="12453665" LA="12455240">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116874902979" LogSessionId="1" SessionTraceCount="35" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.211 [ROAM] Configuring routers and WLAN connection" CA="12466984" LA="12467721">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116874916537" LogSessionId="1" SessionTraceCount="36" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.212 [ROAM] Deleting all wlan profiles" CA="12471181" LA="12472393">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116874921252" LogSessionId="1" SessionTraceCount="37" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:16:55.222 [ROAM] Configuring Routers" CA="12578172" LA="12579054">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116875024746" LogSessionId="1" SessionTraceCount="38" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.144 [ROAM] Reading the BSSIDs configured on router for validation when connected" CA="71797995" LA="71799874">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116934243473" LogSessionId="1" SessionTraceCount="39" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.146 [ROAM] Router[0] 2.4GHz radio:" CA="71817255" LA="71818480">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116934266534" LogSessionId="1" SessionTraceCount="40" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.147 [ROAM]   SSID           = WlanHCK-5328" CA="71820378" LA="71821049">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116934270769" LogSessionId="1" SessionTraceCount="41" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.410 [ROAM]   BSSID          = 94:FF:06:31:F5:00" CA="74459504" LA="74460657">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936908859" LogSessionId="1" SessionTraceCount="42" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.411 [ROAM]   Authentication = WPA2PSK" CA="74467073" LA="74467831">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936914142" LogSessionId="1" SessionTraceCount="43" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.412 [ROAM]   Encryption     = AES" CA="74469719" LA="74470043">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936920920" LogSessionId="1" SessionTraceCount="44" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.412 [ROAM]   PhyType        = n" CA="74471538" LA="74472255">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936923029" LogSessionId="1" SessionTraceCount="45" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.412 [ROAM]   Channel        = 1" CA="74474371" LA="74475258">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936924997" LogSessionId="1" SessionTraceCount="46" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.412 [ROAM] Router[0] 5GHz radio:" CA="74477633" LA="74477891">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936928237" LogSessionId="1" SessionTraceCount="47" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.413 [ROAM]   SSID           = WlanHCK-5328_5" CA="74479849" LA="74480256">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116936930826" LogSessionId="1" SessionTraceCount="48" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.698 [ROAM]   BSSID          = 94:FF:06:31:F5:10" CA="78866286" LA="78867962">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116939786056" LogSessionId="1" SessionTraceCount="49" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.852 [ROAM]   Authentication = WPA2PSK" CA="78872833" LA="78873515">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116941321662" LogSessionId="1" SessionTraceCount="50" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.852 [ROAM]   Encryption     = AES" CA="78877952" LA="78878726">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116941326449" LogSessionId="1" SessionTraceCount="51" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.853 [ROAM]   PhyType        = ac" CA="78881829" LA="78882544">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116941331692" LogSessionId="1" SessionTraceCount="52" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.853 [ROAM]   Channel        = 36" CA="78886683" LA="78888134">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116941335294" LogSessionId="1" SessionTraceCount="53" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.854 [ROAM] Router[1] 2.4GHz radio:" CA="78891686" LA="78893089">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116941340766" LogSessionId="1" SessionTraceCount="54" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:01.854 [ROAM]   SSID           = WlanHCK-5328" CA="78896045" LA="78896644">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116941345623" LogSessionId="1" SessionTraceCount="55" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.108 [ROAM]   BSSID          = 94:FF:06:30:42:41" CA="81435416" LA="81436339">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943880834" LogSessionId="1" SessionTraceCount="56" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.108 [ROAM]   Authentication = WPA2PSK" CA="81477529" LA="81479351">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943889244" LogSessionId="1" SessionTraceCount="57" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.113 [ROAM]   Encryption     = AES" CA="81482841" LA="81484419">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943931866" LogSessionId="1" SessionTraceCount="58" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.113 [ROAM]   PhyType        = n" CA="81487118" LA="81488393">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943936568" LogSessionId="1" SessionTraceCount="59" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.114 [ROAM]   Channel        = 11" CA="81491452" LA="81492349">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943940780" LogSessionId="1" SessionTraceCount="60" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.114 [ROAM] Router[1] 5GHz radio:" CA="81497063" LA="81498023">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943945922" LogSessionId="1" SessionTraceCount="61" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.115 [ROAM]   SSID           = WlanHCK-5328_5" CA="81500118" LA="81501850">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116943950579" LogSessionId="1" SessionTraceCount="62" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.401 [ROAM]   BSSID          = 94:FF:06:30:42:50" CA="84372966" LA="84374599">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116946818858" LogSessionId="1" SessionTraceCount="63" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.402 [ROAM]   Authentication = WPA2PSK" CA="84402847" LA="84404790">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116946826902" LogSessionId="1" SessionTraceCount="64" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.405 [ROAM]   Encryption     = AES" CA="84408403" LA="84409819">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116946857055" LogSessionId="1" SessionTraceCount="65" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.406 [ROAM]   PhyType        = ac" CA="84412588" LA="84413711">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116946862144" LogSessionId="1" SessionTraceCount="66" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.406 [ROAM]   Channel        = 149" CA="84415957" LA="84416553">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116946866066" LogSessionId="1" SessionTraceCount="67" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.407 [ROAM] Setting up static route from AP server to router 1" CA="84421155" LA="84422304">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116946871562" LogSessionId="1" SessionTraceCount="68" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:02.561 [ROAM] Bringing up the Lan ports on all the routers" CA="89202199" LA="89203551">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116948411033" LogSessionId="1" SessionTraceCount="69" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.025 [ROAM] Creating profile SSID=WlanHCK-5328 Auth=psk2 Cipher=aes Radio=2.4GHz" CA="90609100" LA="90610600">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953053093" LogSessionId="1" SessionTraceCount="70" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.032 Deleting Profile WlanHCK-5328" CA="90676405" LA="90678093">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953125967" LogSessionId="1" SessionTraceCount="71" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.034 WLAN[3] profile WlanHCK-5328 not found (DeleteProfile:1020)" CA="90694637" LA="90696088">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953144029" LogSessionId="1" SessionTraceCount="72" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.050 [ROAM] Creating profile SSID=WlanHCK-5328_5 Auth=psk2 Cipher=aes Radio=5GHz" CA="90858062" LA="90860137">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953307400" LogSessionId="1" SessionTraceCount="73" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.053 Deleting Profile WlanHCK-5328_5" CA="90896098" LA="90896548">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953333925" LogSessionId="1" SessionTraceCount="74" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.055 WLAN[3] profile WlanHCK-5328_5 not found (DeleteProfile:1020)" CA="90916078" LA="90916424">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953356022" LogSessionId="1" SessionTraceCount="75" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.067 [ROAM] Starting echo services" CA="91031713" LA="91032099">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116953478849" LogSessionId="1" SessionTraceCount="76" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.166 [ROAM] Testing Roaming with router WlanHCK-5328" CA="92022343" LA="92024937">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116954469357" LogSessionId="1" SessionTraceCount="77" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.167 [ROAM] Turning routers 0 on and router 1 off" CA="92061085" LA="92061827">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116954476216" LogSessionId="1" SessionTraceCount="78" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.172 [ROAM] Setting power of 2.4GHz radio in router 0 to 84" CA="92074276" LA="92075385">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116954522812" LogSessionId="1" SessionTraceCount="79" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:03.231 [ROAM] Turning 2.4GHz radio in router 0 on" CA="92666156" LA="92667318">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116955115194" LogSessionId="1" SessionTraceCount="80" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:04.908 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="109444783" LA="109445191">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="116971882919" LogSessionId="1" SessionTraceCount="81" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:07.006 [ROAM] Setting power of 2.4GHz radio in router 1 to 4" CA="130415963" LA="130416972">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116992864683" LogSessionId="1" SessionTraceCount="82" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:07.704 [ROAM] Turning 2.4GHz radio in router 1 off" CA="137400268" LA="137400802">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="116999847806" LogSessionId="1" SessionTraceCount="83" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:09.236 [ROAM] Connecting to Echo Server using SSID WlanHCK-5328" CA="152718193" LA="152719854">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117015161944" LogSessionId="1" SessionTraceCount="84" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.003 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="170389275" LA="170390057">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="117032836853" LogSessionId="1" SessionTraceCount="85" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.142 [ROAM] Skipped 1 networks to find WlanHCK-5328 in scan list. Scanning for BSSID 94:FF:06:31:F5:00" CA="171773127" LA="171773461">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117034222395" LogSessionId="1" SessionTraceCount="86" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.143 [ROAM] Found expected BSSID 94:FF:06:31:F5:00 in 1.904 seconds" CA="171808919" LA="171809252">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117034232760" LogSessionId="1" SessionTraceCount="87" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.146 [ROAM] Connecting to WlanHCK-5328" CA="171812688" LA="171812947">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117034263538" LogSessionId="1" SessionTraceCount="88" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.910 WLAN[1] ACM Notification ConnectionComplete on interface 736c6fce-4bdf-4778-9d26-bc4f170b07c5&#xD;&#xA;      |wlanConnectionMode : wlan_connection_mode_profile&#xD;&#xA;      |SSID               : WlanHCK-5328&#xD;&#xA;      |dot11BssType       : dot11_BSS_type_infrastructure&#xD;&#xA;      |bSecurityEnabled   : True&#xD;&#xA;      |wlanReasonCode     : WLAN_REASON_CODE_SUCCESS&#xD;&#xA;      |dwFlags            : NONE&#xD;&#xA; (FireConnectionCompleted:857)" CA="179660342" LA="179660675">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="117041905258" LogSessionId="1" SessionTraceCount="89" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.938 [ROAM] Ensuring Adapter is connected to the local network" CA="179791056" LA="179791841">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117042186872" LogSessionId="1" SessionTraceCount="90" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:11.945 Querying DHCP Lease Information" CA="179899848" LA="179901333">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117042253853" LogSessionId="1" SessionTraceCount="91" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:16.882 Router address is 172.31.98.1" CA="229438573" LA="229439473">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117091622318" LogSessionId="1" SessionTraceCount="92" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:16.909 Retrieving Adapter Addresses" CA="229625888" LA="229627170">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117091893319" LogSessionId="1" SessionTraceCount="93" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.177 Adapter Local Address 172.31.99.228. DadState = IpDadStatePreferred" CA="232211694" LA="232213297">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117094570143" LogSessionId="1" SessionTraceCount="94" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.186 Adapter Addresses Local 172.31.99.228 Gateway 172.31.98.1" CA="232339822" LA="232340830">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117094665277" LogSessionId="1" SessionTraceCount="95" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.199 Retrieving Route Information" CA="232814982" LA="232817851">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117094794457" LogSessionId="1" SessionTraceCount="96" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.288 A route to the destination exists" CA="233379255" LA="233382125">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117095686025" LogSessionId="1" SessionTraceCount="97" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.303 Route Destination 172.31.98.0 Mask 255.255.254.0 NextHop 0.0.0.0 Index 19" CA="233389541" LA="233389849">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117095835899" LogSessionId="1" SessionTraceCount="98" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.304 A route was found" CA="233534789" LA="233535165">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117095843294" LogSessionId="1" SessionTraceCount="99" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.318 Resolving gateway" CA="233741391" LA="233741937">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117095988766" LogSessionId="1" SessionTraceCount="100" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.484 Gateway address 172.31.98.1 resolved to physical address 94-FF-06-CB-1F-50" CA="235205845" LA="235208806">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117097647523" LogSessionId="1" SessionTraceCount="101" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.485 Gateway resolved" CA="235226213" LA="235226584">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117097659348" LogSessionId="1" SessionTraceCount="102" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.487 [ROAM] Waiting up to 20 seconds for network connectivity to 192.168.136.135:7777" CA="235236933" LA="235237505">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117097679235" LogSessionId="1" SessionTraceCount="103" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.489 [EchoClient] LocalAddress: 172.31.99.228" CA="235250990" LA="235251386">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117097695414" LogSessionId="1" SessionTraceCount="104" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.490 [EchoClient] Server:       192.168.136.135:7777" CA="235259515" LA="235265058">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117097708489" LogSessionId="1" SessionTraceCount="105" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:17.491 [EchoClient] EchoBytes:    1024" CA="235266179" LA="235268042">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117097712595" LogSessionId="1" SessionTraceCount="106" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.532 [EchoClient] Created Socket: 2352" CA="265698280" LA="265698671">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117128122491" LogSessionId="1" SessionTraceCount="107" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.535 [EchoClient] m_win32Sockets.Close(2352)" CA="265733932" LA="265735124">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117128156421" LogSessionId="1" SessionTraceCount="108" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.540 [ROAM] Verifying network connectivity took 8.601 seconds" CA="265756724" LA="265758928">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117128200880" LogSessionId="1" SessionTraceCount="109" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.541 [ROAM] Making a TCP connection to Echo Services" CA="265760301" LA="265761437">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117128209719" LogSessionId="1" SessionTraceCount="110" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.542 [ROAM] Connecting to echo server" CA="265775981" LA="265777105">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117128226039" LogSessionId="1" SessionTraceCount="111" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.542 [ROAM] Restart echo services to free stale connections" CA="265779859" LA="265780277">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117128229534" LogSessionId="1" SessionTraceCount="112" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.623 [ROAM] Connecting echo client" CA="266602697" LA="266608154">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129030091" LogSessionId="1" SessionTraceCount="113" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.625 [EchoClient] LocalAddress: 172.31.99.228" CA="266604939" LA="266609862">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129055940" LogSessionId="1" SessionTraceCount="114" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.625 [EchoClient] Server:       192.168.136.135:7777" CA="266624796" LA="266627709">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129058975" LogSessionId="1" SessionTraceCount="115" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.627 [EchoClient] EchoBytes:    1024" CA="266628829" LA="266629154">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129078907" LogSessionId="1" SessionTraceCount="116" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.633 [EchoClient] Created Socket: 2364" CA="266684493" LA="266684910">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129132771" LogSessionId="1" SessionTraceCount="117" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.665 [ROAM] Currently associated with 94:FF:06:31:F5:00 - router 0" CA="267044782" LA="267045192">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129453913" LogSessionId="1" SessionTraceCount="118" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.669 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="267088772" LA="267090438">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129498137" LogSessionId="1" SessionTraceCount="119" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.675 [EchoClient] Performing Echo" CA="267130098" LA="267130448">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129551092" LogSessionId="1" SessionTraceCount="120" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.679 [EchoClient] Sent 1024 bytes" CA="267169273" LA="267173075">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129597468" LogSessionId="1" SessionTraceCount="121" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.682 [EchoClient] Waiting up to 90 seconds for response" CA="267199391" LA="267203518">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129622362" LogSessionId="1" SessionTraceCount="122" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.687 [EchoClient] Received 1024-byte echo in 0.004 seconds" CA="267237176" LA="267239838">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129672095" LogSessionId="1" SessionTraceCount="123" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:20.689 [ROAM] Setting power of 2.4GHz radio in router 1 to 84" CA="267311434" LA="267313194">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117129693667" LogSessionId="1" SessionTraceCount="124" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:21.369 [ROAM] Turning 2.4GHz radio in router 1 on" CA="274049809" LA="274050252">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117136498386" LogSessionId="1" SessionTraceCount="125" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:22.922 [ROAM] Turning 2.4GHz radio in router 0 off" CA="289577547" LA="289578997">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117152022714" LogSessionId="1" SessionTraceCount="126" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:24.435 Sending heartbeat" CA="304702713" LA="304703402">
    <Data>
      <WexTraceInfo ThreadId="4152" ProcessId="10464" TimeStamp="117167151837" LogSessionId="1" SessionTraceCount="127" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:26.695 [ROAM] Associated as expected with bssid 94:FF:06:30:42:41 of router 1" CA="327306831" LA="327308434">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117189752549" LogSessionId="1" SessionTraceCount="128" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:26.696 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="327342760" LA="327344748">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117189760671" LogSessionId="1" SessionTraceCount="129" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:26.699 [EchoClient] Performing Echo" CA="327347089" LA="327348495">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117189796342" LogSessionId="1" SessionTraceCount="130" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:26.700 [EchoClient] Sent 1024 bytes" CA="327354515" LA="327356051">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117189804172" LogSessionId="1" SessionTraceCount="131" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:26.700 [EchoClient] Waiting up to 90 seconds for response" CA="327357664" LA="327358848">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117189807674" LogSessionId="1" SessionTraceCount="132" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:17:28.404 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="344493486" LA="344494084">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117206852489" LogSessionId="1" SessionTraceCount="133" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:17:28.404 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="344706308" LA="344712661">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117206947091" LogSessionId="1" SessionTraceCount="134" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:17:28.404 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="344709984" LA="344714088">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117207159677" LogSessionId="1" SessionTraceCount="135" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:17:28.404 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="344715177" LA="344716707">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117207163405" LogSessionId="1" SessionTraceCount="136" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:17:28.437 [ROAM] Cleaning up Echo Client" CA="344720465" LA="344720955">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117207171340" LogSessionId="1" SessionTraceCount="137" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.437 [EchoClient] m_win32Sockets.Close(2364)" CA="344723211" LA="344723531">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117207174074" LogSessionId="1" SessionTraceCount="138" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.438 [ROAM] Connecting to echo server" CA="344730666" LA="344734735">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117207180147" LogSessionId="1" SessionTraceCount="139" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.438 [ROAM] Restart echo services to free stale connections" CA="344737911" LA="344738536">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117207188411" LogSessionId="1" SessionTraceCount="140" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.609 [ROAM] Connecting echo client" CA="346494509" LA="346495362">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117208896208" LogSessionId="1" SessionTraceCount="141" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.614 [EchoClient] LocalAddress: 172.31.99.228" CA="346511535" LA="346513433">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117208949472" LogSessionId="1" SessionTraceCount="142" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.616 [EchoClient] Server:       192.168.136.135:7777" CA="346514538" LA="346514832">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117208964731" LogSessionId="1" SessionTraceCount="143" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:28.616 [EchoClient] EchoBytes:    1024" CA="346516418" LA="346517390">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117208967465" LogSessionId="1" SessionTraceCount="144" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:38.702 [EchoClient] Created Socket: 2460" CA="447376078" LA="447377077">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117309821566" LogSessionId="1" SessionTraceCount="145" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:38.703 [ROAM] Turning 2.4GHz radio in router 0 on" CA="447414726" LA="447416685">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117309830450" LogSessionId="1" SessionTraceCount="146" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:40.402 [ROAM] Turning 2.4GHz radio in router 1 off" CA="464380887" LA="464381700">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117326828847" LogSessionId="1" SessionTraceCount="147" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:42.761 [ROAM] Associated as expected with bssid 94:FF:06:31:F5:00 of router 0" CA="487973321" LA="487974623">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117350416829" LogSessionId="1" SessionTraceCount="148" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:42.762 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="488023850" LA="488026337">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117350427481" LogSessionId="1" SessionTraceCount="149" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:42.767 [EchoClient] Performing Echo" CA="488032390" LA="488033929">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117350478774" LogSessionId="1" SessionTraceCount="150" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:42.768 [EchoClient] Sent 1024 bytes" CA="488037577" LA="488038598">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117350488203" LogSessionId="1" SessionTraceCount="151" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:42.769 [EchoClient] Waiting up to 90 seconds for response" CA="488039812" LA="488040373">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="117350490527" LogSessionId="1" SessionTraceCount="152" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:17:54.434 Sending heartbeat" CA="604697413" LA="604698945">
    <Data>
      <WexTraceInfo ThreadId="4152" ProcessId="10464" TimeStamp="117467142533" LogSessionId="1" SessionTraceCount="153" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:18:03.049 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="690851700" LA="690853537">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="117553296815" LogSessionId="1" SessionTraceCount="154" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:18:24.435 Sending heartbeat" CA="904709740" LA="904711494">
    <Data>
      <WexTraceInfo ThreadId="3248" ProcessId="10464" TimeStamp="117767155938" LogSessionId="1" SessionTraceCount="155" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:18:54.446 Sending heartbeat" CA="1204820247" LA="1204822308">
    <Data>
      <WexTraceInfo ThreadId="13740" ProcessId="10464" TimeStamp="118067266265" LogSessionId="1" SessionTraceCount="156" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:03.043 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="1290787876" LA="1290789600">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="118153231254" LogSessionId="1" SessionTraceCount="157" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:12.781 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSAWaitForMultipleEvents timeout: The wait operation timed out. (0x80070102): hr=0x80131500" CA="1388167756" LA="1388169867">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250611489" LogSessionId="1" SessionTraceCount="158" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:12.781 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="1388220677" LA="1388222196">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250621622" LogSessionId="1" SessionTraceCount="159" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:12.781 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="1388224631" LA="1388224979">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250674432" LogSessionId="1" SessionTraceCount="160" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:12.781 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="1388227007" LA="1388227775">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250677424" LogSessionId="1" SessionTraceCount="161" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:19:12.788 [ROAM] Cleaning up Echo Client" CA="1388230367" LA="1388231272">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250680797" LogSessionId="1" SessionTraceCount="162" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.788 [EchoClient] m_win32Sockets.Close(2460)" CA="1388232583" LA="1388233500">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250683566" LogSessionId="1" SessionTraceCount="163" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.789 [ROAM] Connecting to echo server" CA="1388244466" LA="1388245114">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250693287" LogSessionId="1" SessionTraceCount="164" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.789 [ROAM] Restart echo services to free stale connections" CA="1388248731" LA="1388249135">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118250698184" LogSessionId="1" SessionTraceCount="165" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.820 [ROAM] Connecting echo client" CA="1388556742" LA="1388558080">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118251004259" LogSessionId="1" SessionTraceCount="166" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.821 [EchoClient] LocalAddress: 172.31.99.228" CA="1388560892" LA="1388561465">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118251010544" LogSessionId="1" SessionTraceCount="167" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.821 [EchoClient] Server:       192.168.136.135:7777" CA="1388563007" LA="1388564460">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118251013999" LogSessionId="1" SessionTraceCount="168" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.821 [EchoClient] EchoBytes:    1024" CA="1388566165" LA="1388569153">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118251016254" LogSessionId="1" SessionTraceCount="169" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.828 [EchoClient] Created Socket: 772" CA="1388664245" LA="1388665131">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118251081355" LogSessionId="1" SessionTraceCount="170" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:12.831 [ROAM] Turning 2.4GHz radio in router 1 on" CA="1388668421" LA="1388669383">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118251117811" LogSessionId="1" SessionTraceCount="171" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:15.094 [ROAM] Turning 2.4GHz radio in router 0 off" CA="1411301456" LA="1411303657">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118273746503" LogSessionId="1" SessionTraceCount="172" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:17.446 [ROAM] Associated as expected with bssid 94:FF:06:30:42:41 of router 1" CA="1434819842" LA="1434821578">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118297265211" LogSessionId="1" SessionTraceCount="173" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:17.447 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="1434858291" LA="1434860171">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118297274040" LogSessionId="1" SessionTraceCount="174" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:17.451 [EchoClient] Performing Echo" CA="1434862498" LA="1434863832">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118297311973" LogSessionId="1" SessionTraceCount="175" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:17.452 [EchoClient] Sent 1024 bytes" CA="1434871122" LA="1434872682">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118297320162" LogSessionId="1" SessionTraceCount="176" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:17.452 [EchoClient] Waiting up to 90 seconds for response" CA="1434876008" LA="1434877520">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118297324630" LogSessionId="1" SessionTraceCount="177" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:24.433 Sending heartbeat" CA="1504690383" LA="1504692486">
    <Data>
      <WexTraceInfo ThreadId="13676" ProcessId="10464" TimeStamp="118367135237" LogSessionId="1" SessionTraceCount="178" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:37.130 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="1631654865" LA="1631656730">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494100539" LogSessionId="1" SessionTraceCount="179" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:37.130 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="1631699877" LA="1631701185">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494109021" LogSessionId="1" SessionTraceCount="180" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:37.130 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="1631703094" LA="1631703974">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494153077" LogSessionId="1" SessionTraceCount="181" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:19:37.130 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="1631708978" LA="1631710489">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494157094" LogSessionId="1" SessionTraceCount="182" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:19:37.136 [ROAM] Cleaning up Echo Client" CA="1631713209" LA="1631714119">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494163626" LogSessionId="1" SessionTraceCount="183" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.136 [EchoClient] m_win32Sockets.Close(772)" CA="1631715054" LA="1631715928">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494166373" LogSessionId="1" SessionTraceCount="184" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.140 [ROAM] Connecting to echo server" CA="1631751779" LA="1631752556">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494201475" LogSessionId="1" SessionTraceCount="185" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.140 [ROAM] Restart echo services to free stale connections" CA="1631753466" LA="1631754826">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494204763" LogSessionId="1" SessionTraceCount="186" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.208 [ROAM] Connecting echo client" CA="1632438657" LA="1632439217">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494883167" LogSessionId="1" SessionTraceCount="187" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.209 [EchoClient] LocalAddress: 172.31.99.228" CA="1632466253" LA="1632466762">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494892145" LogSessionId="1" SessionTraceCount="188" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.212 [EchoClient] Server:       192.168.136.135:7777" CA="1632471607" LA="1632472892">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494920330" LogSessionId="1" SessionTraceCount="189" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.212 [EchoClient] EchoBytes:    1024" CA="1632473928" LA="1632475727">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118494924729" LogSessionId="1" SessionTraceCount="190" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.225 [EchoClient] Created Socket: 468" CA="1632624264" LA="1632625559">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118495058612" LogSessionId="1" SessionTraceCount="191" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:37.227 [ROAM] Turning 2.4GHz radio in router 0 on" CA="1632628523" LA="1632628989">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118495078096" LogSessionId="1" SessionTraceCount="192" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:39.777 [ROAM] Turning 2.4GHz radio in router 1 off" CA="1658131622" LA="1658133297">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118520576799" LogSessionId="1" SessionTraceCount="193" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:42.116 [ROAM] Associated as expected with bssid 94:FF:06:31:F5:00 of router 0" CA="1681519524" LA="1681521367">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118543962006" LogSessionId="1" SessionTraceCount="194" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:42.117 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="1681562107" LA="1681563934">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118543973290" LogSessionId="1" SessionTraceCount="195" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:42.121 [EchoClient] Performing Echo" CA="1681566225" LA="1681567327">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118544015778" LogSessionId="1" SessionTraceCount="196" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:42.122 [EchoClient] Sent 1024 bytes" CA="1681574249" LA="1681575094">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118544023921" LogSessionId="1" SessionTraceCount="197" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:42.122 [EchoClient] Waiting up to 90 seconds for response" CA="1681579303" LA="1681581647">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118544027716" LogSessionId="1" SessionTraceCount="198" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:19:54.437 Sending heartbeat" CA="1804730498" LA="1804732615">
    <Data>
      <WexTraceInfo ThreadId="13676" ProcessId="10464" TimeStamp="118667176418" LogSessionId="1" SessionTraceCount="199" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:01.796 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="1878324276" LA="1878326705">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740769151" LogSessionId="1" SessionTraceCount="200" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:01.796 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="1878374163" LA="1878376587">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740778351" LogSessionId="1" SessionTraceCount="201" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:01.796 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="1878380910" LA="1878383094">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740828245" LogSessionId="1" SessionTraceCount="202" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:01.796 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="1878385505" LA="1878386435">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740834726" LogSessionId="1" SessionTraceCount="203" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:20:01.803 [ROAM] Cleaning up Echo Client" CA="1878389000" LA="1878390099">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740839440" LogSessionId="1" SessionTraceCount="204" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.804 [EchoClient] m_win32Sockets.Close(468)" CA="1878390886" LA="1878391813">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740841620" LogSessionId="1" SessionTraceCount="205" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.806 [ROAM] Connecting to echo server" CA="1878419809" LA="1878421667">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740866911" LogSessionId="1" SessionTraceCount="206" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.807 [ROAM] Restart echo services to free stale connections" CA="1878423897" LA="1878425196">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118740873224" LogSessionId="1" SessionTraceCount="207" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.889 [ROAM] Connecting echo client" CA="1879250557" LA="1879252571">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741695925" LogSessionId="1" SessionTraceCount="208" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.890 [EchoClient] LocalAddress: 172.31.99.228" CA="1879294842" LA="1879296497">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741704978" LogSessionId="1" SessionTraceCount="209" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.894 [EchoClient] Server:       192.168.136.135:7777" CA="1879300289" LA="1879301667">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741749136" LogSessionId="1" SessionTraceCount="210" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.895 [EchoClient] EchoBytes:    1024" CA="1879303988" LA="1879305389">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741753773" LogSessionId="1" SessionTraceCount="211" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.913 [EchoClient] Created Socket: 2620" CA="1879490295" LA="1879491342">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741938182" LogSessionId="1" SessionTraceCount="212" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.914 [ROAM] Cleaning up Echo Client" CA="1879493782" LA="1879494145">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741943880" LogSessionId="1" SessionTraceCount="213" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.914 [EchoClient] m_win32Sockets.Close(2620)" CA="1879495513" LA="1879496008">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741946612" LogSessionId="1" SessionTraceCount="214" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.915 [ROAM] Stopping Echo server" CA="1879507799" LA="1879508294">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118741957137" LogSessionId="1" SessionTraceCount="215" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.971 ----------------------------------------" CA="1880071538" LA="1880072703">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742519602" LogSessionId="1" SessionTraceCount="216" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.972 - Stopping Variation: Roaming SuddenLossOfSignal - 2.4GHz radio - elapsed 3:06.761" CA="1880105910" LA="1880107650">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742524890" LogSessionId="1" SessionTraceCount="217" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.976 ========================================" CA="1880110767" LA="1880111968">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742559964" LogSessionId="1" SessionTraceCount="218" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.976 ========================================" CA="1880113694" LA="1880114807">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742564003" LogSessionId="1" SessionTraceCount="219" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.976 - Starting Variation: Roaming SuddenLossOfSignal" CA="1880116032" LA="1880116606">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742566700" LogSessionId="1" SessionTraceCount="220" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.976 -     5GHz radio" CA="1880119721" LA="1880120605">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742569020" LogSessionId="1" SessionTraceCount="221" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.977 ----------------------------------------" CA="1880124374" LA="1880125608">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742573277" LogSessionId="1" SessionTraceCount="222" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.977 [ROAM] Routers and Profiles are already configured" CA="1880127987" LA="1880129011">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742577924" LogSessionId="1" SessionTraceCount="223" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.978 [ROAM] Testing Roaming with router WlanHCK-5328_5" CA="1880130294" LA="1880131654">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742581105" LogSessionId="1" SessionTraceCount="224" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.978 [ROAM] Turning routers 0 on and router 1 off" CA="1880132905" LA="1880134005">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742583238" LogSessionId="1" SessionTraceCount="225" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:01.978 [ROAM] Setting power of 5GHz radio in router 0 to 84" CA="1880136721" LA="1880137767">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118742586137" LogSessionId="1" SessionTraceCount="226" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:02.041 [ROAM] Turning 5GHz radio in router 0 on" CA="1880772980" LA="1880774721">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118743216384" LogSessionId="1" SessionTraceCount="227" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:03.054 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="1890902912" LA="1890905184">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="118753348710" LogSessionId="1" SessionTraceCount="228" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:03.578 [ROAM] Setting power of 5GHz radio in router 1 to 4" CA="1896141167" LA="1896142673">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118758586018" LogSessionId="1" SessionTraceCount="229" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:04.255 [ROAM] Turning 5GHz radio in router 1 off" CA="1902905698" LA="1902906189">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118765353803" LogSessionId="1" SessionTraceCount="230" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:05.786 [ROAM] Connecting to Echo Server using SSID WlanHCK-5328_5" CA="1918225846" LA="1918227589">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118780669484" LogSessionId="1" SessionTraceCount="231" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.009 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="1950446001" LA="1950446670">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="118812893164" LogSessionId="1" SessionTraceCount="232" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.159 [ROAM] Skipped 0 networks to find WlanHCK-5328_5 in scan list. Scanning for BSSID 94:FF:06:31:F5:10" CA="1951941831" LA="1951942429">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118814390432" LogSessionId="1" SessionTraceCount="233" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.159 [ROAM] Skipping unmatched bssid 94:FF:06:31:F5:11" CA="1951975243" LA="1951976084">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118814395228" LogSessionId="1" SessionTraceCount="234" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.407 [ROAM] BSSID-refresh changed to new value &quot;94:FF:06:31:F5:11&quot;" CA="1954423925" LA="1954425927">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118816871248" LogSessionId="1" SessionTraceCount="235" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.407 [ROAM] Found refreshed BSSID 94:FF:06:31:F5:11 in 3.619 seconds" CA="1954439641" LA="1954441574">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118816877610" LogSessionId="1" SessionTraceCount="236" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.409 [ROAM] Starting echo services" CA="1954444190" LA="1954445940">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118816893390" LogSessionId="1" SessionTraceCount="237" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.487 [ROAM] Connecting to WlanHCK-5328_5" CA="1955230944" LA="1955232757">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118817677507" LogSessionId="1" SessionTraceCount="238" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:09.492 WLAN[2] ACM Notification Disconnecting on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireDisconnecting:794)" CA="1955278046" LA="1955278646">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="118817725066" LogSessionId="1" SessionTraceCount="239" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:10.103 WLAN[1] ACM Notification Disconnected on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireDisconnected:806)" CA="1961431586" LA="1961433945">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="118823832272" LogSessionId="1" SessionTraceCount="240" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:11.437 WLAN[1] ACM Notification ConnectionComplete on interface 736c6fce-4bdf-4778-9d26-bc4f170b07c5&#xD;&#xA;      |wlanConnectionMode : wlan_connection_mode_profile&#xD;&#xA;      |SSID               : WlanHCK-5328_5&#xD;&#xA;      |dot11BssType       : dot11_BSS_type_infrastructure&#xD;&#xA;      |bSecurityEnabled   : True&#xD;&#xA;      |wlanReasonCode     : WLAN_REASON_CODE_SUCCESS&#xD;&#xA;      |dwFlags            : NONE&#xD;&#xA; (FireConnectionCompleted:857)" CA="1974774528" LA="1974775066">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="118837171651" LogSessionId="1" SessionTraceCount="241" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:11.445 [ROAM] Ensuring Adapter is connected to the local network" CA="1974831043" LA="1974832568">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118837252736" LogSessionId="1" SessionTraceCount="242" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:11.448 Querying DHCP Lease Information" CA="1974834921" LA="1974836537">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118837284066" LogSessionId="1" SessionTraceCount="243" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.414 Router address is 172.31.98.1" CA="1984681827" LA="1984683016">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118846947873" LogSessionId="1" SessionTraceCount="244" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.433 Retrieving Adapter Addresses" CA="1984762816" LA="1984768742">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118847135338" LogSessionId="1" SessionTraceCount="245" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.748 Adapter Local Address 172.31.99.228. DadState = IpDadStatePreferred" CA="1987892580" LA="1987893522">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850285076" LogSessionId="1" SessionTraceCount="246" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.754 Adapter Addresses Local 172.31.99.228 Gateway 172.31.98.1" CA="1987938307" LA="1987939037">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850346531" LogSessionId="1" SessionTraceCount="247" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.759 Retrieving Route Information" CA="1987941932" LA="1987943299">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850391617" LogSessionId="1" SessionTraceCount="248" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.759 A route to the destination exists" CA="1987950598" LA="1987952364">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850399363" LogSessionId="1" SessionTraceCount="249" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.760 Route Destination 172.31.98.0 Mask 255.255.254.0 NextHop 0.0.0.0 Index 19" CA="1987953164" LA="1987953437">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850404089" LogSessionId="1" SessionTraceCount="250" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.760 A route was found" CA="1987954762" LA="1987955057">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850405979" LogSessionId="1" SessionTraceCount="251" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.760 Resolving gateway" CA="1987956153" LA="1987956491">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850407487" LogSessionId="1" SessionTraceCount="252" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.772 Gateway address 172.31.98.1 resolved to physical address 94-FF-06-CB-1F-50" CA="1988076014" LA="1988077089">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850525154" LogSessionId="1" SessionTraceCount="253" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.772 Gateway resolved" CA="1988079250" LA="1988079561">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850529224" LogSessionId="1" SessionTraceCount="254" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.773 [ROAM] Waiting up to 20 seconds for network connectivity to 192.168.136.135:7777" CA="1988080985" LA="1988081409">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850532139" LogSessionId="1" SessionTraceCount="255" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.773 [EchoClient] LocalAddress: 172.31.99.228" CA="1988082660" LA="1988083114">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850533901" LogSessionId="1" SessionTraceCount="256" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.773 [EchoClient] Server:       192.168.136.135:7777" CA="1988084349" LA="1988084740">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850535600" LogSessionId="1" SessionTraceCount="257" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:12.773 [EchoClient] EchoBytes:    1024" CA="1988085970" LA="1988087538">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118850537234" LogSessionId="1" SessionTraceCount="258" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.786 [EchoClient] Created Socket: 2548" CA="1998221114" LA="1998222617">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118860663457" LogSessionId="1" SessionTraceCount="259" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.787 [EchoClient] m_win32Sockets.Close(2548)" CA="1998276060" LA="1998277242">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118860675764" LogSessionId="1" SessionTraceCount="260" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.793 [ROAM] Verifying network connectivity took 2.348 seconds" CA="1998284304" LA="1998285158">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118860734133" LogSessionId="1" SessionTraceCount="261" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.793 [ROAM] Making a TCP connection to Echo Services" CA="1998286905" LA="1998287900">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118860737303" LogSessionId="1" SessionTraceCount="262" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.794 [ROAM] Connecting to echo server" CA="1998290072" LA="1998290538">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118860740219" LogSessionId="1" SessionTraceCount="263" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.794 [ROAM] Restart echo services to free stale connections" CA="1998292299" LA="1998292749">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118860743146" LogSessionId="1" SessionTraceCount="264" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:13.846 [ROAM] Connecting echo client" CA="2002645148" LA="2002645616">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118861268744" LogSessionId="1" SessionTraceCount="265" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.229 [EchoClient] LocalAddress: 172.31.99.228" CA="2002650717" LA="2002651034">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118865098901" LogSessionId="1" SessionTraceCount="266" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.230 [EchoClient] Server:       192.168.136.135:7777" CA="2002661155" LA="2002661665">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118865103728" LogSessionId="1" SessionTraceCount="267" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.231 [EchoClient] EchoBytes:    1024" CA="2002664873" LA="2002665172">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118865114282" LogSessionId="1" SessionTraceCount="268" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.236 [EchoClient] Created Socket: 2684" CA="2002717731" LA="2002718175">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118865162208" LogSessionId="1" SessionTraceCount="269" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.272 [ROAM] Associated with new router 94:FF:06:31:F5:11 - pausing 500ms for connections to stabilize" CA="2003075041" LA="2003075517">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118865524299" LogSessionId="1" SessionTraceCount="270" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.786 [ROAM] Currently associated with 94:FF:06:31:F5:11 - router 0" CA="2008218149" LA="2008219320">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870665896" LogSessionId="1" SessionTraceCount="271" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.787 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="2008241158" LA="2008242040">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870671687" LogSessionId="1" SessionTraceCount="272" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.789 [EchoClient] Performing Echo" CA="2008246047" LA="2008246418">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870694759" LogSessionId="1" SessionTraceCount="273" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.793 [EchoClient] Sent 1024 bytes" CA="2008288404" LA="2008289771">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870735797" LogSessionId="1" SessionTraceCount="274" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.794 [EchoClient] Waiting up to 90 seconds for response" CA="2008292115" LA="2008292541">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870742119" LogSessionId="1" SessionTraceCount="275" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.795 [EchoClient] Received 1024-byte echo in 0.000 seconds" CA="2008303122" LA="2008303889">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870750689" LogSessionId="1" SessionTraceCount="276" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:14.795 [ROAM] Setting power of 5GHz radio in router 1 to 84" CA="2008306203" LA="2008307437">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118870756451" LogSessionId="1" SessionTraceCount="277" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:15.476 [ROAM] Turning 5GHz radio in router 1 on" CA="2015118116" LA="2015120088">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118877565795" LogSessionId="1" SessionTraceCount="278" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:17.001 [ROAM] Turning 5GHz radio in router 0 off" CA="2030367685" LA="2030368632">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118892811987" LogSessionId="1" SessionTraceCount="279" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:20.675 [ROAM] Associated as expected with bssid 94:FF:06:30:42:50 of router 1" CA="2067110867" LA="2067112078">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118929559468" LogSessionId="1" SessionTraceCount="280" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:20.676 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="2067149782" LA="2067151629">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118929563976" LogSessionId="1" SessionTraceCount="281" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:20.680 [EchoClient] Performing Echo" CA="2067154774" LA="2067156159">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118929603916" LogSessionId="1" SessionTraceCount="282" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:20.681 [EchoClient] Sent 1024 bytes" CA="2067162116" LA="2067163188">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118929611471" LogSessionId="1" SessionTraceCount="283" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:20.681 [EchoClient] Waiting up to 90 seconds for response" CA="2067165510" LA="2067166615">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="118929615450" LogSessionId="1" SessionTraceCount="284" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:24.442 Sending heartbeat" CA="2104782929" LA="2104783654">
    <Data>
      <WexTraceInfo ThreadId="13740" ProcessId="10464" TimeStamp="118967227102" LogSessionId="1" SessionTraceCount="285" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:42.765 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="2288004835" LA="2288006489">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150454075" LogSessionId="1" SessionTraceCount="286" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:42.765 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="2288329396" LA="2288331514">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150457756" LogSessionId="1" SessionTraceCount="287" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:42.765 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="2288334336" LA="2288335481">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150784336" LogSessionId="1" SessionTraceCount="288" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:20:42.765 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="2288336563" LA="2288337399">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150787242" LogSessionId="1" SessionTraceCount="289" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:20:42.799 [ROAM] Cleaning up Echo Client" CA="2288339507" LA="2288340278">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150789956" LogSessionId="1" SessionTraceCount="290" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.799 [EchoClient] m_win32Sockets.Close(2684)" CA="2288341746" LA="2288342319">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150792632" LogSessionId="1" SessionTraceCount="291" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.804 [ROAM] Connecting to echo server" CA="2288395187" LA="2288395812">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150844055" LogSessionId="1" SessionTraceCount="292" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.804 [ROAM] Restart echo services to free stale connections" CA="2288397647" LA="2288398082">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119150848158" LogSessionId="1" SessionTraceCount="293" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.864 [ROAM] Connecting echo client" CA="2289000333" LA="2289001370">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119151444893" LogSessionId="1" SessionTraceCount="294" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.865 [EchoClient] LocalAddress: 172.31.99.228" CA="2289062948" LA="2289063479">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119151454157" LogSessionId="1" SessionTraceCount="295" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.871 [EchoClient] Server:       192.168.136.135:7777" CA="2289066538" LA="2289067826">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119151516780" LogSessionId="1" SessionTraceCount="296" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.871 [EchoClient] EchoBytes:    1024" CA="2289069135" LA="2289069966">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119151519466" LogSessionId="1" SessionTraceCount="297" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.887 [EchoClient] Created Socket: 2396" CA="2289230151" LA="2289230784">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119151675654" LogSessionId="1" SessionTraceCount="298" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:42.888 [ROAM] Turning 5GHz radio in router 0 on" CA="2289237583" LA="2289238112">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119151687096" LogSessionId="1" SessionTraceCount="299" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:45.975 [ROAM] Turning 5GHz radio in router 1 off" CA="2320105267" LA="2320106952">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119182550370" LogSessionId="1" SessionTraceCount="300" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:49.624 [ROAM] Associated as expected with bssid 94:FF:06:31:F5:11 of router 0" CA="2356602135" LA="2356603705">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119219047373" LogSessionId="1" SessionTraceCount="301" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:49.625 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="2356640261" LA="2356641830">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119219056047" LogSessionId="1" SessionTraceCount="302" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:49.629 [EchoClient] Performing Echo" CA="2356644815" LA="2356646201">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119219093871" LogSessionId="1" SessionTraceCount="303" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:49.630 [EchoClient] Sent 1024 bytes" CA="2356652742" LA="2356654242">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119219102368" LogSessionId="1" SessionTraceCount="304" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:49.630 [EchoClient] Waiting up to 90 seconds for response" CA="2356655994" LA="2356657489">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119219105927" LogSessionId="1" SessionTraceCount="305" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:20:54.438 Sending heartbeat" CA="2404741310" LA="2404742107">
    <Data>
      <WexTraceInfo ThreadId="13740" ProcessId="10464" TimeStamp="119267187949" LogSessionId="1" SessionTraceCount="306" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:03.045 WLAN[2] ACM Notification ScanComplete on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireScanCompleted:927)" CA="2490829139" LA="2490830678">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="119353252088" LogSessionId="1" SessionTraceCount="307" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:09.291 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="2553267529" LA="2553269391">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415711970" LogSessionId="1" SessionTraceCount="308" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:09.291 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="2553316086" LA="2553318589">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415721494" LogSessionId="1" SessionTraceCount="309" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:09.291 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="2553320864" LA="2553322811">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415770059" LogSessionId="1" SessionTraceCount="310" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:09.291 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="2553324929" LA="2553326725">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415774131" LogSessionId="1" SessionTraceCount="311" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:21:09.297 [ROAM] Cleaning up Echo Client" CA="2553331685" LA="2553332526">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415779635" LogSessionId="1" SessionTraceCount="312" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.298 [EchoClient] m_win32Sockets.Close(2396)" CA="2553335478" LA="2553336399">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415785343" LogSessionId="1" SessionTraceCount="313" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.302 [ROAM] Connecting to echo server" CA="2553381036" LA="2553381540">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415829844" LogSessionId="1" SessionTraceCount="314" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.303 [ROAM] Restart echo services to free stale connections" CA="2553383433" LA="2553384614">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119415834385" LogSessionId="1" SessionTraceCount="315" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.393 [ROAM] Connecting echo client" CA="2554284975" LA="2554286615">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119416731118" LogSessionId="1" SessionTraceCount="316" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.393 [EchoClient] LocalAddress: 172.31.99.228" CA="2554329138" LA="2554331131">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119416738726" LogSessionId="1" SessionTraceCount="317" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.398 [EchoClient] Server:       192.168.136.135:7777" CA="2554335597" LA="2554337288">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119416783167" LogSessionId="1" SessionTraceCount="318" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.399 [EchoClient] EchoBytes:    1024" CA="2554341330" LA="2554341947">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119416790680" LogSessionId="1" SessionTraceCount="319" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.410 [EchoClient] Created Socket: 1912" CA="2554457388" LA="2554643407">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119416903706" LogSessionId="1" SessionTraceCount="320" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:09.411 [ROAM] Turning 5GHz radio in router 1 on" CA="2554633818" LA="2554647281">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119416910648" LogSessionId="1" SessionTraceCount="321" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:11.652 [ROAM] Turning 5GHz radio in router 0 off" CA="2576883464" LA="2576885164">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119439327065" LogSessionId="1" SessionTraceCount="322" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:15.358 [ROAM] Associated as expected with bssid 94:FF:06:30:42:50 of router 1" CA="2613943055" LA="2613945050">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119476388564" LogSessionId="1" SessionTraceCount="323" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:15.359 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="2613983090" LA="2613985282">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119476396964" LogSessionId="1" SessionTraceCount="324" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:15.363 [EchoClient] Performing Echo" CA="2613987842" LA="2613989200">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119476436919" LogSessionId="1" SessionTraceCount="325" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:15.364 [EchoClient] Sent 1024 bytes" CA="2613995659" LA="2613996539">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119476445458" LogSessionId="1" SessionTraceCount="326" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:15.364 [EchoClient] Waiting up to 90 seconds for response" CA="2613998118" LA="2613998875">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119476448839" LogSessionId="1" SessionTraceCount="327" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:24.433 Sending heartbeat" CA="2704697464" LA="2704698922">
    <Data>
      <WexTraceInfo ThreadId="13740" ProcessId="10464" TimeStamp="119567139628" LogSessionId="1" SessionTraceCount="328" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:35.036 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="2810716376" LA="2810718126">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673162380" LogSessionId="1" SessionTraceCount="329" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:35.036 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="2810763463" LA="2810764822">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673170402" LogSessionId="1" SessionTraceCount="330" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:35.036 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="2810765876" LA="2810766965">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673216793" LogSessionId="1" SessionTraceCount="331" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:21:35.036 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="2810767819" LA="2810768242">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673218658" LogSessionId="1" SessionTraceCount="332" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:21:35.042 [ROAM] Cleaning up Echo Client" CA="2810770579" LA="2810771593">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673221388" LogSessionId="1" SessionTraceCount="333" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.042 [EchoClient] m_win32Sockets.Close(1912)" CA="2810772244" LA="2810772654">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673223529" LogSessionId="1" SessionTraceCount="334" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.044 [ROAM] Connecting to echo server" CA="2810795502" LA="2810796014">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673245890" LogSessionId="1" SessionTraceCount="335" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.044 [ROAM] Restart echo services to free stale connections" CA="2810797061" LA="2810797760">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119673248248" LogSessionId="1" SessionTraceCount="336" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.138 [ROAM] Connecting echo client" CA="2811738110" LA="2811739495">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119674184798" LogSessionId="1" SessionTraceCount="337" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.139 [EchoClient] LocalAddress: 172.31.99.228" CA="2811780896" LA="2811781547">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119674191809" LogSessionId="1" SessionTraceCount="338" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.143 [EchoClient] Server:       192.168.136.135:7777" CA="2811785200" LA="2811786062">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119674234614" LogSessionId="1" SessionTraceCount="339" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.143 [EchoClient] EchoBytes:    1024" CA="2811787778" LA="2811788824">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119674238401" LogSessionId="1" SessionTraceCount="340" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.155 [EchoClient] Created Socket: 2388" CA="2811903990" LA="2811904794">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119674351767" LogSessionId="1" SessionTraceCount="341" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:35.155 [ROAM] Turning 5GHz radio in router 0 on" CA="2811907768" LA="2811909025">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119674357646" LogSessionId="1" SessionTraceCount="342" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:37.400 [ROAM] Turning 5GHz radio in router 1 off" CA="2834358779" LA="2834359944">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119696805021" LogSessionId="1" SessionTraceCount="343" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:42.319 [ROAM] Associated as expected with bssid 94:FF:06:31:F5:11 of router 0" CA="2883553935" LA="2883554983">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119745998969" LogSessionId="1" SessionTraceCount="344" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:42.320 [ROAM] Ensuring echo Network session is alive and functional by performing echo" CA="2883597227" LA="2883598140">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119746007610" LogSessionId="1" SessionTraceCount="345" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:42.325 [EchoClient] Performing Echo" CA="2883601385" LA="2883601958">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119746050945" LogSessionId="1" SessionTraceCount="346" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:42.330 [EchoClient] Sent 1024 bytes" CA="2883661108" LA="2883662159">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119746108971" LogSessionId="1" SessionTraceCount="347" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:42.331 [EchoClient] Waiting up to 90 seconds for response" CA="2883663792" LA="2883664507">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119746114396" LogSessionId="1" SessionTraceCount="348" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:21:54.444 Sending heartbeat" CA="3004804242" LA="3004805630">
    <Data>
      <WexTraceInfo ThreadId="8864" ProcessId="10464" TimeStamp="119867248188" LogSessionId="1" SessionTraceCount="349" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:22:01.971 [ROAM] Exchanging echos with the tcp server on AP Controller over the established connection failed. Please check logs: Exception: WSARecv error: An existing connection was forcibly closed by the remote host. (0x80072746): hr=0x80131500" CA="3080065814" LA="3080067472">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942510892" LogSessionId="1" SessionTraceCount="350" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:22:01.971 [ROAM]    at Microsoft.Test.Networking.Sockets.Win32Sockets.AsyncReceive(IntPtr socket, Int32 timeoutMs)" CA="3080113584" LA="3080115403">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942519769" LogSessionId="1" SessionTraceCount="351" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:22:01.971 [ROAM]    at Microsoft.Test.Networking.Kit.TCPEchoClient.PerformEcho()" CA="3080117804" LA="3080118427">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942567490" LogSessionId="1" SessionTraceCount="352" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Error File="" Line="-1" ErrCode="0x0" ErrType="" ErrorText="Error 0x00000000" UserText="16:22:01.971 [ROAM]    at Microsoft.Test.Networking.Kit.RoamingHelpers.PerformEcho()" CA="3080119535" LA="3080120133">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942570652" LogSessionId="1" SessionTraceCount="353" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Error>
  <Msg UserText="16:22:01.977 [ROAM] Cleaning up Echo Client" CA="3080121763" LA="3080122515">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942572713" LogSessionId="1" SessionTraceCount="354" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:01.977 [EchoClient] m_win32Sockets.Close(2388)" CA="3080123090" LA="3080123339">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942574713" LogSessionId="1" SessionTraceCount="355" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:01.979 [ROAM] Connecting to echo server" CA="3080147975" LA="3080148280">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942597610" LogSessionId="1" SessionTraceCount="356" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:01.980 [ROAM] Restart echo services to free stale connections" CA="3080149501" LA="3080150251">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119942600812" LogSessionId="1" SessionTraceCount="357" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.023 [ROAM] Connecting echo client" CA="3080589202" LA="3080590109">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943037778" LogSessionId="1" SessionTraceCount="358" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.024 [EchoClient] LocalAddress: 172.31.99.228" CA="3080622703" LA="3080624165">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943042476" LogSessionId="1" SessionTraceCount="359" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.027 [EchoClient] Server:       192.168.136.135:7777" CA="3080627842" LA="3080628748">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943077035" LogSessionId="1" SessionTraceCount="360" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.028 [EchoClient] EchoBytes:    1024" CA="3080629356" LA="3080630190">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943080628" LogSessionId="1" SessionTraceCount="361" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.037 [EchoClient] Created Socket: 2748" CA="3080725671" LA="3080726427">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943174173" LogSessionId="1" SessionTraceCount="362" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.037 [ROAM] Cleaning up Echo Client" CA="3080728402" LA="3080728651">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943178839" LogSessionId="1" SessionTraceCount="363" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.038 [EchoClient] m_win32Sockets.Close(2748)" CA="3080730339" LA="3080730761">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943181306" LogSessionId="1" SessionTraceCount="364" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.038 [ROAM] Stopping Echo server" CA="3080741339" LA="3080741679">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943187541" LogSessionId="1" SessionTraceCount="365" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.097 ----------------------------------------" CA="3081328892" LA="3081329831">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943775694" LogSessionId="1" SessionTraceCount="366" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.098 - Stopping Variation: Roaming SuddenLossOfSignal - 5GHz radio - elapsed 2:00.119" CA="3081365626" LA="3081366786">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943782213" LogSessionId="1" SessionTraceCount="367" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.101 ========================================" CA="3081368885" LA="3081370490">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943819008" LogSessionId="1" SessionTraceCount="368" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.102 [ROAM] WhckRoaming: Cleaning up" CA="3081377781" LA="3081378962">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943828332" LogSessionId="1" SessionTraceCount="369" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.103 [ROAM] WhckRoaming: Disposing RoamingHelpers" CA="3081380658" LA="3081381324">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943831528" LogSessionId="1" SessionTraceCount="370" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.103 [ROAM] Cleaning static route from AP server to router 1" CA="3081387017" LA="3081387417">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119943837508" LogSessionId="1" SessionTraceCount="371" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.242 [ROAM] WhckRoaming: Disconnecting from AP-Control server" CA="3082774588" LA="3082775381">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119945222853" LogSessionId="1" SessionTraceCount="372" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.242 Disposing AP Config Client" CA="3082812790" LA="3082813520">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119945229493" LogSessionId="1" SessionTraceCount="373" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.320 Released AP Config Services" CA="3083557161" LA="3083558454">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119946001772" LogSessionId="1" SessionTraceCount="374" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.321 Released AP config service successfully" CA="3083598262" LA="3083599784">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119946011246" LogSessionId="1" SessionTraceCount="375" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.325 Disconnecting remote command client" CA="3083608484" LA="3083610284">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119946056901" LogSessionId="1" SessionTraceCount="376" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.331 [ROAM] WhckRoaming: DeleteAllProfiles" CA="3083669477" LA="3083670216">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119946117797" LogSessionId="1" SessionTraceCount="377" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.343 WLAN[2] ACM Notification Disconnecting on 736c6fce-4bdf-4778-9d26-bc4f170b07c5 (FireDisconnecting:794)" CA="3083810092" LA="3083811206">
    <Data>
      <WexTraceInfo ThreadId="7956" ProcessId="10464" TimeStamp="119946237659" LogSessionId="1" SessionTraceCount="378" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <Msg UserText="16:22:02.985 [ROAM] WhckRoaming: Closing WLAN API" CA="3090384391" LA="3090388608">
    <Data>
      <WexTraceInfo ThreadId="1484" ProcessId="10464" TimeStamp="119952655673" LogSessionId="1" SessionTraceCount="379" />
    </Data>
    <rti id="65771941" />
    <ctx id="42145314" />
  </Msg>
  <EndTest Title="HlkTest.RoamingTests.RoamingTests.Roam_Custom" TUID="" Result="Fail" Repro="" CA="3096237435" LA="3096238988">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="119952995515" LogSessionId="1" SessionTraceCount="380" />
    </Data>
    <rti id="7294411" />
    <ctx id="42145314" />
  </EndTest>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests.Roam_Custom" CA="3096238689" LA="3096239879">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="119953071716" LogSessionId="1" SessionTraceCount="381" />
      <WexContext><![CDATA[TestScope]]></WexContext>
      <EndGroup Result="Failed" />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="HlkTest.RoamingTests.RoamingTests" CA="3096246585" LA="3096247727">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="119953079511" LogSessionId="1" SessionTraceCount="382" />
      <WexContext><![CDATA[ClassScope]]></WexContext>
      <EndGroup Result="Failed" />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <Msg UserText="C:\Data\test\bin\WlanAcceptance\Microsoft.Test.Networking.Wireless.RoamingTests.dll" CA="3096247005" LA="3096248585">
    <Data>
      <WexTraceInfo ThreadId="9124" ProcessId="12936" TimeStamp="119953080089" LogSessionId="1" SessionTraceCount="383" />
      <WexContext><![CDATA[ModuleScope]]></WexContext>
      <EndGroup Result="Failed" />
    </Data>
    <rti id="7294411" />
    <ctx id="384048256" />
  </Msg>
  <PFRollup Total="1" Passed="0" Failed="1" Blocked="0" Warned="0" Skipped="0" CA="3099237277" LA="3099237683">
    <rti id="3356986142" />
    <ctx id="384048256" />
  </PFRollup>
</WTT-Logger>