2019-07-11T18:43:08.572Z,1562870588.572 [CommandLine](IMPORTANT): got command restart logs
2019-07-11T18:43:11.744Z,1562870591.744 [NAL9602](INFO): NAL9602 initialized
2019-07-11T18:43:12.553Z,1562870592.553 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-11T18:43:12.553Z,1562870592.553 [NAL9602] Data Fault, FailCount= 1
2019-07-11T18:43:12.553Z,1562870592.553 [NAL9602](ERROR): Data Fault
2019-07-11T18:43:12.590Z,1562870592.590 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-11T18:43:12.956Z,1562870592.956 [NAL9602](INFO): Powering down
2019-07-11T18:43:13.815Z,1562870593.815 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-11T18:43:13.815Z,1562870593.815 [NAL9602] No Fault, FailCount= 1
2019-07-11T18:43:25.008Z,1562870605.008 [CommandLine](IMPORTANT): got command run ./Missions/DUSBL_Tracking.xml
2019-07-11T18:43:25.009Z,1562870605.009 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-07-11T18:43:25.093Z,1562870605.093 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 5.000000 min
2019-07-11T18:43:25.115Z,1562870605.115 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-07-11T18:43:25.118Z,1562870605.118 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 8.000000 s
2019-07-11T18:43:25.156Z,1562870605.156 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-07-11T18:43:25.175Z,1562870605.175 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-07-11T18:43:25.176Z,1562870605.176 [DUSBL:A.Pitch](DEBUG): Construct.
2019-07-11T18:43:25.188Z,1562870605.188 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-07-11T18:43:25.243Z,1562870605.243 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-07-11T18:43:25.255Z,1562870605.255 [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>5</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-11T18:43:25.257Z,1562870605.257 [CommandLine](IMPORTANT): Running ./Missions/DUSBL_Tracking.xml
2019-07-11T18:43:25.505Z,1562870605.505 [Default] Stopped
2019-07-11T18:43:25.505Z,1562870605.505 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-11T18:43:25.505Z,1562870605.505 [Default:B.GoToSurface] Stopped
2019-07-11T18:43:25.505Z,1562870605.505 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-11T18:43:25.505Z,1562870605.505 [Default:CheckIn] Stopped
2019-07-11T18:43:25.506Z,1562870605.506 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-11T18:43:25.506Z,1562870605.506 [Default:CheckIn:Read_GPS] Stopped
2019-07-11T18:43:25.506Z,1562870605.506 [MissionManager](IMPORTANT): Started mission DUSBL
2019-07-11T18:43:25.506Z,1562870605.506 [DUSBL] Running Loop=1
2019-07-11T18:43:25.506Z,1562870605.506 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:A.Pitch] Running Loop=1
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:C.] Running Loop=1
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:RequestRepeater] Running Loop=1
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-07-11T18:43:25.507Z,1562870605.507 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-07-11T18:43:25.508Z,1562870605.508 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-07-11T18:43:25.508Z,1562870605.508 [DUSBL:C.] Running Loop=1
2019-07-11T18:43:25.508Z,1562870605.508 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-11T18:43:25.508Z,1562870605.508 [DUSBL:A.Pitch] Running Loop=1
2019-07-11T18:43:27.107Z,1562870607.107 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:675.926819
2019-07-11T18:43:33.589Z,1562870613.589 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:43:33.969Z,1562870613.969 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:43:33.969Z,1562870613.969 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:43:33.970Z,1562870613.970 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:43:33.971Z,1562870613.971 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:43:34.368Z,1562870614.368 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:43:34.368Z,1562870614.368 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:43:34.368Z,1562870614.368 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:43:34.370Z,1562870614.370 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:43:34.778Z,1562870614.778 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:43:37.604Z,1562870617.604 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,630035F,18A,80,80,0A,FF
2019-07-11T18:43:37.604Z,1562870617.604 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,630035F,18A,80,80,0A,FF
2019-07-11T18:43:37.604Z,1562870617.604 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:43:41.669Z,1562870621.669 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:43:42.047Z,1562870622.047 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:43:42.047Z,1562870622.047 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:43:42.048Z,1562870622.048 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:43:42.048Z,1562870622.048 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:43:42.451Z,1562870622.451 [DUSBL_Hydroid](INFO): USBL response received:,E4
!U1,P
0,630035F,18A,80,80,0A,FF
2019-07-11T18:43:42.452Z,1562870622.452 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:43:42.452Z,1562870622.452 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:43:42.453Z,1562870622.453 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:43:42.869Z,1562870622.869 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:43:43.257Z,1562870623.257 [NAL9602](INFO): Powering up NAL9602
2019-07-11T18:43:45.684Z,1562870625.684 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,630035F,18A,80,80,0A,FF
2019-07-11T18:43:45.684Z,1562870625.684 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,630035F,18A,80,80,0A,FF
2019-07-11T18:43:45.684Z,1562870625.684 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:43:49.741Z,1562870629.741 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:43:50.129Z,1562870630.129 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:43:50.129Z,1562870630.129 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:43:50.129Z,1562870630.129 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:43:50.130Z,1562870630.130 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:43:50.528Z,1562870630.528 [DUSBL_Hydroid](INFO): USBL response received:,63,E4
!U1,P
30035F,18A,80,80,0A,FF
2019-07-11T18:43:50.528Z,1562870630.528 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:43:50.528Z,1562870630.528 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:43:50.529Z,1562870630.529 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:43:50.937Z,1562870630.937 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:43:51.336Z,1562870631.336 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,CCD,000,0035F,18A,80,80,09,FF
2019-07-11T18:43:51.336Z,1562870631.336 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:43:51.410Z,1562870631.410 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 14.280000 m
2019-07-11T18:43:54.162Z,1562870634.162 [NAL9602](INFO): NAL9602 initialized
2019-07-11T18:43:54.371Z,1562870634.371 [CommandLine](IMPORTANT): got command stop
2019-07-11T18:43:54.371Z,1562870634.371 [CommandLine](IMPORTANT): Scheduling is paused
2019-07-11T18:43:54.371Z,1562870634.371 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-11T18:43:54.616Z,1562870634.616 [MissionManager](INFO): MissionManager is completed.
2019-07-11T18:43:54.617Z,1562870634.617 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL] Stopped
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:A.Pitch] Stopped
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:B.SetSpeed] Stopped
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:C.] Stopped
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:RequestRepeater] Stopped
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-07-11T18:43:54.617Z,1562870634.617 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T18:43:55.010Z,1562870635.010 [MissionManager](IMPORTANT): Started mission Default
2019-07-11T18:43:55.010Z,1562870635.010 [Default] Running Loop=1
2019-07-11T18:43:55.010Z,1562870635.010 [Default](DEBUG): Aggregate::initialize Default
2019-07-11T18:43:55.010Z,1562870635.010 [Default:B.GoToSurface] Running Loop=1
2019-07-11T18:43:55.010Z,1562870635.010 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-11T18:43:55.011Z,1562870635.011 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-11T18:43:55.011Z,1562870635.011 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-11T18:43:55.011Z,1562870635.011 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-11T18:43:55.012Z,1562870635.012 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-11T18:43:55.012Z,1562870635.012 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-11T18:43:55.013Z,1562870635.013 [Default:A.Wait] Running Loop=1
2019-07-11T18:43:55.013Z,1562870635.013 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-11T18:44:08.334Z,1562870648.334 [Default:A.Wait](INFO): Done Waiting.
2019-07-11T18:44:08.334Z,1562870648.334 [Default:A.Wait] Stopped
2019-07-11T18:44:08.334Z,1562870648.334 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T18:44:08.735Z,1562870648.735 [Default:CheckIn] Running Loop=1
2019-07-11T18:44:08.736Z,1562870648.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-11T18:44:08.736Z,1562870648.736 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-11T18:44:10.342Z,1562870650.342 [NAL9602](DEBUG): Fix Requested
2019-07-11T18:45:12.783Z,1562870712.783 [CommandLine](IMPORTANT): got command quit
2019-07-11T18:45:13.787Z,1562870713.787 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:13.787Z,1562870713.787 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:13.790Z,1562870713.790 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-07-11T18:45:13.791Z,1562870713.791 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:13.791Z,1562870713.791 [CommandLine](INFO): Join timeout helper Thread ID is 3053
2019-07-11T18:45:13.814Z,1562870713.814 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-07-11T18:45:13.814Z,1562870713.814 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:13.815Z,1562870713.815 [NavChartDb](INFO): Join timeout helper Thread ID is 3054
2019-07-11T18:45:14.051Z,1562870714.051 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.051Z,1562870714.051 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.070Z,1562870714.070 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-07-11T18:45:14.071Z,1562870714.071 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.071Z,1562870714.071 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3055
2019-07-11T18:45:14.206Z,1562870714.206 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.207Z,1562870714.207 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-07-11T18:45:14.207Z,1562870714.207 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.218Z,1562870714.218 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-07-11T18:45:14.218Z,1562870714.218 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.219Z,1562870714.219 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3056
2019-07-11T18:45:14.362Z,1562870714.362 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.363Z,1562870714.363 [CTD_NeilBrown](INFO): Powering down
2019-07-11T18:45:14.374Z,1562870714.374 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.386Z,1562870714.386 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-07-11T18:45:14.386Z,1562870714.386 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.387Z,1562870714.387 [Radio_Surface](INFO): Join timeout helper Thread ID is 3057
2019-07-11T18:45:14.434Z,1562870714.434 [Radio_Surface](INFO): Powering down
2019-07-11T18:45:14.435Z,1562870714.435 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.436Z,1562870714.436 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.447Z,1562870714.447 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-07-11T18:45:14.447Z,1562870714.447 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.448Z,1562870714.448 [DataOverHttps](INFO): Join timeout helper Thread ID is 3058
2019-07-11T18:45:14.706Z,1562870714.706 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.710Z,1562870714.710 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.719Z,1562870714.719 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-07-11T18:45:14.719Z,1562870714.719 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.720Z,1562870714.720 [logger](INFO): Join timeout helper Thread ID is 3059
2019-07-11T18:45:14.726Z,1562870714.726 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.727Z,1562870714.727 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.739Z,1562870714.739 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-07-11T18:45:14.739Z,1562870714.739 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.739Z,1562870714.739 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-07-11T18:45:14.739Z,1562870714.739 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:14.740Z,1562870714.740 [controlThread](INFO): Join timeout helper Thread ID is 3060
2019-07-11T18:45:14.946Z,1562870714.946 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-11T18:45:14.947Z,1562870714.947 [controlThread](DEBUG): Uninitializing ControlThread
2019-07-11T18:45:14.947Z,1562870714.947 [AHRS_M2](INFO): Powering down
2019-07-11T18:45:15.019Z,1562870715.019 [DUSBL_Hydroid](INFO): Powering down
2019-07-11T18:45:15.091Z,1562870715.091 [Micromodem](INFO): Powering down
2019-07-11T18:45:15.187Z,1562870715.187 [NAL9602](INFO): Powering down
2019-07-11T18:45:15.258Z,1562870715.258 [RDI_Pathfinder](INFO): Powering down
2019-07-11T18:45:15.260Z,1562870715.260 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-07-11T18:45:15.261Z,1562870715.261 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-07-11T18:45:15.261Z,1562870715.261 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-07-11T18:45:15.261Z,1562870715.261 [MissionManager](INFO): Uninitializing Mission Default
2019-07-11T18:45:15.262Z,1562870715.262 [Default] Stopped
2019-07-11T18:45:15.262Z,1562870715.262 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-11T18:45:15.262Z,1562870715.262 [Default:B.GoToSurface] Stopped
2019-07-11T18:45:15.262Z,1562870715.262 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-11T18:45:15.262Z,1562870715.262 [Default:CheckIn] Stopped
2019-07-11T18:45:15.262Z,1562870715.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-11T18:45:15.262Z,1562870715.262 [Default:CheckIn:Read_GPS] Stopped
2019-07-11T18:45:15.265Z,1562870715.265 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-07-11T18:45:15.265Z,1562870715.265 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-07-11T18:45:15.265Z,1562870715.265 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-07-11T18:45:15.265Z,1562870715.265 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-07-11T18:45:15.266Z,1562870715.266 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-11T18:45:15.266Z,1562870715.266 [BuoyancyServo](INFO): Powering down
2019-07-11T18:45:15.278Z,1562870715.278 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-07-11T18:45:15.279Z,1562870715.279 [ElevatorServo](INFO): Powering down
2019-07-11T18:45:15.279Z,1562870715.279 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-07-11T18:45:15.280Z,1562870715.280 [MassServo](INFO): Powering down
2019-07-11T18:45:15.280Z,1562870715.280 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-11T18:45:15.280Z,1562870715.280 [RudderServo](INFO): Powering down
2019-07-11T18:45:15.281Z,1562870715.281 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-07-11T18:45:15.281Z,1562870715.281 [ThrusterServo](INFO): Powering down
2019-07-11T18:45:15.282Z,1562870715.282 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-07-11T18:45:15.283Z,1562870715.283 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-07-11T18:45:15.283Z,1562870715.283 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-07-11T18:45:15.283Z,1562870715.283 [CBIT](DEBUG): Powering off loads.
2019-07-11T18:45:15.294Z,1562870715.294 [CBIT](DEBUG): Disabling WDT.
2019-07-11T18:45:15.306Z,1562870715.306 [CBIT](DEBUG): Opening all GF detection circuits.
2019-07-11T18:45:15.307Z,1562870715.307 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:15.356Z,1562870715.356 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:15.365Z,1562870715.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:15.406Z,1562870715.406 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:15.408Z,1562870715.408 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:15.466Z,1562870715.466 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-11T18:45:15.539Z,1562870715.539 [logger ThreadHandler](INFO): Thread cancelled.