2018-09-11T11:40:25.567Z,1536666025.567 [DataOverHttps](INFO): Received command:restart logs
2018-09-11T11:40:25.581Z,1536666025.581 [CommandLine](IMPORTANT): got command restart logs
2018-09-11T11:40:41.687Z,1536666041.687 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T112653/Courier0010.lzma
2018-09-11T11:40:43.686Z,1536666043.686 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Courier0010.lzma.bak
2018-09-11T11:40:43.686Z,1536666043.686 [DataOverHttps](IMPORTANT): SBD MOMSN=8515873, MTMSN=20180911T113942
2018-09-11T11:40:59.391Z,1536666059.391 [DataOverHttps](INFO): Received command:load Transport/keepstation.xml;set keepstation.NeedCommsTime 90 minute;set keepstation.Speed .5 meter_per_second;set keepstation.Radius 1000 meter;run 
2018-09-11T11:40:59.406Z,1536666059.406 [CommandLine](IMPORTANT): got command load ./Missions/Transport/keepstation.xml
2018-09-11T11:40:59.407Z,1536666059.407 [MissionManager](INFO): Loading Mission: ./Missions/Transport/keepstation.xml
2018-09-11T11:40:59.505Z,1536666059.505 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2018-09-11T11:40:59.507Z,1536666059.507 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2018-09-11T11:40:59.510Z,1536666059.510 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2018-09-11T11:40:59.512Z,1536666059.512 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2018-09-11T11:40:59.514Z,1536666059.514 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2018-09-11T11:40:59.516Z,1536666059.516 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2018-09-11T11:40:59.519Z,1536666059.519 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2018-09-11T11:40:59.521Z,1536666059.521 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2018-09-11T11:40:59.563Z,1536666059.563 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2018-09-11T11:40:59.566Z,1536666059.566 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2018-09-11T11:40:59.568Z,1536666059.568 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2018-09-11T11:40:59.568Z,1536666059.568 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-09-11T11:40:59.693Z,1536666059.693 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 3.000000 h
2018-09-11T11:40:59.695Z,1536666059.695 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2018-09-11T11:40:59.697Z,1536666059.697 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-09-11T11:40:59.700Z,1536666059.700 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2018-09-11T11:40:59.703Z,1536666059.703 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-09-11T11:40:59.705Z,1536666059.705 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2018-09-11T11:40:59.707Z,1536666059.707 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2018-09-11T11:40:59.740Z,1536666059.740 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-11T11:40:59.788Z,1536666059.788 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-09-11T11:40:59.821Z,1536666059.821 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2018-09-11T11:40:59.832Z,1536666059.832 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2018-09-11T11:40:59.835Z,1536666059.835 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-09-11T11:40:59.836Z,1536666059.836 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-11T11:40:59.841Z,1536666059.841 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-11T11:40:59.847Z,1536666059.847 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-09-11T11:40:59.862Z,1536666059.862 [keepstation:F.Pitch](DEBUG): Construct.
2018-09-11T11:40:59.906Z,1536666059.906 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2018-09-11T11:40:59.920Z,1536666059.920 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2018-09-11T11:40:59.938Z,1536666059.938 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2018-09-11T11:40:59.952Z,1536666059.952 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2018-09-11T11:40:59.991Z,1536666059.991 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2018-09-11T11:41:00.002Z,1536666060.002 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2018-09-11T11:41:00.019Z,1536666060.019 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2018-09-11T11:41:00.052Z,1536666060.052 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="utf-8"?>
<Mission xmlns="Tethys"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Units="Tethys/Units"
         xmlns:Universal="Tethys/Universal"
         xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="keepstation">

    <Description>
        Vehicle transits to desired waypoint (or stays put if none is commanded)
        and stays within the specified radius.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="Latitude"><Description>
        Latitude of waypoint to hold. If NaN, hold the latitude at start of
        mission.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Longitude"><Description>
        Longitude of waypoint to hold. If NaN, hold the longitude at start of
        mission.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Depth"><Description>
        Depth held during drift mode
    </Description><Units:meter/><Value>30</Value></DefineArg>

    <DefineArg Name="ApproachDepth"><Description>
        Depth for initial approach to station.
    </Description><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed in drift
        mode
    </Description><Units:meter/><Value>4</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle transit speed.
    </Description><Units:meter_per_second/><Value>0.75</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius of circle around waypoint to hold.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum allowable depth during the mission.
    </Description><Units:meter/><Value>45</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum distance from the shoreline to maintain
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout and a NeedComms aggregate. -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Most missions should not actively control mass or buoyancy. However, keepstation will want to control buoyancy, so only set mass here. -->

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

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Then transit to specified location. TODO: Consider encapsulating in a standard Insert/ApproachTransit.xml -->

    <Aggregate Id="TransitToStation">

        <Description>
            Need a separate aggregate for transit if we want to specify a
            different depth for the approach.
        </Description>

        <Sequence/>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
        </Guidance:Buoyancy>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="ApproachDepth"/></Setting>
        </Guidance:Pitch>

        <Guidance:SetSpeed>
            <Parallel/>
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Waypoint Id="Wpt1">
            <Sequence/>
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Latitude"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Longitude"/></Setting>
        </Guidance:Waypoint>

    </Aggregate>

    <Call Id="OnStation" RefId="NeedComms"/>

    <Aggregate Id="KeepStation">

        <Sequence/>

<!-- TODO: Consider making depth and depthDeadband into settings for the keepstation behavior. -->

        <Assign><Parallel/><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="Depth"/></Setting>
        </Guidance:Pitch>

        <Guidance:KeepStation>
            <Parallel/>
            <Setting><Guidance:KeepStation.latitude/><Arg Name="Latitude"/></Setting>
            <Setting><Guidance:KeepStation.longitude/><Arg Name="Longitude"/></Setting>
            <Setting><Guidance:KeepStation.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:KeepStation.speed/><Arg Name="Speed"/></Setting>
        </Guidance:KeepStation>

        <Guidance:Wait>
            <Description>
                Due to the way the KeepStation behavior is currently
                implemented, we must run it in parallel and use a Wait to keep
                from completing the mission before the timeout expires. However,
                this new version of the mission uses a top-level timeout so that
                the entire mission will not run for longer then MissionTimeout.
            </Description>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2018-09-11T11:41:00.053Z,1536666060.053 [CommandLine](IMPORTANT): Loaded ./Missions/Transport/keepstation.xml
2018-09-11T11:41:04.412Z,1536666064.412 [CommandLine](IMPORTANT): got command set keepstation.NeedCommsTime 90.000000 minute
2018-09-11T11:41:04.412Z,1536666064.412 [CommandLine](IMPORTANT): got command set keepstation.Speed 0.500000 meter_per_second
2018-09-11T11:41:04.413Z,1536666064.413 [CommandLine](IMPORTANT): got command set keepstation.Radius 1000.000000 meter
2018-09-11T11:41:04.414Z,1536666064.414 [CommandLine](IMPORTANT): got command run 
2018-09-11T11:41:04.418Z,1536666064.418 [CommandLine](IMPORTANT): Running 
2018-09-11T11:41:04.530Z,1536666064.530 [Default] Stopped
2018-09-11T11:41:04.530Z,1536666064.530 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-11T11:41:04.531Z,1536666064.531 [Default:B.GoToSurface] Stopped
2018-09-11T11:41:04.531Z,1536666064.531 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-11T11:41:04.531Z,1536666064.531 [Default:CheckIn] Stopped
2018-09-11T11:41:04.531Z,1536666064.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-11T11:41:04.531Z,1536666064.531 [Default:CheckIn:Read_Iridium] Stopped
2018-09-11T11:41:04.531Z,1536666064.531 [MissionManager](IMPORTANT): Started mission keepstation
2018-09-11T11:41:04.531Z,1536666064.531 [keepstation] Running Loop=1
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation](DEBUG): Aggregate::initialize keepstation
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation:StandardEnvelopes] Running Loop=1
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-11T11:41:04.532Z,1536666064.532 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-11T11:41:04.533Z,1536666064.533 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-11T11:41:04.533Z,1536666064.533 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-09-11T11:41:04.533Z,1536666064.533 [keepstation:F.Pitch] Running Loop=1
2018-09-11T11:41:04.533Z,1536666064.533 [keepstation:F.Pitch](DEBUG): Initialize.
2018-09-11T11:41:04.533Z,1536666064.533 [keepstation:B] Running Loop=1
2018-09-11T11:41:04.534Z,1536666064.534 [keepstation:F.Pitch] Running Loop=1
2018-09-11T11:41:04.539Z,1536666064.539 [keepstation:StandardEnvelopes] Running Loop=1
2018-09-11T11:41:04.539Z,1536666064.539 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-11T11:41:04.540Z,1536666064.540 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-11T11:41:04.541Z,1536666064.541 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-11T11:41:04.548Z,1536666064.548 [keepstation:B] Stopped
2018-09-11T11:41:04.548Z,1536666064.548 [keepstation:D] Running Loop=1
2018-09-11T11:41:04.645Z,1536666064.645 [keepstation:D] Stopped
2018-09-11T11:41:04.645Z,1536666064.645 [keepstation:E] Running Loop=1
2018-09-11T11:41:05.062Z,1536666065.062 [keepstation:E] Stopped
2018-09-11T11:41:05.062Z,1536666065.062 [keepstation:StartingMission] Running Loop=1
2018-09-11T11:41:05.064Z,1536666065.064 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2018-09-11T11:41:05.441Z,1536666065.441 [keepstation:NeedComms] Running Loop=1
2018-09-11T11:41:05.441Z,1536666065.441 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2018-09-11T11:41:05.441Z,1536666065.441 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2018-09-11T11:41:05.441Z,1536666065.441 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-11T11:41:05.441Z,1536666065.441 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-11T11:41:05.442Z,1536666065.442 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-11T11:41:05.446Z,1536666065.446 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-11T11:41:05.447Z,1536666065.447 [keepstation:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-11T11:41:05.447Z,1536666065.447 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-11T11:41:05.448Z,1536666065.448 [keepstation:NeedComms:A] Running Loop=1
2018-09-11T11:41:05.458Z,1536666065.458 [keepstation:NeedComms:A](INFO): last time_fix was: 1536665906.000000 second since 1970/01/01T00:00:00Z 
2018-09-11T11:41:05.465Z,1536666065.465 [keepstation:NeedComms:A] Stopped
2018-09-11T11:41:05.896Z,1536666065.896 [keepstation:NeedComms:C] Running Loop=1
2018-09-11T11:41:06.252Z,1536666066.252 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-11T11:41:06.584Z,1536666066.584 [NAL9602](INFO): Powering up
2018-09-11T11:41:17.388Z,1536666077.388 [NAL9602](INFO): NAL9602 initialized
2018-09-11T11:41:24.919Z,1536666084.919 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T112653/Courier0013.lzma
2018-09-11T11:41:27.717Z,1536666087.717 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Courier0013.lzma.bak
2018-09-11T11:41:27.717Z,1536666087.717 [DataOverHttps](INFO): SBD MOMSN=8515876
2018-09-11T11:41:49.721Z,1536666109.721 [NAL9602](INFO): SBD MO Status=0, MOMSN=9791, MT Status=0, MTMSN=0
2018-09-11T11:41:49.722Z,1536666109.722 [NAL9602](INFO): No messages in MT queue
2018-09-11T11:42:18.272Z,1536666138.272 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2018-09-11T11:42:18.273Z,1536666138.273 [CommandLine](IMPORTANT): platform_buoyancy_position 945.864886 cc
2018-09-11T11:42:57.104Z,1536666177.104 [NAL9602](INFO): GPS fix at 20180911T114155: (36.913706, -122.407509)
2018-09-11T11:42:57.145Z,1536666177.145 [keepstation:NeedComms:C] Stopped
2018-09-11T11:42:57.145Z,1536666177.145 [keepstation:NeedComms:D] Running Loop=1
2018-09-11T11:42:57.567Z,1536666177.567 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-11T11:43:05.707Z,1536666185.707 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T114025/Courier0000.lzma
2018-09-11T11:43:07.305Z,1536666187.305 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Courier0000.lzma.bak
2018-09-11T11:43:07.305Z,1536666187.305 [DataOverHttps](INFO): SBD MOMSN=8515878
2018-09-11T11:43:29.644Z,1536666209.644 [NAL9602](INFO): Powering down
2018-09-11T11:43:36.173Z,1536666216.173 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20180911T114025/Courier0003.lzma
2018-09-11T11:43:38.168Z,1536666218.168 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Courier0003.lzma.bak
2018-09-11T11:43:38.168Z,1536666218.168 [DataOverHttps](INFO): SBD MOMSN=8515882
2018-09-11T11:43:39.893Z,1536666219.893 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2018-09-11T11:43:39.894Z,1536666219.894 [CommandLine](IMPORTANT): platform_buoyancy_position 945.864886 cc
2018-09-11T11:43:52.913Z,1536666232.913 [CommandLine](IMPORTANT): got command get platform_buoyancy_position
2018-09-11T11:43:52.913Z,1536666232.913 [CommandLine](IMPORTANT): platform_buoyancy_position 945.864886 cc
2018-09-11T11:44:04.735Z,1536666244.735 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T114025/Courier0006.lzma
2018-09-11T11:44:06.734Z,1536666246.734 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Courier0006.lzma.bak
2018-09-11T11:44:06.734Z,1536666246.734 [DataOverHttps](INFO): SBD MOMSN=8515885
2018-09-11T11:44:39.138Z,1536666279.138 [DataOverHttps](INFO): Sending 1118 bytes from file Logs/20180911T112653/Express0001.lzma
2018-09-11T11:44:49.534Z,1536666289.534 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Express0001.lzma.bak
2018-09-11T11:44:49.534Z,1536666289.534 [DataOverHttps](INFO): SBD MOMSN=8515887
2018-09-11T11:45:10.352Z,1536666310.352 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20180911T112653/Express0005.lzma
2018-09-11T11:45:12.749Z,1536666312.749 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Express0005.lzma.bak
2018-09-11T11:45:12.749Z,1536666312.749 [DataOverHttps](INFO): SBD MOMSN=8515921
2018-09-11T11:45:41.924Z,1536666341.924 [DataOverHttps](INFO): Sending 564 bytes from file Logs/20180911T112653/Express0008.lzma
2018-09-11T11:45:45.068Z,1536666345.068 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Express0008.lzma.bak
2018-09-11T11:45:45.068Z,1536666345.068 [DataOverHttps](INFO): SBD MOMSN=8515924
2018-09-11T11:46:06.723Z,1536666366.723 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20180911T112653/Express0011.lzma
2018-09-11T11:46:08.321Z,1536666368.321 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Express0011.lzma.bak
2018-09-11T11:46:08.321Z,1536666368.321 [DataOverHttps](INFO): SBD MOMSN=8515948
2018-09-11T11:46:27.066Z,1536666387.066 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20180911T112653/Express0014.lzma
2018-09-11T11:46:29.061Z,1536666389.061 [DataOverHttps](INFO): Moved sent file to Logs/20180911T112653/Express0014.lzma.bak
2018-09-11T11:46:29.061Z,1536666389.061 [DataOverHttps](INFO): SBD MOMSN=8515950
2018-09-11T11:46:48.275Z,1536666408.275 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T114025/Express0001.lzma
2018-09-11T11:46:50.274Z,1536666410.274 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Express0001.lzma.bak
2018-09-11T11:46:50.279Z,1536666410.279 [DataOverHttps](INFO): SBD MOMSN=8515953
2018-09-11T11:47:17.653Z,1536666437.653 [DataOverHttps](INFO): Sending 596 bytes from file Logs/20180911T114025/Express0004.lzma
2018-09-11T11:47:22.849Z,1536666442.849 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Express0004.lzma.bak
2018-09-11T11:47:22.849Z,1536666442.849 [DataOverHttps](INFO): SBD MOMSN=8515955
2018-09-11T11:48:15.881Z,1536666495.881 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T114025/Express0007.lzma
2018-09-11T11:48:21.477Z,1536666501.477 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Express0007.lzma.bak
2018-09-11T11:48:21.477Z,1536666501.477 [DataOverHttps](INFO): SBD MOMSN=8515972
2018-09-11T11:48:22.293Z,1536666502.293 [keepstation:NeedComms:D] Stopped
2018-09-11T11:48:22.294Z,1536666502.294 [keepstation:NeedComms:E] Running Loop=1
2018-09-11T11:48:22.422Z,1536666502.422 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-11T11:48:22.732Z,1536666502.732 [NAL9602](INFO): Powering up
2018-09-11T11:48:33.712Z,1536666513.712 [NAL9602](INFO): NAL9602 initialized
2018-09-11T11:48:53.718Z,1536666533.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=9792, MT Status=2, MTMSN=0
2018-09-11T11:48:53.718Z,1536666533.718 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-11T11:49:04.913Z,1536666544.913 [NAL9602](INFO): SBD MO Status=0, MOMSN=9792, MT Status=0, MTMSN=0
2018-09-11T11:49:04.914Z,1536666544.914 [NAL9602](INFO): No messages in MT queue
2018-09-11T11:50:27.080Z,1536666627.080 [NAL9602](INFO): GPS fix at 20180911T114925: (36.913304, -122.408113)
2018-09-11T11:50:27.166Z,1536666627.166 [keepstation:NeedComms:E] Stopped
2018-09-11T11:50:27.172Z,1536666627.172 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2018-09-11T11:50:27.172Z,1536666627.172 [keepstation:NeedComms] Stopped
2018-09-11T11:50:27.172Z,1536666627.172 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2018-09-11T11:50:27.172Z,1536666627.172 [keepstation:NeedComms:B.GoToSurface] Stopped
2018-09-11T11:50:27.172Z,1536666627.172 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-11T11:50:27.533Z,1536666627.533 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2018-09-11T11:50:27.534Z,1536666627.534 [keepstation:StartingMission] Stopped
2018-09-11T11:50:27.534Z,1536666627.534 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2018-09-11T11:50:27.534Z,1536666627.534 [keepstation:TransitToStation] Running Loop=1
2018-09-11T11:50:27.534Z,1536666627.534 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2018-09-11T11:50:27.534Z,1536666627.534 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2018-09-11T11:50:27.535Z,1536666627.534 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-09-11T11:50:27.535Z,1536666627.535 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2018-09-11T11:50:27.535Z,1536666627.535 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2018-09-11T11:50:27.535Z,1536666627.535 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2018-09-11T11:50:27.535Z,1536666627.535 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2018-09-11T11:50:27.535Z,1536666627.535 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2018-09-11T11:50:27.535Z,1536666627.535 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2018-09-11T11:50:28.088Z,1536666628.088 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2018-09-11T11:50:28.093Z,1536666628.093 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2018-09-11T11:50:28.119Z,1536666628.119 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2018-09-11T11:50:29.126Z,1536666629.126 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-09-11T11:50:29.127Z,1536666629.127 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-09-11T11:50:29.127Z,1536666629.127 [ThrusterServo] Communications Fault, FailCount= 1
2018-09-11T11:50:29.127Z,1536666629.127 [ThrusterServo](ERROR): Communications Fault
2018-09-11T11:50:29.131Z,1536666629.131 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-09-11T11:50:29.303Z,1536666629.303 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-11T11:50:29.304Z,1536666629.304 [ThrusterServo](INFO): Powering down
2018-09-11T11:50:32.931Z,1536666632.931 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-11T11:50:32.931Z,1536666632.931 [ThrusterServo] No Fault, FailCount= 1
2018-09-11T11:50:33.312Z,1536666633.312 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:50:33.312Z,1536666633.312 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-11T11:50:42.271Z,1536666642.271 [Radio_Surface](INFO): Powering down
2018-09-11T11:50:47.343Z,1536666647.343 [BuoyancyServo](FAULT): Overload Error
2018-09-11T11:50:47.343Z,1536666647.343 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-11T11:50:47.343Z,1536666647.343 [BuoyancyServo](ERROR): Hardware Fault
2018-09-11T11:50:47.383Z,1536666647.383 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-09-11T11:50:47.737Z,1536666647.737 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-11T11:50:47.738Z,1536666647.738 [BuoyancyServo](INFO): Powering down
2018-09-11T11:50:48.331Z,1536666648.331 [Radio_Surface](INFO): Powering up
2018-09-11T11:50:49.739Z,1536666649.739 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-11T11:50:49.739Z,1536666649.739 [BuoyancyServo] No Fault, FailCount= 1
2018-09-11T11:50:50.110Z,1536666650.110 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:50:50.111Z,1536666650.111 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-11T11:50:51.038Z,1536666651.038 [ThrusterServo](FAULT): Overload Error
2018-09-11T11:50:51.039Z,1536666651.039 [ThrusterServo] Hardware Fault, FailCount= 2
2018-09-11T11:50:51.039Z,1536666651.039 [ThrusterServo](ERROR): Hardware Fault
2018-09-11T11:50:51.043Z,1536666651.043 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2018-09-11T11:50:51.327Z,1536666651.327 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-11T11:50:51.327Z,1536666651.327 [ThrusterServo](INFO): Powering down
2018-09-11T11:50:52.129Z,1536666652.129 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:50:52.130Z,1536666652.130 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-11T11:50:52.135Z,1536666652.135 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-11T11:50:52.135Z,1536666652.135 [ThrusterServo] No Fault, FailCount= 2
2018-09-11T11:50:57.367Z,1536666657.367 [ThrusterServo](FAULT): Overload Error
2018-09-11T11:50:57.367Z,1536666657.367 [ThrusterServo] Hardware Fault, FailCount= 3
2018-09-11T11:50:57.367Z,1536666657.367 [ThrusterServo](ERROR): Hardware Fault
2018-09-11T11:50:57.371Z,1536666657.371 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2018-09-11T11:50:57.751Z,1536666657.751 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-11T11:50:57.751Z,1536666657.751 [ThrusterServo](INFO): Powering down
2018-09-11T11:50:58.555Z,1536666658.555 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:50:58.556Z,1536666658.556 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-11T11:50:58.560Z,1536666658.560 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-11T11:50:58.560Z,1536666658.560 [ThrusterServo] No Fault, FailCount= 3
2018-09-11T11:51:02.848Z,1536666662.848 [NAL9602](INFO): Powering down
2018-09-11T11:51:03.798Z,1536666663.798 [ThrusterServo](FAULT): Overload Error
2018-09-11T11:51:03.798Z,1536666663.798 [ThrusterServo] Hardware Fault, FailCount= 4
2018-09-11T11:51:03.799Z,1536666663.799 [ThrusterServo](ERROR): Hardware Fault
2018-09-11T11:51:03.803Z,1536666663.803 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2018-09-11T11:51:04.164Z,1536666664.164 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-11T11:51:04.164Z,1536666664.164 [ThrusterServo](INFO): Powering down
2018-09-11T11:51:04.937Z,1536666664.937 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:51:04.938Z,1536666664.938 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-11T11:51:04.952Z,1536666664.952 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-11T11:51:04.952Z,1536666664.952 [ThrusterServo] No Fault, FailCount= 4
2018-09-11T11:51:05.371Z,1536666665.371 [BuoyancyServo](FAULT): Overload Error
2018-09-11T11:51:05.371Z,1536666665.371 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-09-11T11:51:05.371Z,1536666665.371 [BuoyancyServo](ERROR): Hardware Fault
2018-09-11T11:51:05.411Z,1536666665.411 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-09-11T11:51:05.712Z,1536666665.712 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-11T11:51:05.713Z,1536666665.713 [BuoyancyServo](INFO): Powering down
2018-09-11T11:51:07.699Z,1536666667.699 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-11T11:51:07.699Z,1536666667.699 [BuoyancyServo] No Fault, FailCount= 2
2018-09-11T11:51:08.156Z,1536666668.156 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:51:08.156Z,1536666668.156 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-11T11:51:10.158Z,1536666670.158 [ThrusterServo](FAULT): Overload Error
2018-09-11T11:51:10.159Z,1536666670.159 [ThrusterServo] Hardware Fault, FailCount= 5
2018-09-11T11:51:10.159Z,1536666670.159 [ThrusterServo](ERROR): Hardware Fault
2018-09-11T11:51:10.170Z,1536666670.170 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2018-09-11T11:51:10.170Z,1536666670.170 [CBIT](CRITICAL): Hardware Fault in component: ThrusterServo
2018-09-11T11:51:10.531Z,1536666670.531 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-11T11:51:10.531Z,1536666670.531 [ThrusterServo](INFO): Powering down
2018-09-11T11:51:10.534Z,1536666670.534 [CBIT](INFO): Critical error at 20180911T115110
2018-09-11T11:51:10.534Z,1536666670.534 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-11T11:51:10.908Z,1536666670.908 [MissionManager](INFO): MissionManager is completed.
2018-09-11T11:51:10.908Z,1536666670.908 [MissionManager](INFO): Uninitializing Mission keepstation
2018-09-11T11:51:10.908Z,1536666670.908 [keepstation] Stopped
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes] Stopped
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-09-11T11:51:10.909Z,1536666670.909 [keepstation:F.Pitch] Stopped
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation] Stopped
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:A.Buoyancy] Stopped
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:B.Pitch] Stopped
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:C.SetSpeed] Stopped
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2018-09-11T11:51:10.910Z,1536666670.910 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2018-09-11T11:51:11.305Z,1536666671.305 [MissionManager](IMPORTANT): Started mission Default
2018-09-11T11:51:11.305Z,1536666671.305 [Default] Running Loop=1
2018-09-11T11:51:11.305Z,1536666671.305 [Default](DEBUG): Aggregate::initialize Default
2018-09-11T11:51:11.306Z,1536666671.306 [Default:B.GoToSurface] Running Loop=1
2018-09-11T11:51:11.306Z,1536666671.306 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-11T11:51:11.306Z,1536666671.306 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-11T11:51:11.306Z,1536666671.306 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-11T11:51:11.307Z,1536666671.307 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-11T11:51:11.307Z,1536666671.307 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-11T11:51:11.308Z,1536666671.308 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-11T11:51:11.308Z,1536666671.308 [Default:A.Wait] Running Loop=1
2018-09-11T11:51:11.308Z,1536666671.308 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-11T11:51:24.497Z,1536666684.497 [Default:A.Wait](INFO): Done Waiting.
2018-09-11T11:51:24.497Z,1536666684.497 [Default:A.Wait] Stopped
2018-09-11T11:51:24.497Z,1536666684.497 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-11T11:51:24.892Z,1536666684.892 [Default:CheckIn] Running Loop=1
2018-09-11T11:51:24.892Z,1536666684.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-11T11:51:24.892Z,1536666684.892 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-11T11:51:25.640Z,1536666685.640 [NAL9602](INFO): Powering up
2018-09-11T11:51:36.444Z,1536666696.444 [NAL9602](INFO): NAL9602 initialized
2018-09-11T11:51:40.183Z,1536666700.183 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-11T11:51:40.183Z,1536666700.183 [ThrusterServo] No Fault, FailCount= 5
2018-09-11T11:51:40.607Z,1536666700.607 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-11T11:51:40.607Z,1536666700.607 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-11T11:51:53.237Z,1536666713.237 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:51:57.642Z,1536666717.642 [NAL9602](INFO): SBD MO Status=0, MOMSN=9793, MT Status=0, MTMSN=0
2018-09-11T11:51:57.642Z,1536666717.642 [NAL9602](INFO): No messages in MT queue
2018-09-11T11:52:53.236Z,1536666773.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:53:25.254Z,1536666805.254 [NAL9602](INFO): GPS fix at 20180911T115223: (36.913153, -122.408408)
2018-09-11T11:53:25.308Z,1536666805.308 [Default:CheckIn:Read_GPS] Stopped
2018-09-11T11:53:25.308Z,1536666805.308 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-11T11:53:40.177Z,1536666820.177 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.138382
2018-09-11T11:54:06.289Z,1536666846.289 [NAL9602](INFO): SBD MO Status=1, MOMSN=9794, MT Status=0, MTMSN=0
2018-09-11T11:54:06.339Z,1536666846.339 [NAL9602](INFO): Sent 138 bytes from file Logs/20180911T114025/Courier0009.lzma
2018-09-11T11:54:06.339Z,1536666846.339 [NAL9602](INFO): Packets left to send: 0
2018-09-11T11:54:06.341Z,1536666846.341 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180911T114025/Courier0009.lzma.parts/0000.sbd
2018-09-11T11:54:06.341Z,1536666846.341 [NAL9602](DEBUG): Completed sending Logs/20180911T114025/Courier0009.lzma
2018-09-11T11:54:10.776Z,1536666850.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:54:30.385Z,1536666870.385 [NAL9602](INFO): SBD MO Status=1, MOMSN=9795, MT Status=0, MTMSN=0
2018-09-11T11:54:30.439Z,1536666870.439 [NAL9602](INFO): Sent 332 bytes from file Logs/20180911T114025/Express0010.lzma
2018-09-11T11:54:30.439Z,1536666870.439 [NAL9602](INFO): Packets left to send: 1
2018-09-11T11:54:30.440Z,1536666870.440 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180911T114025/Express0010.lzma.parts/0001.sbd
2018-09-11T11:54:52.617Z,1536666892.617 [NAL9602](INFO): SBD MO Status=1, MOMSN=9796, MT Status=0, MTMSN=0
2018-09-11T11:54:52.674Z,1536666892.674 [NAL9602](INFO): Sent 43 bytes from file Logs/20180911T114025/Express0010.lzma
2018-09-11T11:54:52.674Z,1536666892.674 [NAL9602](INFO): Packets left to send: 0
2018-09-11T11:54:52.675Z,1536666892.675 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180911T114025/Express0010.lzma.parts/0000.sbd
2018-09-11T11:54:52.675Z,1536666892.675 [NAL9602](DEBUG): Completed sending Logs/20180911T114025/Express0010.lzma
2018-09-11T11:55:06.617Z,1536666906.617 [NAL9602](INFO): SBD MO Status=0, MOMSN=9797, MT Status=0, MTMSN=0
2018-09-11T11:55:06.731Z,1536666906.731 [Default:CheckIn:Read_Iridium] Stopped
2018-09-11T11:55:06.731Z,1536666906.731 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-11T11:55:06.731Z,1536666906.731 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-11T11:55:10.604Z,1536666910.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:55:37.407Z,1536666937.407 [NAL9602](INFO): Powering down
2018-09-11T11:56:10.604Z,1536666970.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:57:10.604Z,1536667030.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:58:11.004Z,1536667091.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-11T11:58:51.417Z,1536667131.417 [DataOverHttps](IMPORTANT): SBD MTMSN=20180911T115749
2018-09-11T11:58:51.420Z,1536667131.420 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003447
2018-09-11T11:58:59.719Z,1536667139.719 [DataOverHttps](INFO): Received command:sched asap "set keepstation:NeedComms.SurfaceSpeed 0 meter_per_second;run " 4ebvo 2 2
2018-09-11T11:58:59.752Z,1536667139.752 [CommandLine](IMPORTANT): got command schedule asap "set keepstation:NeedComms.SurfaceSpeed 0 meter_per_second;run " 4ebvo 2 2.000000
2018-09-11T11:58:59.753Z,1536667139.753 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=4ebvo
2018-09-11T11:58:59.754Z,1536667139.754 [CommandLine](IMPORTANT): Scheduled #1 (#2 of 2 with id='4ebvo'): "set keepstation:NeedComms.SurfaceSpeed 0 meter_per_second;run " ASAP
2018-09-11T12:00:07.368Z,1536667207.368 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-11T12:00:07.368Z,1536667207.368 [Default:CheckIn:C.Wait] Stopped
2018-09-11T12:00:07.368Z,1536667207.368 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-11T12:00:07.369Z,1536667207.369 [Default:CheckIn:D] Running Loop=1
2018-09-11T12:00:07.774Z,1536667207.774 [Default:CheckIn:D] Stopped
2018-09-11T12:00:07.774Z,1536667207.774 [Default:CheckIn:E] Running Loop=1
2018-09-11T12:00:08.174Z,1536667208.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.941137 min 
2018-09-11T12:00:08.174Z,1536667208.174 [Default:CheckIn:E] Stopped
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn] Stopped
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn](INFO): Running loop #2
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn] Running Loop=2
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-11T12:00:08.175Z,1536667208.175 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-11T12:00:08.916Z,1536667208.916 [NAL9602](INFO): Powering up
2018-09-11T12:00:20.116Z,1536667220.116 [NAL9602](INFO): NAL9602 initialized
2018-09-11T12:00:35.317Z,1536667235.317 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9798, MT Status=1, MTMSN=1594
2018-09-11T12:00:35.318Z,1536667235.318 [NAL9602](INFO): Data available in MT queue
2018-09-11T12:00:35.855Z,1536667235.855 [NAL9602](INFO): Received command:configSet ThrusterServo.loadAtStartup 0 bool persist
2018-09-11T12:00:36.220Z,1536667236.220 [CommandLine](IMPORTANT): got command configSet ThrusterServo.loadAtStartup 0.000000 bool persist
2018-09-11T12:00:36.220Z,1536667236.220 [CommandLine](IMPORTANT): configSet ThrusterServo.loadAtStartup requires a restart to take effect.
2018-09-11T12:02:20.284Z,1536667340.284 [NAL9602](INFO): GPS fix at 20180911T120118: (36.912636, -122.408899)
2018-09-11T12:02:20.355Z,1536667340.355 [Default:CheckIn:Read_GPS] Stopped
2018-09-11T12:02:20.355Z,1536667340.355 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-11T12:02:28.703Z,1536667348.703 [DataOverHttps](INFO): Sending 65 bytes from file Logs/20180911T114025/Courier0012.lzma
2018-09-11T12:02:30.301Z,1536667350.301 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Courier0012.lzma.bak
2018-09-11T12:02:30.301Z,1536667350.301 [DataOverHttps](INFO): SBD MOMSN=8516083
2018-09-11T12:02:52.467Z,1536667372.467 [NAL9602](INFO): Powering down
2018-09-11T12:03:08.435Z,1536667388.435 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180911T114025/Courier0015.lzma
2018-09-11T12:03:10.036Z,1536667390.036 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Courier0015.lzma.bak
2018-09-11T12:03:10.036Z,1536667390.036 [DataOverHttps](INFO): SBD MOMSN=8516086
2018-09-11T12:03:39.608Z,1536667419.608 [DataOverHttps](INFO): Sending 524 bytes from file Logs/20180911T114025/Express0013.lzma
2018-09-11T12:03:54.404Z,1536667434.404 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-09-11T12:04:05.557Z,1536667445.557 [DataOverHttps](INFO): Sending 524 bytes from file Logs/20180911T114025/Express0013.lzma
2018-09-11T12:04:09.553Z,1536667449.553 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Express0013.lzma.bak
2018-09-11T12:04:09.553Z,1536667449.553 [DataOverHttps](INFO): SBD MOMSN=8516089
2018-09-11T12:05:03.739Z,1536667503.739 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20180911T114025/Express0016.lzma
2018-09-11T12:05:06.141Z,1536667506.141 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Express0016.lzma.bak
2018-09-11T12:05:06.142Z,1536667506.142 [DataOverHttps](INFO): SBD MOMSN=8516100
2018-09-11T12:05:06.813Z,1536667506.813 [Default:CheckIn:Read_Iridium] Stopped
2018-09-11T12:05:06.814Z,1536667506.814 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-11T12:05:06.814Z,1536667506.814 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-11T12:09:38.013Z,1536667778.013 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2018-09-11T12:10:07.281Z,1536667807.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-11T12:10:07.281Z,1536667807.281 [Default:CheckIn:C.Wait] Stopped
2018-09-11T12:10:07.281Z,1536667807.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-11T12:10:07.281Z,1536667807.281 [Default:CheckIn:D] Running Loop=1
2018-09-11T12:10:07.673Z,1536667807.673 [Default:CheckIn:D] Stopped
2018-09-11T12:10:07.673Z,1536667807.673 [Default:CheckIn:E] Running Loop=1
2018-09-11T12:10:08.088Z,1536667808.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.939459 min 
2018-09-11T12:10:08.089Z,1536667808.089 [Default:CheckIn:E] Stopped
2018-09-11T12:10:08.089Z,1536667808.089 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-11T12:10:08.089Z,1536667808.089 [Default:CheckIn] Stopped
2018-09-11T12:10:08.089Z,1536667808.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-11T12:10:08.089Z,1536667808.089 [Default:CheckIn](INFO): Running loop #3
2018-09-11T12:10:08.089Z,1536667808.089 [Default:CheckIn] Running Loop=3
2018-09-11T12:10:08.090Z,1536667808.090 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-11T12:10:08.090Z,1536667808.090 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-11T12:10:08.824Z,1536667808.824 [NAL9602](INFO): Powering up
2018-09-11T12:10:19.852Z,1536667819.852 [NAL9602](INFO): NAL9602 initialized
2018-09-11T12:10:37.053Z,1536667837.053 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9799, MT Status=1, MTMSN=1595
2018-09-11T12:10:37.054Z,1536667837.054 [NAL9602](INFO): Data available in MT queue
2018-09-11T12:10:37.579Z,1536667837.579 [NAL9602](INFO): Received command:configSet Rowe_600LCM.loadAtStartup 0 bool persist
2018-09-11T12:10:37.798Z,1536667837.798 [CommandLine](IMPORTANT): got command configSet Rowe_600LCM.loadAtStartup 0.000000 bool persist
2018-09-11T12:10:37.799Z,1536667837.799 [CommandLine](IMPORTANT): configSet Rowe_600LCM.loadAtStartup requires a restart to take effect.
2018-09-11T12:10:38.331Z,1536667838.331 [Rowe_600LCM](IMPORTANT): Stopping now due to load at startup. No immediate restart required.
2018-09-11T12:10:38.331Z,1536667838.331 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-09-11T12:10:38.561Z,1536667838.561 [Rowe_600LCM](INFO): Powering down
2018-09-11T12:15:03.364Z,1536668103.364 [NAL9602](INFO): GPS fix at 20180911T121401: (36.912112, -122.409714)
2018-09-11T12:15:03.433Z,1536668103.433 [Default:CheckIn:Read_GPS] Stopped
2018-09-11T12:15:03.434Z,1536668103.434 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-11T12:15:12.571Z,1536668112.571 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20180911T114025/Courier0018.lzma
2018-09-11T12:15:16.569Z,1536668116.569 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Courier0018.lzma.bak
2018-09-11T12:15:16.569Z,1536668116.569 [DataOverHttps](INFO): SBD MOMSN=8516140
2018-09-11T12:15:35.676Z,1536668135.676 [NAL9602](INFO): Powering down
2018-09-11T12:15:49.712Z,1536668149.712 [DataOverHttps](INFO): Sending 369 bytes from file Logs/20180911T114025/Express0019.lzma
2018-09-11T12:15:52.109Z,1536668152.109 [DataOverHttps](INFO): Moved sent file to Logs/20180911T114025/Express0019.lzma.bak
2018-09-11T12:15:52.109Z,1536668152.109 [DataOverHttps](INFO): SBD MOMSN=8516143
2018-09-11T12:15:52.803Z,1536668152.803 [Default:CheckIn:Read_Iridium] Stopped
2018-09-11T12:15:52.804Z,1536668152.804 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-11T12:15:52.804Z,1536668152.804 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-11T12:20:53.184Z,1536668453.184 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-11T12:20:53.184Z,1536668453.184 [Default:CheckIn:C.Wait] Stopped
2018-09-11T12:20:53.184Z,1536668453.184 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-11T12:20:53.184Z,1536668453.184 [Default:CheckIn:D] Running Loop=1
2018-09-11T12:20:53.584Z,1536668453.584 [Default:CheckIn:D] Stopped
2018-09-11T12:20:53.584Z,1536668453.584 [Default:CheckIn:E] Running Loop=1
2018-09-11T12:20:53.997Z,1536668453.997 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.704635 min 
2018-09-11T12:20:53.997Z,1536668453.997 [Default:CheckIn:E] Stopped
2018-09-11T12:20:53.997Z,1536668453.997 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-11T12:20:53.998Z,1536668453.998 [Default:CheckIn] Stopped
2018-09-11T12:20:53.998Z,1536668453.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-11T12:20:53.998Z,1536668453.998 [Default:CheckIn](INFO): Running loop #4
2018-09-11T12:20:53.998Z,1536668453.998 [Default:CheckIn] Running Loop=4
2018-09-11T12:20:53.998Z,1536668453.998 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-11T12:20:53.999Z,1536668453.999 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-11T12:20:54.740Z,1536668454.740 [NAL9602](INFO): Powering up
2018-09-11T12:21:05.572Z,1536668465.572 [NAL9602](INFO): NAL9602 initialized
2018-09-11T12:21:21.973Z,1536668481.973 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9800, MT Status=1, MTMSN=1596
2018-09-11T12:21:21.974Z,1536668481.974 [NAL9602](INFO): Data available in MT queue
2018-09-11T12:21:22.459Z,1536668482.459 [NAL9602](INFO): Received command:restart app
2018-09-11T12:21:22.519Z,1536668482.519 [CommandLine](IMPORTANT): got command restart application
2018-09-11T12:21:23.526Z,1536668483.526 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:23.526Z,1536668483.526 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:23.678Z,1536668483.678 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-11T12:21:23.679Z,1536668483.679 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:23.679Z,1536668483.679 [CommandLine](INFO): Join timeout helper Thread ID is 1199
2018-09-11T12:21:23.687Z,1536668483.687 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-11T12:21:23.687Z,1536668483.687 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:23.688Z,1536668483.688 [NavChartDb](INFO): Join timeout helper Thread ID is 1200
2018-09-11T12:21:23.862Z,1536668483.862 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:23.863Z,1536668483.863 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:23.874Z,1536668483.874 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-09-11T12:21:23.874Z,1536668483.874 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:23.875Z,1536668483.875 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1201
2018-09-11T12:21:24.163Z,1536668484.163 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:24.163Z,1536668484.163 [WetLabsBB2FL](INFO): Powering down
2018-09-11T12:21:24.164Z,1536668484.164 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:24.166Z,1536668484.166 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-09-11T12:21:24.167Z,1536668484.167 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:24.167Z,1536668484.167 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1203
2018-09-11T12:21:24.435Z,1536668484.435 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:24.574Z,1536668484.574 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-09-11T12:21:24.574Z,1536668484.574 [CTD_Seabird](INFO): Powering down
2018-09-11T12:21:24.575Z,1536668484.575 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:24.582Z,1536668484.582 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-09-11T12:21:24.583Z,1536668484.583 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:24.583Z,1536668484.583 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1205
2018-09-11T12:21:24.975Z,1536668484.975 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:24.975Z,1536668484.975 [CTD_NeilBrown](INFO): Powering down
2018-09-11T12:21:24.976Z,1536668484.976 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:24.995Z,1536668484.995 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-09-11T12:21:24.995Z,1536668484.995 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:24.996Z,1536668484.996 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1208
2018-09-11T12:21:25.287Z,1536668485.287 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:25.287Z,1536668485.287 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-09-11T12:21:25.380Z,1536668485.380 [Rowe_600LCM](INFO): Powering down
2018-09-11T12:21:25.381Z,1536668485.381 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.395Z,1536668485.395 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-11T12:21:25.395Z,1536668485.395 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.396Z,1536668485.396 [Radio_Surface](INFO): Join timeout helper Thread ID is 1210
2018-09-11T12:21:25.707Z,1536668485.707 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:25.707Z,1536668485.707 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.716Z,1536668485.716 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-11T12:21:25.716Z,1536668485.716 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.716Z,1536668485.716 [logger](INFO): Join timeout helper Thread ID is 1211
2018-09-11T12:21:25.738Z,1536668485.738 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:25.739Z,1536668485.739 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.755Z,1536668485.755 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-11T12:21:25.755Z,1536668485.755 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.755Z,1536668485.755 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-11T12:21:25.755Z,1536668485.755 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:25.756Z,1536668485.756 [controlThread](INFO): Join timeout helper Thread ID is 1212
2018-09-11T12:21:25.962Z,1536668485.962 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-11T12:21:25.963Z,1536668485.963 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-11T12:21:25.964Z,1536668485.964 [NAL9602](INFO): Powering down
2018-09-11T12:21:25.965Z,1536668485.965 [PNI_TCM](INFO): Powering down
2018-09-11T12:21:26.055Z,1536668486.055 [Aanderaa_O2](INFO): Powering down
2018-09-11T12:21:26.057Z,1536668486.057 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-11T12:21:26.058Z,1536668486.058 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-11T12:21:26.059Z,1536668486.059 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-11T12:21:26.059Z,1536668486.059 [MissionManager](INFO): Uninitializing Mission Default
2018-09-11T12:21:26.059Z,1536668486.059 [Default] Stopped
2018-09-11T12:21:26.059Z,1536668486.059 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-11T12:21:26.060Z,1536668486.060 [Default:B.GoToSurface] Stopped
2018-09-11T12:21:26.060Z,1536668486.060 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-11T12:21:26.060Z,1536668486.060 [Default:CheckIn] Stopped
2018-09-11T12:21:26.060Z,1536668486.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-11T12:21:26.060Z,1536668486.060 [Default:CheckIn:Read_GPS] Stopped
2018-09-11T12:21:26.064Z,1536668486.064 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-11T12:21:26.064Z,1536668486.064 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-11T12:21:26.065Z,1536668486.065 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-11T12:21:26.065Z,1536668486.065 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-11T12:21:26.065Z,1536668486.065 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-11T12:21:26.066Z,1536668486.066 [BuoyancyServo](INFO): Powering down
2018-09-11T12:21:26.078Z,1536668486.078 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-11T12:21:26.079Z,1536668486.079 [ThrusterServo](INFO): Powering down
2018-09-11T12:21:26.080Z,1536668486.080 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-11T12:21:26.080Z,1536668486.080 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-11T12:21:26.080Z,1536668486.080 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-11T12:21:26.081Z,1536668486.081 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.196Z,1536668486.196 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.203Z,1536668486.203 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.240Z,1536668486.240 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.245Z,1536668486.245 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.248Z,1536668486.248 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.275Z,1536668486.275 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-11T12:21:26.338Z,1536668486.338 [logger ThreadHandler](INFO): Thread cancelled.