2019-08-14T04:40:05.082Z,1565757605.082 [CommandLine](IMPORTANT): got command restart logs
2019-08-14T04:41:07.274Z,1565757667.274 [CommandLine](IMPORTANT): got command load ./Missions/DUSBL_Tracking.xml
2019-08-14T04:41:07.275Z,1565757667.275 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-08-14T04:41:07.298Z,1565757667.298 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-08-14T04:41:07.301Z,1565757667.301 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-08-14T04:41:07.303Z,1565757667.303 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-08-14T04:41:07.305Z,1565757667.305 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-08-14T04:41:07.307Z,1565757667.307 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-08-14T04:41:07.308Z,1565757667.308 [DUSBL:A.Pitch](DEBUG): Construct.
2019-08-14T04:41:07.312Z,1565757667.312 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-08-14T04:41:07.365Z,1565757667.365 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-08-14T04:41:07.368Z,1565757667.368 [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>4</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-08-14T04:41:07.369Z,1565757667.369 [CommandLine](IMPORTANT): Loaded ./Missions/DUSBL_Tracking.xml
2019-08-14T04:41:15.990Z,1565757675.990 [CommandLine](IMPORTANT): got command run
2019-08-14T04:41:15.992Z,1565757675.992 [CommandLine](IMPORTANT): Running 
2019-08-14T04:41:16.319Z,1565757676.319 [Default] Stopped
2019-08-14T04:41:16.320Z,1565757676.320 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-14T04:41:16.320Z,1565757676.320 [Default:B.GoToSurface] Stopped
2019-08-14T04:41:16.320Z,1565757676.320 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-14T04:41:16.320Z,1565757676.320 [Default:CheckIn] Stopped
2019-08-14T04:41:16.320Z,1565757676.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-14T04:41:16.320Z,1565757676.320 [Default:CheckIn:C.Wait] Stopped
2019-08-14T04:41:16.320Z,1565757676.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-14T04:41:16.320Z,1565757676.320 [MissionManager](IMPORTANT): Started mission DUSBL
2019-08-14T04:41:16.321Z,1565757676.321 [DUSBL] Running Loop=1
2019-08-14T04:41:16.321Z,1565757676.321 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:A.Pitch] Running Loop=1
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:B.SetSpeed] Running Loop=1
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:C.] Running Loop=1
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-08-14T04:41:16.322Z,1565757676.322 [DUSBL:RequestRepeater] Running Loop=1
2019-08-14T04:41:16.323Z,1565757676.323 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-08-14T04:41:16.323Z,1565757676.323 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-08-14T04:41:16.323Z,1565757676.323 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-08-14T04:41:16.323Z,1565757676.323 [DUSBL:C.] Running Loop=1
2019-08-14T04:41:16.323Z,1565757676.323 [DUSBL:B.SetSpeed] Running Loop=1
2019-08-14T04:41:16.324Z,1565757676.324 [DUSBL:A.Pitch] Running Loop=1
2019-08-14T04:41:20.355Z,1565757680.355 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:20.730Z,1565757680.730 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-14T04:41:20.730Z,1565757680.730 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-14T04:41:20.730Z,1565757680.730 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 20
2019-08-14T04:41:20.731Z,1565757680.731 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-14T04:41:21.134Z,1565757681.134 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,63,E4
0,187,80,80,12,FF
2019-08-14T04:41:21.134Z,1565757681.134 [DUSBL_Hydroid](INFO): Command Ack
2019-08-14T04:41:21.134Z,1565757681.134 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-14T04:41:21.136Z,1565757681.136 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-08-14T04:41:21.562Z,1565757681.562 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-08-14T04:41:22.355Z,1565757682.355 [Micromodem](INFO): Nmea in: $SNTTA,,,,,044122.39*55

2019-08-14T04:41:24.399Z,1565757684.399 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:24.774Z,1565757684.774 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,63,E4
0,187,80,80,12,FF
2019-08-14T04:41:24.774Z,1565757684.774 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
0,187,80,80,12,FF
2019-08-14T04:41:24.774Z,1565757684.774 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-08-14T04:41:28.430Z,1565757688.430 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:28.814Z,1565757688.814 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-14T04:41:28.814Z,1565757688.814 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-14T04:41:28.814Z,1565757688.814 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 20
2019-08-14T04:41:28.815Z,1565757688.815 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-14T04:41:29.218Z,1565757689.218 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,63,E4
0,187,80,80,12,FF
2019-08-14T04:41:29.218Z,1565757689.218 [DUSBL_Hydroid](INFO): Command Ack
2019-08-14T04:41:29.218Z,1565757689.218 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-14T04:41:29.220Z,1565757689.220 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-08-14T04:41:29.621Z,1565757689.621 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-08-14T04:41:30.441Z,1565757690.441 [Micromodem](INFO): Nmea in: $SNTTA,,,,,044130.48*50

2019-08-14T04:41:30.830Z,1565757690.830 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,01F,000,03084,184,80,80,68,FF
2019-08-14T04:41:30.830Z,1565757690.830 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-08-14T04:41:30.911Z,1565757690.911 [Reporter](INFO): acoustic_contact_range 707.700012 m
2019-08-14T04:41:34.909Z,1565757694.909 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:35.274Z,1565757695.274 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-14T04:41:35.274Z,1565757695.274 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-14T04:41:35.274Z,1565757695.274 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 20
2019-08-14T04:41:35.275Z,1565757695.275 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-14T04:41:35.678Z,1565757695.678 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
01F,000,03084,184,80,80,68,FF
2019-08-14T04:41:35.678Z,1565757695.678 [DUSBL_Hydroid](INFO): Command Ack
2019-08-14T04:41:35.678Z,1565757695.678 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-14T04:41:35.680Z,1565757695.680 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-08-14T04:41:36.085Z,1565757696.085 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-08-14T04:41:36.904Z,1565757696.904 [Micromodem](INFO): Nmea in: $SNTTA,,,,,044136.94*57

2019-08-14T04:41:36.924Z,1565757696.924 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-14T04:41:36.924Z,1565757696.924 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19081321483471,35.0, -0.1,  8.9,  0
2019-08-14T04:41:38.948Z,1565757698.948 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:39.314Z,1565757699.314 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:39.314Z,1565757699.314 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:39.314Z,1565757699.314 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-08-14T04:41:42.996Z,1565757702.996 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:43.362Z,1565757703.362 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-14T04:41:43.362Z,1565757703.362 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-14T04:41:43.362Z,1565757703.362 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 20
2019-08-14T04:41:43.363Z,1565757703.363 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-14T04:41:43.769Z,1565757703.769 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:43.769Z,1565757703.769 [DUSBL_Hydroid](INFO): Command Ack
2019-08-14T04:41:43.769Z,1565757703.769 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-14T04:41:43.771Z,1565757703.771 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-08-14T04:41:44.169Z,1565757704.169 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-08-14T04:41:44.983Z,1565757704.983 [Micromodem](INFO): Nmea in: $SNTTA,,,,,044145.03*5D

2019-08-14T04:41:47.026Z,1565757707.026 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:47.398Z,1565757707.398 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:47.398Z,1565757707.398 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:47.399Z,1565757707.399 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-08-14T04:41:51.060Z,1565757711.060 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:51.438Z,1565757711.438 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-14T04:41:51.438Z,1565757711.438 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-14T04:41:51.438Z,1565757711.438 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 20
2019-08-14T04:41:51.439Z,1565757711.439 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-14T04:41:51.842Z,1565757711.842 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:51.842Z,1565757711.842 [DUSBL_Hydroid](INFO): Command Ack
2019-08-14T04:41:51.842Z,1565757711.842 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-14T04:41:51.844Z,1565757711.844 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-08-14T04:41:52.249Z,1565757712.249 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-08-14T04:41:53.059Z,1565757713.059 [Micromodem](INFO): Nmea in: $SNTTA,,,,,044153.10*58

2019-08-14T04:41:55.091Z,1565757715.091 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:55.478Z,1565757715.478 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,N,80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:55.478Z,1565757715.478 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:55.478Z,1565757715.478 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-08-14T04:41:59.138Z,1565757719.138 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:41:59.542Z,1565757719.542 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-14T04:41:59.542Z,1565757719.542 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-14T04:41:59.543Z,1565757719.543 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 20
2019-08-14T04:41:59.544Z,1565757719.544 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-14T04:41:59.918Z,1565757719.918 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,P
80,80,63,E4
4,184,80,80,68,FF
2019-08-14T04:41:59.918Z,1565757719.918 [DUSBL_Hydroid](INFO): Command Ack
2019-08-14T04:41:59.918Z,1565757719.918 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-14T04:41:59.920Z,1565757719.920 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-08-14T04:42:00.325Z,1565757720.325 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-08-14T04:42:01.132Z,1565757721.132 [Micromodem](INFO): Nmea in: $SNTTA,,,,,044201.18*54

2019-08-14T04:42:03.190Z,1565757723.190 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-08-14T04:42:03.570Z,1565757723.570 [DUSBL_Hydroid](INFO): DUSBL response received:!U1,R,028,000,08FD9,185,80,80,72,FF
2019-08-14T04:42:03.570Z,1565757723.570 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-08-14T04:42:03.649Z,1565757723.649 [Reporter](INFO): acoustic_contact_range 2172.000000 m
2019-08-14T04:42:05.173Z,1565757725.173 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-08-14T04:42:05.174Z,1565757725.174 [DropWeight] Hardware Fault, FailCount= 1
2019-08-14T04:42:05.174Z,1565757725.174 [DropWeight](ERROR): Hardware Fault
2019-08-14T04:42:05.222Z,1565757725.222 [CommandLine](FAULT): Scheduling is paused
2019-08-14T04:42:05.222Z,1565757725.222 [CBIT](INFO): Critical error at 20190814T044205
2019-08-14T04:42:05.223Z,1565757725.223 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-08-14T04:42:05.225Z,1565757725.225 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-08-14T04:42:05.226Z,1565757725.226 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-08-14T04:42:05.624Z,1565757725.624 [MissionManager](INFO): MissionManager is completed.
2019-08-14T04:42:05.624Z,1565757725.624 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-08-14T04:42:05.624Z,1565757725.624 [DUSBL] Stopped
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:A.Pitch] Stopped
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:B.SetSpeed] Stopped
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:C.] Stopped
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:RequestRepeater] Stopped
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-08-14T04:42:05.625Z,1565757725.625 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-14T04:42:05.635Z,1565757725.635 [CBIT](INFO): Critical error at 20190814T044205
2019-08-14T04:42:06.008Z,1565757726.008 [MissionManager](IMPORTANT): Started mission Default
2019-08-14T04:42:06.008Z,1565757726.008 [Default] Running Loop=1
2019-08-14T04:42:06.009Z,1565757726.009 [Default](DEBUG): Aggregate::initialize Default
2019-08-14T04:42:06.009Z,1565757726.009 [Default:B.GoToSurface] Running Loop=1
2019-08-14T04:42:06.009Z,1565757726.009 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-14T04:42:06.009Z,1565757726.009 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-14T04:42:06.009Z,1565757726.009 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-14T04:42:06.010Z,1565757726.010 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-14T04:42:06.010Z,1565757726.010 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-14T04:42:06.010Z,1565757726.010 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-14T04:42:06.011Z,1565757726.011 [Default:A.Wait] Running Loop=1
2019-08-14T04:42:06.011Z,1565757726.011 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-14T04:42:18.940Z,1565757738.940 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-14T04:42:18.940Z,1565757738.940 [RDI_Pathfinder](ERROR): Failed to parse:
:TS1491671,35.0, -0.1,   0.0,1448.9,  0
2019-08-14T04:42:19.344Z,1565757739.344 [Default:A.Wait](INFO): Done Waiting.
2019-08-14T04:42:19.344Z,1565757739.344 [Default:A.Wait] Stopped
2019-08-14T04:42:19.344Z,1565757739.344 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-14T04:42:19.759Z,1565757739.759 [Default:CheckIn] Running Loop=1
2019-08-14T04:42:19.760Z,1565757739.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-14T04:42:19.760Z,1565757739.760 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-14T04:42:21.345Z,1565757741.345 [NAL9602](DEBUG): Fix Requested
2019-08-14T04:42:21.740Z,1565757741.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,043841.00,A,3648.16856,N,12147.28336,W,0.583,175.69,140819,,,A*79
2019-08-14T04:42:21.742Z,1565757741.742 [NAL9602](INFO): GPS fix at 20190814T043841: (36.802809, -121.788056)
2019-08-14T04:42:21.776Z,1565757741.776 [Default:CheckIn:Read_GPS] Stopped
2019-08-14T04:42:21.776Z,1565757741.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-14T04:42:23.822Z,1565757743.822 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-08-14T04:42:27.297Z,1565757747.297 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20190814T044005/Courier0000.lzma
2019-08-14T04:42:28.103Z,1565757748.103 [DataOverHttps](INFO): Moved sent file to Logs/20190814T044005/Courier0000.lzma.bak
2019-08-14T04:42:28.104Z,1565757748.104 [DataOverHttps](INFO): SBD MOMSN=11568564
2019-08-14T04:42:40.508Z,1565757760.508 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190814T042527/Express0007.lzma
2019-08-14T04:42:41.314Z,1565757761.314 [DataOverHttps](INFO): Moved sent file to Logs/20190814T042527/Express0007.lzma.bak
2019-08-14T04:42:41.314Z,1565757761.314 [DataOverHttps](INFO): SBD MOMSN=11568570
2019-08-14T04:42:52.487Z,1565757772.487 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-08-14T04:42:52.569Z,1565757772.569 [NAL9602](FAULT): received: K
CSQ:0

OK92, 2, 0, 0, 0

OK
2019-08-14T04:42:52.569Z,1565757772.569 [NAL9602] Data Fault, FailCount= 1
2019-08-14T04:42:52.569Z,1565757772.569 [NAL9602](ERROR): Data Fault
2019-08-14T04:42:52.658Z,1565757772.658 [DataOverHttps](INFO): Sending 464 bytes from file Logs/20190814T044005/Express0001.lzma
2019-08-14T04:42:52.688Z,1565757772.688 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-14T04:42:52.895Z,1565757772.895 [NAL9602](INFO): Powering down
2019-08-14T04:42:53.246Z,1565757773.246 [DataOverHttps](INFO): Moved sent file to Logs/20190814T044005/Express0001.lzma.bak
2019-08-14T04:42:53.246Z,1565757773.246 [DataOverHttps](INFO): SBD MOMSN=11568572
2019-08-14T04:42:53.784Z,1565757773.784 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-14T04:42:53.784Z,1565757773.784 [NAL9602] No Fault, FailCount= 1
2019-08-14T04:42:54.142Z,1565757774.142 [Default:CheckIn:Read_Iridium] Stopped
2019-08-14T04:42:54.143Z,1565757774.143 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-14T04:42:54.143Z,1565757774.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-14T04:43:23.191Z,1565757803.191 [NAL9602](INFO): Powering up NAL9602
2019-08-14T04:43:34.099Z,1565757814.099 [NAL9602](INFO): NAL9602 initialized
2019-08-14T04:43:39.166Z,1565757819.166 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 18.000000 count persist