2019-07-10T23:55:31.266Z,1562802931.266 [CommandLine](IMPORTANT): got command restart logs
2019-07-10T23:56:18.343Z,1562802978.343 [CommandLine](IMPORTANT): got command run ./Missions/DUSBL_Tracking.xml
2019-07-10T23:56:18.343Z,1562802978.343 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-07-10T23:56:18.366Z,1562802978.366 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-07-10T23:56:18.369Z,1562802978.369 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-07-10T23:56:18.371Z,1562802978.371 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 8.000000 s
2019-07-10T23:56:18.373Z,1562802978.373 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-07-10T23:56:18.375Z,1562802978.375 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-07-10T23:56:18.376Z,1562802978.376 [DUSBL:A.Pitch](DEBUG): Construct.
2019-07-10T23:56:18.381Z,1562802978.381 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-07-10T23:56:18.410Z,1562802978.410 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-07-10T23:56:18.418Z,1562802978.418 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>8</Value></DefineArg>
    
    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    
        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>
        
      <Aggregate Id="RequestRepeater">

        <Sequence/>
        
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>            
          </Guidance:Wait>
      </Aggregate>

</Mission>


2019-07-10T23:56:18.428Z,1562802978.428 [CommandLine](IMPORTANT): Running ./Missions/DUSBL_Tracking.xml
2019-07-10T23:56:18.666Z,1562802978.666 [Default] Stopped
2019-07-10T23:56:18.667Z,1562802978.667 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-10T23:56:18.667Z,1562802978.667 [Default:B.GoToSurface] Stopped
2019-07-10T23:56:18.667Z,1562802978.667 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-10T23:56:18.667Z,1562802978.667 [Default:CheckIn] Stopped
2019-07-10T23:56:18.667Z,1562802978.667 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-10T23:56:18.667Z,1562802978.667 [Default:CheckIn:C.Wait] Stopped
2019-07-10T23:56:18.667Z,1562802978.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-10T23:56:18.667Z,1562802978.667 [MissionManager](IMPORTANT): Started mission DUSBL
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL] Running Loop=1
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL:A.Pitch] Running Loop=1
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL:C.] Running Loop=1
2019-07-10T23:56:18.668Z,1562802978.668 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-07-10T23:56:18.669Z,1562802978.669 [DUSBL:RequestRepeater] Running Loop=1
2019-07-10T23:56:18.669Z,1562802978.669 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-07-10T23:56:18.669Z,1562802978.669 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-07-10T23:56:18.669Z,1562802978.669 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-07-10T23:56:18.669Z,1562802978.669 [DUSBL:C.] Running Loop=1
2019-07-10T23:56:18.670Z,1562802978.670 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-10T23:56:18.670Z,1562802978.670 [DUSBL:A.Pitch] Running Loop=1
2019-07-10T23:56:20.234Z,1562802980.234 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:589.884705
2019-07-10T23:56:26.742Z,1562802986.742 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:56:27.091Z,1562802987.091 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:56:27.091Z,1562802987.091 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:56:27.092Z,1562802987.092 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:56:27.092Z,1562802987.092 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:56:27.496Z,1562802987.496 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
04C,000,0034D,184,80,80,28,FF
2019-07-10T23:56:27.496Z,1562802987.496 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:56:27.496Z,1562802987.496 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:56:27.498Z,1562802987.498 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:56:27.902Z,1562802987.902 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:56:30.732Z,1562802990.732 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,630034D,184,80,80,28,FF
2019-07-10T23:56:30.732Z,1562802990.732 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,630034D,184,80,80,28,FF
2019-07-10T23:56:30.732Z,1562802990.732 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:56:34.805Z,1562802994.805 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:56:35.175Z,1562802995.175 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:56:35.175Z,1562802995.175 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:56:35.176Z,1562802995.176 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:56:35.176Z,1562802995.176 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:56:35.603Z,1562802995.603 [DUSBL_Hydroid](INFO): USBL response received:,F4
!U1,P
0,630034D,184,80,80,28,FF
2019-07-10T23:56:35.603Z,1562802995.603 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:56:35.604Z,1562802995.604 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:56:35.605Z,1562802995.605 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:56:35.982Z,1562802995.982 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:56:39.215Z,1562802999.215 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
D,184,80,80,28,FF
2019-07-10T23:56:39.216Z,1562802999.216 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
D,184,80,80,28,FF
2019-07-10T23:56:39.216Z,1562802999.216 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:56:42.901Z,1562803002.901 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:56:43.255Z,1562803003.255 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:56:43.255Z,1562803003.255 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:56:43.256Z,1562803003.256 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:56:43.256Z,1562803003.256 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:56:43.659Z,1562803003.659 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,F4
D,184,80,80,28,FF
2019-07-10T23:56:43.660Z,1562803003.660 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:56:43.660Z,1562803003.660 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:56:43.661Z,1562803003.661 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:56:44.066Z,1562803004.066 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:56:44.464Z,1562803004.464 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,3C6,000,009D6,18A,80,80,0E,FF
2019-07-10T23:56:44.464Z,1562803004.464 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:56:44.502Z,1562803004.502 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 113.580009 m
2019-07-10T23:56:52.610Z,1562803012.610 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:56:52.951Z,1562803012.951 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:56:52.951Z,1562803012.951 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:56:52.952Z,1562803012.952 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:56:52.952Z,1562803012.952 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:56:53.355Z,1562803013.355 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
3C6,000,009D6,18A,80,80,0E,FF
2019-07-10T23:56:53.356Z,1562803013.356 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:56:53.356Z,1562803013.356 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:56:53.357Z,1562803013.357 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:56:53.761Z,1562803013.761 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:56:54.160Z,1562803014.160 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,3AB,000,009D6,189,80,80,0E,FF
2019-07-10T23:56:54.160Z,1562803014.160 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:56:54.202Z,1562803014.202 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 113.580009 m
2019-07-10T23:56:54.607Z,1562803014.607 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-07-10T23:56:54.607Z,1562803014.607 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    -5,   +31,
2019-07-10T23:57:02.282Z,1562803022.282 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:02.643Z,1562803022.643 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:02.643Z,1562803022.643 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:02.643Z,1562803022.643 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:02.644Z,1562803022.644 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:03.048Z,1562803023.048 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
3AB,000,009D6,189,80,80,0E,FF
2019-07-10T23:57:03.048Z,1562803023.048 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:03.048Z,1562803023.048 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:03.049Z,1562803023.049 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:03.453Z,1562803023.453 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:06.280Z,1562803026.280 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,0,009D6,189,80,80,0E,FF
2019-07-10T23:57:06.280Z,1562803026.280 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,0,009D6,189,80,80,0E,FF
2019-07-10T23:57:06.280Z,1562803026.280 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:10.348Z,1562803030.348 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:10.727Z,1562803030.727 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:10.728Z,1562803030.728 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:10.728Z,1562803030.728 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:10.728Z,1562803030.728 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:11.139Z,1562803031.139 [DUSBL_Hydroid](INFO): USBL response received:63,F4
!U1,P
0,009D6,189,80,80,0E,FF
2019-07-10T23:57:11.139Z,1562803031.139 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:11.139Z,1562803031.139 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:11.140Z,1562803031.140 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:11.534Z,1562803031.534 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:14.761Z,1562803034.761 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:14.762Z,1562803034.762 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:14.762Z,1562803034.762 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:18.439Z,1562803038.439 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:18.803Z,1562803038.803 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:18.803Z,1562803038.803 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:18.804Z,1562803038.804 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:18.804Z,1562803038.804 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:19.208Z,1562803039.208 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:19.208Z,1562803039.208 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:19.208Z,1562803039.208 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:19.209Z,1562803039.209 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:19.622Z,1562803039.622 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:22.440Z,1562803042.440 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:22.440Z,1562803042.440 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:22.440Z,1562803042.440 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:26.508Z,1562803046.508 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:26.883Z,1562803046.883 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:26.884Z,1562803046.884 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:26.884Z,1562803046.884 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:26.885Z,1562803046.885 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:27.288Z,1562803047.288 [DUSBL_Hydroid](INFO): USBL response received:,63,F4
!U1,P
3,F4
6,189,80,80,0E,FF
2019-07-10T23:57:27.288Z,1562803047.288 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:27.288Z,1562803047.288 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:27.289Z,1562803047.289 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:27.699Z,1562803047.699 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:30.524Z,1562803050.524 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:30.524Z,1562803050.524 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:30.524Z,1562803050.524 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:34.627Z,1562803054.627 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:34.963Z,1562803054.963 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:34.963Z,1562803054.963 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:34.964Z,1562803054.964 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:34.964Z,1562803054.964 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:35.368Z,1562803055.368 [DUSBL_Hydroid](INFO): USBL response received:,F4
!U1,P
0,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:35.368Z,1562803055.368 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:35.368Z,1562803055.368 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:35.370Z,1562803055.370 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:35.774Z,1562803055.774 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:38.611Z,1562803058.611 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:38.612Z,1562803058.612 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:38.612Z,1562803058.612 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:42.673Z,1562803062.673 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:43.047Z,1562803063.047 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:43.047Z,1562803063.047 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:43.048Z,1562803063.048 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:43.048Z,1562803063.048 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:43.451Z,1562803063.451 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:43.452Z,1562803063.452 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:43.452Z,1562803063.452 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:43.453Z,1562803063.453 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:43.854Z,1562803063.854 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:46.684Z,1562803066.684 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:46.684Z,1562803066.684 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:46.684Z,1562803066.684 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:50.773Z,1562803070.773 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:51.123Z,1562803071.123 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:51.123Z,1562803071.123 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:51.124Z,1562803071.124 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:51.124Z,1562803071.124 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:51.527Z,1562803071.527 [DUSBL_Hydroid](INFO): USBL response received:63,F4
!U1,P
63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:51.528Z,1562803071.528 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:51.528Z,1562803071.528 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:51.529Z,1562803071.529 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:57:51.933Z,1562803071.933 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:57:54.760Z,1562803074.760 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:54.760Z,1562803074.760 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:54.760Z,1562803074.760 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:57:58.842Z,1562803078.842 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:57:59.209Z,1562803079.209 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:57:59.209Z,1562803079.209 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:57:59.209Z,1562803079.209 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:57:59.209Z,1562803079.209 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:57:59.620Z,1562803079.620 [DUSBL_Hydroid](INFO): USBL response received:3,F4
!U1,P
,63,F4
6,189,80,80,0E,FF
2019-07-10T23:57:59.620Z,1562803079.620 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:57:59.620Z,1562803079.620 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:57:59.622Z,1562803079.622 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:00.018Z,1562803080.018 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:02.843Z,1562803082.843 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,81,P
,63,F4
6,189,80,80,0E,FF
2019-07-10T23:58:02.844Z,1562803082.844 [DUSBL_Hydroid](INFO): No reply:!U1,N,81,P
,63,F4
6,189,80,80,0E,FF
2019-07-10T23:58:02.844Z,1562803082.844 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:58:06.924Z,1562803086.924 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:58:07.283Z,1562803087.283 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:58:07.284Z,1562803087.284 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:58:07.284Z,1562803087.284 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:58:07.285Z,1562803087.285 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:58:07.691Z,1562803087.691 [DUSBL_Hydroid](INFO): USBL response received:0,80,63,F4
!U1,P

6,189,80,80,0E,FF
2019-07-10T23:58:07.692Z,1562803087.692 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:58:07.692Z,1562803087.692 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:58:07.693Z,1562803087.693 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:08.098Z,1562803088.098 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:10.919Z,1562803090.919 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,4
!U1,P

6,189,80,80,0E,FF
2019-07-10T23:58:10.919Z,1562803090.919 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,4
!U1,P

6,189,80,80,0E,FF
2019-07-10T23:58:10.919Z,1562803090.919 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:58:15.002Z,1562803095.002 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:58:15.363Z,1562803095.363 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:58:15.363Z,1562803095.363 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:58:15.363Z,1562803095.363 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:58:15.364Z,1562803095.364 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:58:15.767Z,1562803095.767 [DUSBL_Hydroid](INFO): USBL response received:80,63,F4
!U1,P
P

6,189,80,80,0E,FF
2019-07-10T23:58:15.768Z,1562803095.768 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:58:15.768Z,1562803095.768 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:58:15.769Z,1562803095.769 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:16.181Z,1562803096.181 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:18.000Z,1562803099.000 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,,P
P

6,189,80,80,0E,FF
2019-07-10T23:58:19.000Z,1562803099.000 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,,P
P

6,189,80,80,0E,FF
2019-07-10T23:58:19.000Z,1562803099.000 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:58:23.075Z,1562803103.075 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:58:23.447Z,1562803103.447 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:58:23.447Z,1562803103.447 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:58:23.448Z,1562803103.448 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:58:23.448Z,1562803103.448 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:58:23.852Z,1562803103.852 [DUSBL_Hydroid](INFO): USBL response received:63,F4
!U1,P
,P
P

6,189,80,80,0E,FF
2019-07-10T23:58:23.852Z,1562803103.852 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:58:23.852Z,1562803103.852 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:58:23.853Z,1562803103.853 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:24.257Z,1562803104.257 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:24.659Z,1562803104.659 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,072,000,00324,187,80,80,29,FF
2019-07-10T23:58:24.660Z,1562803104.660 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:58:24.707Z,1562803104.707 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 10.739998 m
2019-07-10T23:58:32.783Z,1562803112.783 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:58:33.155Z,1562803113.155 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:58:33.155Z,1562803113.155 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:58:33.156Z,1562803113.156 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:58:33.156Z,1562803113.156 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:58:33.548Z,1562803113.548 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
072,000,00324,187,80,80,29,FF
2019-07-10T23:58:33.548Z,1562803113.548 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:58:33.548Z,1562803113.548 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:58:33.549Z,1562803113.549 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:33.616Z,1562803113.616 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-10T23:58:33.616Z,1562803113.616 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,1907101.1,   0.0,1448.9,  0
2019-07-10T23:58:33.965Z,1562803113.965 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:36.780Z,1562803116.780 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,00324,187,80,80,29,FF
2019-07-10T23:58:36.780Z,1562803116.780 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,00324,187,80,80,29,FF
2019-07-10T23:58:36.780Z,1562803116.780 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:58:40.854Z,1562803120.854 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:58:41.219Z,1562803121.219 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:58:41.219Z,1562803121.219 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:58:41.220Z,1562803121.220 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:58:41.220Z,1562803121.220 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:58:41.627Z,1562803121.627 [DUSBL_Hydroid](INFO): USBL response received:,63,F4
!U1,P
,00324,187,80,80,29,FF
2019-07-10T23:58:41.628Z,1562803121.628 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:58:41.628Z,1562803121.628 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:58:41.629Z,1562803121.629 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:42.030Z,1562803122.030 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:42.435Z,1562803122.435 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1A9,000,004DB,186,80,80,11,FF
2019-07-10T23:58:42.436Z,1562803122.436 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:58:42.473Z,1562803122.473 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 37.080002 m
2019-07-10T23:58:50.541Z,1562803130.541 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:58:50.919Z,1562803130.919 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:58:50.919Z,1562803130.919 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:58:50.920Z,1562803130.920 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:58:50.920Z,1562803130.920 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:58:51.323Z,1562803131.323 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
1A9,000,004DB,186,80,80,11,FF
2019-07-10T23:58:51.324Z,1562803131.324 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:58:51.324Z,1562803131.324 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:58:51.325Z,1562803131.325 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:58:51.726Z,1562803131.726 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:58:52.132Z,1562803132.132 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,074,000,00325,186,80,80,28,FF
2019-07-10T23:58:52.132Z,1562803132.132 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:58:52.169Z,1562803132.169 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 10.800001 m
2019-07-10T23:59:00.238Z,1562803140.238 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:59:00.615Z,1562803140.615 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:59:00.615Z,1562803140.615 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:59:00.616Z,1562803140.616 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:59:00.616Z,1562803140.616 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:59:01.019Z,1562803141.019 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
074,000,00325,186,80,80,28,FF
2019-07-10T23:59:01.020Z,1562803141.020 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:59:01.020Z,1562803141.020 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:59:01.021Z,1562803141.021 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:59:01.422Z,1562803141.422 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:59:04.248Z,1562803144.248 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,00325,186,80,80,28,FF
2019-07-10T23:59:04.248Z,1562803144.248 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,00325,186,80,80,28,FF
2019-07-10T23:59:04.248Z,1562803144.248 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:59:08.320Z,1562803148.320 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:59:08.691Z,1562803148.691 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:59:08.692Z,1562803148.692 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:59:08.692Z,1562803148.692 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:59:08.693Z,1562803148.693 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:59:09.115Z,1562803149.115 [DUSBL_Hydroid](INFO): USBL response received:0,63,F4
!U1,P
00325,186,80,80,28,FF
2019-07-10T23:59:09.115Z,1562803149.115 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:59:09.115Z,1562803149.115 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:59:09.116Z,1562803149.116 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:59:09.502Z,1562803149.502 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:59:12.735Z,1562803152.736 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
5,186,80,80,28,FF
2019-07-10T23:59:12.736Z,1562803152.736 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
5,186,80,80,28,FF
2019-07-10T23:59:12.736Z,1562803152.736 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:59:16.404Z,1562803156.404 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:59:16.771Z,1562803156.771 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:59:16.771Z,1562803156.771 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:59:16.772Z,1562803156.772 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:59:16.772Z,1562803156.772 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:59:17.180Z,1562803157.180 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,F4
5,186,80,80,28,FF
2019-07-10T23:59:17.180Z,1562803157.180 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:59:17.180Z,1562803157.180 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:59:17.181Z,1562803157.181 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:59:17.604Z,1562803157.604 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:59:20.407Z,1562803160.407 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
5,186,80,80,28,FF
2019-07-10T23:59:20.407Z,1562803160.407 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
5,186,80,80,28,FF
2019-07-10T23:59:20.407Z,1562803160.407 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:59:24.474Z,1562803164.474 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:59:24.851Z,1562803164.851 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:59:24.851Z,1562803164.851 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:59:24.852Z,1562803164.852 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:59:24.852Z,1562803164.852 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:59:25.256Z,1562803165.256 [DUSBL_Hydroid](INFO): USBL response received:0,63,F4
!U1,P
,F4
5,186,80,80,28,FF
2019-07-10T23:59:25.256Z,1562803165.256 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:59:25.256Z,1562803165.256 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:59:25.257Z,1562803165.257 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:59:25.662Z,1562803165.662 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:59:28.492Z,1562803168.492 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6
,F4
5,186,80,80,28,FF
2019-07-10T23:59:28.492Z,1562803168.492 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6
,F4
5,186,80,80,28,FF
2019-07-10T23:59:28.492Z,1562803168.492 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:59:32.594Z,1562803172.594 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:59:32.935Z,1562803172.935 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:59:32.935Z,1562803172.935 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:59:32.936Z,1562803172.936 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:59:32.936Z,1562803172.936 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:59:33.336Z,1562803173.336 [DUSBL_Hydroid](INFO): USBL response received:3,F4
!U1,P
,6
,F4
5,186,80,80,28,FF
2019-07-10T23:59:33.336Z,1562803173.336 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:59:33.336Z,1562803173.336 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:59:33.337Z,1562803173.337 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:59:33.745Z,1562803173.745 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:59:34.141Z,1562803174.141 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,3F6,000,009D8,18A,80,80,0B,FF
2019-07-10T23:59:34.142Z,1562803174.142 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:59:34.179Z,1562803174.179 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 113.700005 m
2019-07-10T23:59:42.257Z,1562803182.257 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:59:42.631Z,1562803182.631 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:59:42.631Z,1562803182.631 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:59:42.632Z,1562803182.632 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:59:42.632Z,1562803182.632 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:59:43.047Z,1562803183.047 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
3F6,000,009D8,18A,80,80,0B,FF
2019-07-10T23:59:43.048Z,1562803183.048 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:59:43.048Z,1562803183.048 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:59:43.049Z,1562803183.049 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:59:43.441Z,1562803183.441 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:59:46.671Z,1562803186.671 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
8,18A,80,80,0B,FF
2019-07-10T23:59:46.671Z,1562803186.671 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
8,18A,80,80,0B,FF
2019-07-10T23:59:46.671Z,1562803186.671 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:59:47.615Z,1562803187.615 [CommandLine](IMPORTANT): got command stop
2019-07-10T23:59:47.615Z,1562803187.615 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-10T23:59:47.918Z,1562803187.918 [MissionManager](INFO): MissionManager is completed.
2019-07-10T23:59:47.918Z,1562803187.918 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL] Stopped
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:A.Pitch] Stopped
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:B.SetSpeed] Stopped
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:C.] Stopped
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:RequestRepeater] Stopped
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-07-10T23:59:47.919Z,1562803187.919 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-10T23:59:48.313Z,1562803188.313 [MissionManager](IMPORTANT): Started mission Default
2019-07-10T23:59:48.313Z,1562803188.313 [Default] Running Loop=1
2019-07-10T23:59:48.313Z,1562803188.313 [Default](DEBUG): Aggregate::initialize Default
2019-07-10T23:59:48.313Z,1562803188.313 [Default:B.GoToSurface] Running Loop=1
2019-07-10T23:59:48.314Z,1562803188.314 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-10T23:59:48.314Z,1562803188.314 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-10T23:59:48.315Z,1562803188.315 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-10T23:59:48.315Z,1562803188.315 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-10T23:59:48.315Z,1562803188.315 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-10T23:59:48.316Z,1562803188.316 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-10T23:59:48.316Z,1562803188.316 [Default:A.Wait] Running Loop=1
2019-07-10T23:59:48.316Z,1562803188.316 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-11T00:00:01.653Z,1562803201.653 [Default:A.Wait](INFO): Done Waiting.
2019-07-11T00:00:01.654Z,1562803201.654 [Default:A.Wait] Stopped
2019-07-11T00:00:01.654Z,1562803201.654 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T00:00:02.053Z,1562803202.053 [Default:CheckIn] Running Loop=1
2019-07-11T00:00:02.053Z,1562803202.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-11T00:00:02.053Z,1562803202.053 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-11T00:00:02.832Z,1562803202.832 [NAL9602](INFO): Powering up
2019-07-11T00:00:13.740Z,1562803213.740 [NAL9602](INFO): NAL9602 initialized
2019-07-11T00:00:14.549Z,1562803214.549 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-11T00:00:14.549Z,1562803214.549 [NAL9602] Data Fault, FailCount= 2
2019-07-11T00:00:14.549Z,1562803214.549 [NAL9602](ERROR): Data Fault
2019-07-11T00:00:14.607Z,1562803214.607 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-11T00:00:14.952Z,1562803214.952 [NAL9602](INFO): Powering down
2019-07-11T00:00:15.803Z,1562803215.803 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-11T00:00:15.803Z,1562803215.803 [NAL9602] No Fault, FailCount= 2
2019-07-11T00:00:45.252Z,1562803245.252 [NAL9602](INFO): Powering up NAL9602
2019-07-11T00:00:56.154Z,1562803256.154 [NAL9602](INFO): NAL9602 initialized
2019-07-11T00:00:56.974Z,1562803256.974 [NAL9602](DEBUG): Fix Requested
2019-07-11T00:01:15.609Z,1562803275.609 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-07-11T00:01:15.609Z,1562803275.609 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    -2,   +17,
2019-07-11T00:02:53.774Z,1562803373.774 [RDI_Pathfinder](ERROR): Failed to parse:
   -2.91,       +32.24,       -3.03,   9.38,  0.00
2019-07-11T00:03:15.996Z,1562803395.996 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-07-11T00:03:15.996Z,1562803395.996 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    -4,   +2,A
2019-07-11T00:03:36.964Z,1562803416.964 [CommandLine](IMPORTANT): got command restart application
2019-07-11T00:03:37.970Z,1562803417.970 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:37.971Z,1562803417.971 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:37.991Z,1562803417.991 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-07-11T00:03:37.991Z,1562803417.991 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:37.991Z,1562803417.991 [CommandLine](INFO): Join timeout helper Thread ID is 1162
2019-07-11T00:03:37.997Z,1562803417.997 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-07-11T00:03:37.997Z,1562803417.997 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:37.997Z,1562803417.997 [NavChartDb](INFO): Join timeout helper Thread ID is 1163
2019-07-11T00:03:37.998Z,1562803417.998 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:37.998Z,1562803417.998 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:37.999Z,1562803417.999 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-07-11T00:03:37.999Z,1562803417.999 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:37.999Z,1562803417.999 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1164
2019-07-11T00:03:38.394Z,1562803418.394 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:38.395Z,1562803418.395 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-07-11T00:03:38.395Z,1562803418.395 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:38.414Z,1562803418.414 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-07-11T00:03:38.414Z,1562803418.414 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:38.415Z,1562803418.415 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1165
2019-07-11T00:03:38.423Z,1562803418.423 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:38.423Z,1562803418.423 [CTD_NeilBrown](INFO): Powering down
2019-07-11T00:03:38.434Z,1562803418.434 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:38.435Z,1562803418.435 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-07-11T00:03:38.435Z,1562803418.435 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:38.435Z,1562803418.435 [Radio_Surface](INFO): Join timeout helper Thread ID is 1166
2019-07-11T00:03:38.758Z,1562803418.758 [Radio_Surface](INFO): Powering down
2019-07-11T00:03:38.759Z,1562803418.759 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:38.760Z,1562803418.760 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:38.771Z,1562803418.771 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-07-11T00:03:38.771Z,1562803418.771 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:38.772Z,1562803418.772 [DataOverHttps](INFO): Join timeout helper Thread ID is 1167
2019-07-11T00:03:39.023Z,1562803419.023 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:39.023Z,1562803419.023 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.039Z,1562803419.039 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-07-11T00:03:39.039Z,1562803419.039 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.040Z,1562803419.040 [logger](INFO): Join timeout helper Thread ID is 1168
2019-07-11T00:03:39.062Z,1562803419.062 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:39.062Z,1562803419.062 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.067Z,1562803419.067 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-07-11T00:03:39.067Z,1562803419.067 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.067Z,1562803419.067 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-07-11T00:03:39.067Z,1562803419.067 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.068Z,1562803419.068 [controlThread](INFO): Join timeout helper Thread ID is 1169
2019-07-11T00:03:39.366Z,1562803419.366 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T00:03:39.367Z,1562803419.367 [controlThread](DEBUG): Uninitializing ControlThread
2019-07-11T00:03:39.367Z,1562803419.367 [AHRS_M2](INFO): Powering down
2019-07-11T00:03:39.439Z,1562803419.439 [DUSBL_Hydroid](INFO): Powering down
2019-07-11T00:03:39.511Z,1562803419.511 [Micromodem](INFO): Powering down
2019-07-11T00:03:39.607Z,1562803419.607 [NAL9602](INFO): Powering down
2019-07-11T00:03:39.678Z,1562803419.678 [RDI_Pathfinder](INFO): Powering down
2019-07-11T00:03:39.680Z,1562803419.680 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-07-11T00:03:39.681Z,1562803419.681 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-07-11T00:03:39.681Z,1562803419.681 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-07-11T00:03:39.681Z,1562803419.681 [MissionManager](INFO): Uninitializing Mission Default
2019-07-11T00:03:39.682Z,1562803419.682 [Default] Stopped
2019-07-11T00:03:39.682Z,1562803419.682 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-11T00:03:39.682Z,1562803419.682 [Default:B.GoToSurface] Stopped
2019-07-11T00:03:39.682Z,1562803419.682 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-11T00:03:39.682Z,1562803419.682 [Default:CheckIn] Stopped
2019-07-11T00:03:39.682Z,1562803419.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-11T00:03:39.682Z,1562803419.682 [Default:CheckIn:Read_GPS] Stopped
2019-07-11T00:03:39.684Z,1562803419.684 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-07-11T00:03:39.685Z,1562803419.685 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-07-11T00:03:39.685Z,1562803419.685 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-07-11T00:03:39.685Z,1562803419.685 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-07-11T00:03:39.686Z,1562803419.686 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-11T00:03:39.686Z,1562803419.686 [BuoyancyServo](INFO): Powering down
2019-07-11T00:03:39.698Z,1562803419.698 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-07-11T00:03:39.699Z,1562803419.699 [ElevatorServo](INFO): Powering down
2019-07-11T00:03:39.699Z,1562803419.699 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-07-11T00:03:39.699Z,1562803419.699 [MassServo](INFO): Powering down
2019-07-11T00:03:39.700Z,1562803419.700 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-11T00:03:39.700Z,1562803419.700 [RudderServo](INFO): Powering down
2019-07-11T00:03:39.701Z,1562803419.701 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-07-11T00:03:39.701Z,1562803419.701 [ThrusterServo](INFO): Powering down
2019-07-11T00:03:39.702Z,1562803419.702 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-07-11T00:03:39.702Z,1562803419.702 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-07-11T00:03:39.703Z,1562803419.703 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-07-11T00:03:39.703Z,1562803419.703 [CBIT](DEBUG): Powering off loads.
2019-07-11T00:03:39.714Z,1562803419.714 [CBIT](DEBUG): Disabling WDT.
2019-07-11T00:03:39.726Z,1562803419.726 [CBIT](DEBUG): Opening all GF detection circuits.
2019-07-11T00:03:39.727Z,1562803419.727 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.784Z,1562803419.784 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.793Z,1562803419.793 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.837Z,1562803419.837 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.839Z,1562803419.839 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.896Z,1562803419.896 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-11T00:03:39.965Z,1562803419.965 [logger ThreadHandler](INFO): Thread cancelled.