2018-09-20T19:40:20.100Z,1537472420.100 [DataOverHttps](INFO): Received command:restart logs
2018-09-20T19:40:20.121Z,1537472420.121 [CommandLine](IMPORTANT): got command restart logs
2018-09-20T19:40:25.690Z,1537472425.690 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T19:40:25.691Z,1537472425.691 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,
2018-09-20T19:41:21.451Z,1537472481.451 [DataOverHttps](IMPORTANT): SBD MTMSN=20180920T194121
2018-09-20T19:41:25.692Z,1537472485.692 [DataOverHttps](INFO): Received command:load Science/profile_station.xml;set profile_station.NeedCommsTime 20 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 20 meter;run 
2018-09-20T19:41:25.731Z,1537472485.731 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2018-09-20T19:41:25.732Z,1537472485.732 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2018-09-20T19:41:25.852Z,1537472485.852 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2018-09-20T19:41:25.855Z,1537472485.855 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2018-09-20T19:41:25.857Z,1537472485.857 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2018-09-20T19:41:25.859Z,1537472485.859 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2018-09-20T19:41:25.862Z,1537472485.862 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2018-09-20T19:41:25.864Z,1537472485.864 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2018-09-20T19:41:25.866Z,1537472485.866 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2018-09-20T19:41:25.868Z,1537472485.868 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2018-09-20T19:41:25.872Z,1537472485.872 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2018-09-20T19:41:25.877Z,1537472485.877 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2018-09-20T19:41:25.911Z,1537472485.911 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2018-09-20T19:41:25.913Z,1537472485.913 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2018-09-20T19:41:25.920Z,1537472485.920 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2018-09-20T19:41:25.939Z,1537472485.939 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2018-09-20T19:41:25.941Z,1537472485.941 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2018-09-20T19:41:25.943Z,1537472485.943 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2018-09-20T19:41:25.944Z,1537472485.944 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-09-20T19:41:26.218Z,1537472486.218 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2018-09-20T19:41:26.220Z,1537472486.220 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-09-20T19:41:26.222Z,1537472486.222 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-09-20T19:41:26.224Z,1537472486.224 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2018-09-20T19:41:26.226Z,1537472486.226 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2018-09-20T19:41:26.228Z,1537472486.228 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2018-09-20T19:41:26.230Z,1537472486.230 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2018-09-20T19:41:26.232Z,1537472486.232 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2018-09-20T19:41:26.234Z,1537472486.234 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2018-09-20T19:41:26.236Z,1537472486.236 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2018-09-20T19:41:26.238Z,1537472486.238 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-09-20T19:41:26.241Z,1537472486.241 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 1.000000 bool
2018-09-20T19:41:26.243Z,1537472486.243 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2018-09-20T19:41:26.245Z,1537472486.245 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 0.000000 bool
2018-09-20T19:41:26.248Z,1537472486.248 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-09-20T19:41:26.250Z,1537472486.250 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 0.000000 bool
2018-09-20T19:41:26.253Z,1537472486.253 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-09-20T19:41:26.255Z,1537472486.255 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2018-09-20T19:41:26.294Z,1537472486.294 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2018-09-20T19:41:26.298Z,1537472486.298 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2018-09-20T19:41:26.319Z,1537472486.319 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2018-09-20T19:41:26.321Z,1537472486.321 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2018-09-20T19:41:26.324Z,1537472486.324 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2018-09-20T19:41:26.326Z,1537472486.326 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2018-09-20T19:41:26.328Z,1537472486.328 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2018-09-20T19:41:26.347Z,1537472486.347 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2018-09-20T19:41:26.360Z,1537472486.360 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2018-09-20T19:41:26.380Z,1537472486.380 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2018-09-20T19:41:26.382Z,1537472486.382 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2018-09-20T19:41:26.384Z,1537472486.384 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2018-09-20T19:41:26.387Z,1537472486.387 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2018-09-20T19:41:26.389Z,1537472486.389 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2018-09-20T19:41:26.411Z,1537472486.411 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2018-09-20T19:41:26.414Z,1537472486.414 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2018-09-20T19:41:26.416Z,1537472486.416 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2018-09-20T19:41:26.442Z,1537472486.442 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2018-09-20T19:41:26.445Z,1537472486.445 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2018-09-20T19:41:26.459Z,1537472486.459 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2018-09-20T19:41:26.475Z,1537472486.475 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2018-09-20T19:41:26.552Z,1537472486.552 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-20T19:41:26.613Z,1537472486.613 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-09-20T19:41:26.669Z,1537472486.669 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-20T19:41:26.688Z,1537472486.688 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-20T19:41:26.720Z,1537472486.720 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2018-09-20T19:41:26.721Z,1537472486.721 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2018-09-20T19:41:26.722Z,1537472486.722 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-09-20T19:41:26.839Z,1537472486.839 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2018-09-20T19:41:26.869Z,1537472486.869 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2018-09-20T19:41:26.872Z,1537472486.872 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-09-20T19:41:26.874Z,1537472486.874 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2018-09-20T19:41:26.876Z,1537472486.876 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-09-20T19:41:26.911Z,1537472486.911 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2018-09-20T19:41:26.913Z,1537472486.913 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2018-09-20T19:41:26.926Z,1537472486.926 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-20T19:41:26.974Z,1537472486.974 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-09-20T19:41:27.008Z,1537472487.008 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2018-09-20T19:41:27.032Z,1537472487.032 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2018-09-20T19:41:27.035Z,1537472487.035 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-09-20T19:41:27.036Z,1537472487.036 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-20T19:41:27.065Z,1537472487.065 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-20T19:41:27.085Z,1537472487.085 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-09-20T19:41:27.111Z,1537472487.111 [profile_station:I.Pitch](DEBUG): Construct.
2018-09-20T19:41:27.116Z,1537472487.116 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-09-20T19:41:27.119Z,1537472487.119 [profile_station:K.SetSpeed](DEBUG): Construct.
2018-09-20T19:41:27.121Z,1537472487.121 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-20T19:41:27.158Z,1537472487.158 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-20T19:41:27.174Z,1537472487.174 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2018-09-20T19:41:27.182Z,1537472487.182 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2018-09-20T19:41:27.208Z,1537472487.208 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Estimation="Tethys/Estimation"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Sensor="Tethys/Sensor"
       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/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

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

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

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

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <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:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

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

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

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

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

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

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

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

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

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="CircleWrapper">

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2018-09-20T19:41:27.209Z,1537472487.209 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2018-09-20T19:41:34.105Z,1537472494.105 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 20.000000 minute
2018-09-20T19:41:34.106Z,1537472494.106 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2018-09-20T19:41:34.107Z,1537472494.107 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2018-09-20T19:41:34.107Z,1537472494.107 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 20.000000 meter
2018-09-20T19:41:34.108Z,1537472494.108 [CommandLine](IMPORTANT): got command run 
2018-09-20T19:41:34.110Z,1537472494.110 [CommandLine](IMPORTANT): Running 
2018-09-20T19:41:34.371Z,1537472494.371 [Default] Stopped
2018-09-20T19:41:34.372Z,1537472494.372 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-20T19:41:34.372Z,1537472494.372 [Default:B.GoToSurface] Stopped
2018-09-20T19:41:34.372Z,1537472494.372 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T19:41:34.372Z,1537472494.372 [Default:CheckIn] Stopped
2018-09-20T19:41:34.372Z,1537472494.372 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-20T19:41:34.372Z,1537472494.372 [Default:CheckIn:Read_GPS] Stopped
2018-09-20T19:41:34.372Z,1537472494.372 [MissionManager](IMPORTANT): Started mission profile_station
2018-09-20T19:41:34.372Z,1537472494.372 [profile_station] Running Loop=1
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station](DEBUG): Aggregate::initialize profile_station
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science] Running Loop=1
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science:A] Running Loop=1
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science:B] Running Loop=1
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science:C] Running Loop=1
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science:D] Running Loop=1
2018-09-20T19:41:34.373Z,1537472494.373 [profile_station:Science:E] Running Loop=1
2018-09-20T19:41:34.389Z,1537472494.389 [profile_station:Science:F] Running Loop=1
2018-09-20T19:41:34.390Z,1537472494.390 [profile_station:Science:G] Running Loop=1
2018-09-20T19:41:34.390Z,1537472494.390 [profile_station:Science:Read_Oil] Running Loop=1
2018-09-20T19:41:34.390Z,1537472494.390 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-09-20T19:41:34.390Z,1537472494.390 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2018-09-20T19:41:34.390Z,1537472494.390 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-09-20T19:41:34.390Z,1537472494.390 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-20T19:41:34.391Z,1537472494.391 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2018-09-20T19:41:34.391Z,1537472494.391 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2018-09-20T19:41:34.392Z,1537472494.392 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-09-20T19:41:34.392Z,1537472494.392 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-09-20T19:41:34.392Z,1537472494.392 [profile_station:Science:PeakDetectNO3] Running Loop=1
2018-09-20T19:41:34.392Z,1537472494.392 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2018-09-20T19:41:34.392Z,1537472494.392 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-09-20T19:41:34.393Z,1537472494.393 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-20T19:41:34.398Z,1537472494.398 [profile_station:Science:PeakDetectOil] Running Loop=1
2018-09-20T19:41:34.398Z,1537472494.398 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2018-09-20T19:41:34.398Z,1537472494.398 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-09-20T19:41:34.398Z,1537472494.398 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-20T19:41:34.399Z,1537472494.399 [profile_station:StandardEnvelopes] Running Loop=1
2018-09-20T19:41:34.399Z,1537472494.399 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2018-09-20T19:41:34.399Z,1537472494.399 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-20T19:41:34.399Z,1537472494.399 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:I.Pitch] Running Loop=1
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:I.Pitch](DEBUG): Initialize.
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:J.Buoyancy] Running Loop=1
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-09-20T19:41:34.400Z,1537472494.400 [profile_station:K.SetSpeed] Running Loop=1
2018-09-20T19:41:34.401Z,1537472494.401 [profile_station:K.SetSpeed](DEBUG): Initialize.
2018-09-20T19:41:34.401Z,1537472494.401 [profile_station:L.DepthEnvelope] Running Loop=1
2018-09-20T19:41:34.401Z,1537472494.401 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-20T19:41:34.401Z,1537472494.401 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-09-20T19:41:34.401Z,1537472494.401 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-20T19:41:34.405Z,1537472494.405 [profile_station:N.YoYo] Running Loop=1
2018-09-20T19:41:34.406Z,1537472494.406 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-09-20T19:41:34.406Z,1537472494.406 [profile_station:C] Running Loop=1
2018-09-20T19:41:34.406Z,1537472494.406 [profile_station:N.YoYo] Running Loop=1
2018-09-20T19:41:34.408Z,1537472494.408 [profile_station:M.AltitudeEnvelope] Running Loop=1
2018-09-20T19:41:34.409Z,1537472494.409 [profile_station:L.DepthEnvelope] Running Loop=1
2018-09-20T19:41:34.410Z,1537472494.410 [profile_station:K.SetSpeed] Running Loop=1
2018-09-20T19:41:34.411Z,1537472494.411 [profile_station:J.Buoyancy] Running Loop=1
2018-09-20T19:41:34.412Z,1537472494.412 [profile_station:I.Pitch] Running Loop=1
2018-09-20T19:41:34.413Z,1537472494.413 [profile_station:StandardEnvelopes] Running Loop=1
2018-09-20T19:41:34.414Z,1537472494.414 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-20T19:41:34.434Z,1537472494.434 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-20T19:41:34.435Z,1537472494.435 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-20T19:41:34.437Z,1537472494.437 [profile_station:C] Stopped
2018-09-20T19:41:34.438Z,1537472494.438 [profile_station:E] Running Loop=1
2018-09-20T19:41:34.438Z,1537472494.438 [profile_station:Science] Running Loop=1
2018-09-20T19:41:34.440Z,1537472494.440 [profile_station:Science:PeakDetectOil] Stopped
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectNO3] Stopped
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-20T19:41:34.441Z,1537472494.441 [profile_station:Science:HighestChlPeakReport] Stopped
2018-09-20T19:41:34.462Z,1537472494.462 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2018-09-20T19:41:34.462Z,1537472494.462 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-09-20T19:41:34.462Z,1537472494.462 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-09-20T19:41:34.462Z,1537472494.462 [profile_station:Science:PeakDetectChl] Running Loop=1
2018-09-20T19:41:34.463Z,1537472494.463 [profile_station:Science:Read_Oil] Running Loop=1
2018-09-20T19:41:34.463Z,1537472494.463 [profile_station:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-09-20T19:41:34.464Z,1537472494.464 [profile_station:Science:G] Running Loop=1
2018-09-20T19:41:34.465Z,1537472494.465 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:F] Stopped
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:E] Stopped
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:D] Stopped
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:C] Stopped
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:B] Stopped
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:A] Running Loop=1
2018-09-20T19:41:34.466Z,1537472494.466 [profile_station:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-09-20T19:41:34.550Z,1537472494.550 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2018-09-20T19:41:34.550Z,1537472494.550 [WetLabsSeaOWL_UV_A](INFO): Powering up
2018-09-20T19:41:34.650Z,1537472494.650 [profile_station:E] Stopped
2018-09-20T19:41:34.650Z,1537472494.650 [profile_station:F] Running Loop=1
2018-09-20T19:41:35.086Z,1537472495.086 [profile_station:F] Stopped
2018-09-20T19:41:35.086Z,1537472495.086 [profile_station:G] Running Loop=1
2018-09-20T19:41:35.442Z,1537472495.442 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-09-20T19:41:35.442Z,1537472495.442 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-09-20T19:41:35.442Z,1537472495.442 [ThrusterServo] Communications Fault, FailCount= 2
2018-09-20T19:41:35.442Z,1537472495.442 [ThrusterServo](ERROR): Communications Fault
2018-09-20T19:41:35.446Z,1537472495.446 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-09-20T19:41:35.650Z,1537472495.650 [profile_station:G] Stopped
2018-09-20T19:41:35.650Z,1537472495.650 [profile_station:StartingMission] Running Loop=1
2018-09-20T19:41:35.650Z,1537472495.650 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2018-09-20T19:41:35.810Z,1537472495.810 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-20T19:41:35.810Z,1537472495.810 [ThrusterServo](INFO): Powering down
2018-09-20T19:41:35.962Z,1537472495.962 [profile_station:NeedComms] Running Loop=1
2018-09-20T19:41:35.963Z,1537472495.963 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-20T19:41:35.963Z,1537472495.963 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-20T19:41:35.963Z,1537472495.963 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T19:41:35.963Z,1537472495.963 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-20T19:41:35.964Z,1537472495.964 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-20T19:41:35.964Z,1537472495.964 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-20T19:41:35.964Z,1537472495.964 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T19:41:35.965Z,1537472495.965 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T19:41:35.965Z,1537472495.965 [profile_station:NeedComms:A] Running Loop=1
2018-09-20T19:41:35.967Z,1537472495.967 [profile_station:NeedComms:A](INFO): last time_fix was: 1537471890.000000 second since 1970/01/01T00:00:00Z 
2018-09-20T19:41:35.968Z,1537472495.968 [profile_station:NeedComms:A] Stopped
2018-09-20T19:41:36.400Z,1537472496.400 [profile_station:NeedComms:C] Running Loop=1
2018-09-20T19:41:36.803Z,1537472496.803 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-20T19:41:36.803Z,1537472496.803 [ThrusterServo] No Fault, FailCount= 2
2018-09-20T19:41:36.911Z,1537472496.911 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-20T19:41:36.995Z,1537472496.995 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-20T19:41:36.995Z,1537472496.995 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-20T19:41:42.412Z,1537472502.412 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		318.929		102.072		15.367		30.591		30.278		38.648		8.370		381.9		558.6		284.6	



2018-09-20T19:41:42.412Z,1537472502.412 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T19:41:43.212Z,1537472503.212 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		319.152		102.141		15.366		30.583		30.270		38.635		8.365		381.9		558.1		284.6	



2018-09-20T19:41:43.212Z,1537472503.212 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T19:41:44.412Z,1537472504.412 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		318.743		101.977		15.351		30.605		30.292		38.653		8.361		382.1		557.7		285.1	

2018-09-20T19:41:44.412Z,1537472504.412 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T19:41:46.412Z,1537472506.412 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		318.946		102.012		15.338		30.603		30.290		38.646		8.356		381.9		557.2		285.5	

2018-09-20T19:41:46.412Z,1537472506.412 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T19:42:21.680Z,1537472541.680 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T19:42:21.680Z,1537472541.680 [RDI_Pathfinder](ERROR): Failed to parse:
:TS2480437,35.0, -0.1,   0.0,1448.9,  0
2018-09-20T19:43:20.367Z,1537472600.367 [NAL9602](INFO): GPS fix at 20180920T194319: (36.797773, -121.812576)
2018-09-20T19:43:20.447Z,1537472600.447 [profile_station:NeedComms:C] Stopped
2018-09-20T19:43:20.447Z,1537472600.447 [profile_station:NeedComms:D] Running Loop=1
2018-09-20T19:43:20.862Z,1537472600.862 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-20T19:43:25.613Z,1537472605.613 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T182838/Courier0031.lzma
2018-09-20T19:43:26.391Z,1537472606.391 [DataOverHttps](INFO): Moved sent file to Logs/20180920T182838/Courier0031.lzma.bak
2018-09-20T19:43:26.391Z,1537472606.391 [DataOverHttps](INFO): SBD MOMSN=8548861
2018-09-20T19:43:35.936Z,1537472615.936 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180920T194020/Courier0000.lzma
2018-09-20T19:43:36.718Z,1537472616.718 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0000.lzma.bak
2018-09-20T19:43:36.718Z,1537472616.718 [DataOverHttps](INFO): SBD MOMSN=8548863
2018-09-20T19:43:46.658Z,1537472626.658 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Courier0003.lzma
2018-09-20T19:43:47.435Z,1537472627.435 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0003.lzma.bak
2018-09-20T19:43:47.435Z,1537472627.435 [DataOverHttps](INFO): SBD MOMSN=8548867
2018-09-20T19:43:58.087Z,1537472638.087 [NAL9602](INFO): Powering down
2018-09-20T19:43:58.351Z,1537472638.351 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20180920T182838/Express0032.lzma
2018-09-20T19:43:59.131Z,1537472639.131 [DataOverHttps](INFO): Moved sent file to Logs/20180920T182838/Express0032.lzma.bak
2018-09-20T19:43:59.131Z,1537472639.131 [DataOverHttps](INFO): SBD MOMSN=8548869
2018-09-20T19:44:08.697Z,1537472648.697 [DataOverHttps](INFO): Sending 574 bytes from file Logs/20180920T194020/Express0001.lzma
2018-09-20T19:44:09.502Z,1537472649.502 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0001.lzma.bak
2018-09-20T19:44:09.502Z,1537472649.502 [DataOverHttps](INFO): SBD MOMSN=8548873
2018-09-20T19:44:19.095Z,1537472659.095 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Express0004.lzma
2018-09-20T19:44:19.871Z,1537472659.871 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0004.lzma.bak
2018-09-20T19:44:19.871Z,1537472659.871 [DataOverHttps](INFO): SBD MOMSN=8548888
2018-09-20T19:44:20.361Z,1537472660.361 [profile_station:NeedComms:D] Stopped
2018-09-20T19:44:20.361Z,1537472660.361 [profile_station:NeedComms:E] Running Loop=1
2018-09-20T19:44:20.789Z,1537472660.789 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-20T19:44:24.006Z,1537472664.006 [NAL9602](INFO): Powering up
2018-09-20T19:44:35.083Z,1537472675.083 [NAL9602](INFO): NAL9602 initialized
2018-09-20T19:44:47.895Z,1537472687.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=874, MT Status=0, MTMSN=0
2018-09-20T19:44:47.896Z,1537472687.896 [NAL9602](INFO): No messages in MT queue
2018-09-20T19:45:11.111Z,1537472711.111 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T19:45:11.235Z,1537472711.235 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T19:46:27.763Z,1537472787.763 [WetLabsSeaOWL_UV_A](DEBUG): Error parsing data: 	3277	3277	76	307	307

2018-09-20T19:46:34.707Z,1537472794.707 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8c,0ffd, 14.792, -0.085, 0.000
2018-09-20T19:46:50.262Z,1537472810.262 [NAL9602](INFO): GPS fix at 20180920T194650: (36.797522, -121.812966)
2018-09-20T19:46:50.340Z,1537472810.340 [profile_station:NeedComms:E] Stopped
2018-09-20T19:46:50.346Z,1537472810.346 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-20T19:46:50.346Z,1537472810.346 [profile_station:NeedComms] Stopped
2018-09-20T19:46:50.346Z,1537472810.346 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-20T19:46:50.346Z,1537472810.346 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-20T19:46:50.346Z,1537472810.346 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T19:46:50.764Z,1537472810.764 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission
2018-09-20T19:46:50.765Z,1537472810.765 [profile_station:StartingMission] Stopped
2018-09-20T19:46:50.765Z,1537472810.765 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2018-09-20T19:46:50.765Z,1537472810.765 [profile_station:CircleWrapper] Running Loop=1
2018-09-20T19:46:50.765Z,1537472810.765 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2018-09-20T19:46:50.765Z,1537472810.765 [profile_station:CircleWrapper:A] Running Loop=1
2018-09-20T19:46:50.765Z,1537472810.765 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2018-09-20T19:46:50.769Z,1537472810.769 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2018-09-20T19:46:51.123Z,1537472811.123 [profile_station:CircleWrapper:A] Running Loop=1
2018-09-20T19:46:54.697Z,1537472814.697 [RDI_Pathfinder](ERROR): Failed to parse:
:B        +0.00,        +0.00,   0.00,1241.93
2018-09-20T19:47:04.414Z,1537472824.414 [BuoyancyServo](FAULT): Overload Error
2018-09-20T19:47:04.414Z,1537472824.414 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-20T19:47:04.414Z,1537472824.414 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T19:47:04.570Z,1537472824.570 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-09-20T19:47:04.775Z,1537472824.775 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T19:47:04.776Z,1537472824.776 [BuoyancyServo](INFO): Powering down
2018-09-20T19:47:06.888Z,1537472826.888 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T19:47:06.888Z,1537472826.888 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T19:47:07.164Z,1537472827.164 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T19:47:07.303Z,1537472827.303 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T19:47:11.257Z,1537472831.257 [Radio_Surface](INFO): Powering down
2018-09-20T19:47:15.123Z,1537472835.123 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position -1.00 mm (1 active estimators).
2018-09-20T19:47:16.325Z,1537472836.325 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-20T19:47:16.330Z,1537472836.330 [RDI_Pathfinder](ERROR): Failed to parse:
:HMc,0ffd, 14.658, -0.095, 0.000
2018-09-20T19:47:22.271Z,1537472842.271 [NAL9602](INFO): Powering down
2018-09-20T19:47:24.689Z,1537472844.689 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T19:47:24.689Z,1537472844.689 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092012530737,31,   0.0,1448.9,  0
2018-09-20T19:47:32.705Z,1537472852.705 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T19:47:32.709Z,1537472852.709 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-3268,-32768,V
2018-09-20T19:47:35.908Z,1537472855.908 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position -1.00 mm (2 active estimators).
2018-09-20T19:47:51.361Z,1537472871.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:48:51.366Z,1537472931.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:49:26.160Z,1537472966.160 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.305634
2018-09-20T19:50:52.162Z,1537473052.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:51:52.174Z,1537473112.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:52:52.178Z,1537473172.178 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:53:52.181Z,1537473232.181 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:54:52.182Z,1537473292.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:55:52.580Z,1537473352.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:56:13.107Z,1537473373.107 [Reporter](INFO): platform_battery_voltage 16.041803 V
2018-09-20T19:56:53.373Z,1537473413.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:57:21.016Z,1537473441.016 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-20T19:57:53.382Z,1537473473.382 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:58:53.774Z,1537473533.774 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T19:59:53.859Z,1537473593.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:00:22.320Z,1537473622.320 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:00:22.320Z,1537473622.320 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092013060537,35.0, -0.1,   0.0,1448:BI,-32768,-32768,-32768,-32768,V
2018-09-20T20:00:54.275Z,1537473654.275 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:01:12.688Z,1537473672.688 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:01:12.688Z,1537473672.688 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-3268,-32768,-32768,V
2018-09-20T20:01:54.269Z,1537473714.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:02:24.321Z,1537473744.321 [RDI_Pathfinder](ERROR): Failed to parse:
  +0.00,        +0.00,   0.00,2171.80
2018-09-20T20:02:41.925Z,1537473761.925 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T20:02:42.071Z,1537473762.071 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T20:02:54.273Z,1537473774.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:03:52.706Z,1537473832.706 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-20T20:03:52.706Z,1537473832.706 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.0  +0.00,   0.00,2259.78
2018-09-20T20:03:54.290Z,1537473834.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:04:52.323Z,1537473892.323 [RDI_Pathfinder](ERROR): Unknown system health message
2018-09-20T20:04:52.323Z,1537473892.323 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2018-09-20T20:04:52.324Z,1537473892.324 [RDI_Pathfinder](ERROR): Hardware Fault
2018-09-20T20:04:52.514Z,1537473892.514 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2018-09-20T20:04:52.742Z,1537473892.742 [RDI_Pathfinder](INFO): Powering down
2018-09-20T20:04:53.638Z,1537473893.638 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-09-20T20:04:53.638Z,1537473893.638 [RDI_Pathfinder] No Fault, FailCount= 0
2018-09-20T20:04:55.070Z,1537473895.070 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:05:07.098Z,1537473907.098 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-20T20:05:55.070Z,1537473955.070 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:06:50.340Z,1537474010.340 [profile_station:NeedComms] Running Loop=1
2018-09-20T20:06:50.341Z,1537474010.341 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-20T20:06:50.341Z,1537474010.341 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-20T20:06:50.341Z,1537474010.341 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T20:06:50.341Z,1537474010.341 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-20T20:06:50.346Z,1537474010.346 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-20T20:06:50.346Z,1537474010.346 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-20T20:06:50.347Z,1537474010.347 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T20:06:50.347Z,1537474010.347 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T20:06:50.347Z,1537474010.347 [profile_station:NeedComms:A] Running Loop=1
2018-09-20T20:06:50.349Z,1537474010.349 [profile_station:NeedComms:A](INFO): last time_fix was: 1537472810.000000 second since 1970/01/01T00:00:00Z 
2018-09-20T20:06:50.354Z,1537474010.354 [profile_station:NeedComms:A] Stopped
2018-09-20T20:06:55.479Z,1537474015.479 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:07:01.467Z,1537474021.467 [profile_station:NeedComms:C] Running Loop=1
2018-09-20T20:07:01.827Z,1537474021.827 [Radio_Surface](INFO): Powering up
2018-09-20T20:07:02.192Z,1537474022.192 [NAL9602](INFO): Powering up
2018-09-20T20:07:04.186Z,1537474024.186 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T20:07:04.186Z,1537474024.186 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T20:07:04.186Z,1537474024.186 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-20T20:07:04.186Z,1537474024.186 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T20:07:04.296Z,1537474024.296 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T20:07:04.496Z,1537474024.496 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T20:07:04.497Z,1537474024.497 [BuoyancyServo](INFO): Powering down
2018-09-20T20:07:06.507Z,1537474026.507 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T20:07:06.508Z,1537474026.508 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T20:07:06.901Z,1537474026.901 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T20:07:07.019Z,1537474027.019 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T20:07:11.578Z,1537474031.578 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T20:07:11.578Z,1537474031.578 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T20:07:11.578Z,1537474031.578 [BuoyancyServo] Communications Fault, FailCount= 2
2018-09-20T20:07:11.578Z,1537474031.578 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T20:07:11.646Z,1537474031.646 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T20:07:11.794Z,1537474031.794 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T20:07:11.796Z,1537474031.796 [BuoyancyServo](INFO): Powering down
2018-09-20T20:07:13.267Z,1537474033.267 [NAL9602](INFO): NAL9602 initialized
2018-09-20T20:07:13.355Z,1537474033.355 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-20T20:07:13.355Z,1537474033.355 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-09-20T20:07:13.355Z,1537474033.355 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T20:07:13.778Z,1537474033.778 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T20:07:13.778Z,1537474033.778 [BuoyancyServo] No Fault, FailCount= 2
2018-09-20T20:07:14.178Z,1537474034.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T20:07:14.299Z,1537474034.299 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T20:07:19.278Z,1537474039.278 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T20:07:19.278Z,1537474039.278 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T20:07:19.278Z,1537474039.278 [BuoyancyServo] Communications Fault, FailCount= 3
2018-09-20T20:07:19.278Z,1537474039.278 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T20:07:19.354Z,1537474039.354 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T20:07:19.468Z,1537474039.468 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T20:07:19.469Z,1537474039.469 [BuoyancyServo](INFO): Powering down
2018-09-20T20:07:21.074Z,1537474041.074 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-20T20:07:21.074Z,1537474041.074 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-09-20T20:07:21.074Z,1537474041.074 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T20:07:21.491Z,1537474041.491 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T20:07:21.491Z,1537474041.491 [BuoyancyServo] No Fault, FailCount= 3
2018-09-20T20:07:21.908Z,1537474041.908 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T20:07:22.026Z,1537474042.026 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T20:07:36.988Z,1537474056.988 [NAL9602](INFO): SBD MO Status=2, MOMSN=875, MT Status=2, MTMSN=0
2018-09-20T20:07:36.988Z,1537474056.988 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-20T20:07:55.374Z,1537474075.374 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:07:58.578Z,1537474078.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=875, MT Status=2, MTMSN=0
2018-09-20T20:07:58.579Z,1537474078.579 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-20T20:08:07.809Z,1537474087.809 [NAL9602](INFO): SBD MO Status=0, MOMSN=875, MT Status=0, MTMSN=0
2018-09-20T20:08:07.810Z,1537474087.810 [NAL9602](INFO): No messages in MT queue
2018-09-20T20:08:55.377Z,1537474135.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:09:56.173Z,1537474196.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:10:31.090Z,1537474231.090 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003047
2018-09-20T20:13:26.691Z,1537474406.691 [Radio_Surface](INFO): Powering down
2018-09-20T20:13:32.751Z,1537474412.751 [Radio_Surface](INFO): Powering up
2018-09-20T20:13:44.031Z,1537474424.031 [Reporter](INFO): platform_battery_voltage 16.032804 V
2018-09-20T20:13:57.018Z,1537474437.018 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:14:01.488Z,1537474441.488 [profile_station:NeedComms:C](INFO): Timed out from 2018-09-20T20:07:01.5Z
2018-09-20T20:14:01.488Z,1537474441.488 [profile_station:NeedComms:C:A_Timeout] Running Loop=1
2018-09-20T20:14:01.488Z,1537474441.488 [profile_station:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize profile_station:NeedComms:C:A_Timeout
2018-09-20T20:14:01.489Z,1537474441.489 [profile_station:NeedComms:C:A_Timeout:A] Running Loop=1
2018-09-20T20:14:01.489Z,1537474441.489 [profile_station:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout
2018-09-20T20:14:01.494Z,1537474441.494 [profile_station:NeedComms:C:A_Timeout:A] Stopped
2018-09-20T20:14:01.495Z,1537474441.495 [profile_station:NeedComms:C:A_Timeout](INFO): Completed profile_station:NeedComms:C:A_Timeout
2018-09-20T20:14:01.495Z,1537474441.495 [profile_station:NeedComms:C] Stopped
2018-09-20T20:14:01.495Z,1537474441.495 [profile_station:NeedComms:D] Running Loop=1
2018-09-20T20:14:05.510Z,1537474445.510 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:14:05.511Z,1537474445.511 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-2768,-32768,V
2018-09-20T20:14:32.674Z,1537474472.674 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:14:32.683Z,1537474472.683 [NAL9602](INFO): Powering down
2018-09-20T20:15:03.082Z,1537474503.082 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:15:31.485Z,1537474531.485 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.066345
2018-09-20T20:15:31.488Z,1537474531.488 [NAL9602](INFO): Powering up
2018-09-20T20:15:42.276Z,1537474542.276 [NAL9602](INFO): NAL9602 initialized
2018-09-20T20:15:57.341Z,1537474557.341 [NAL9602](INFO): SBD MO Status=1, MOMSN=876, MT Status=0, MTMSN=0
2018-09-20T20:15:57.398Z,1537474557.398 [NAL9602](INFO): Sent 224 bytes from file Logs/20180920T194020/Courier0006.lzma
2018-09-20T20:15:57.398Z,1537474557.398 [NAL9602](INFO): Packets left to send: 0
2018-09-20T20:15:57.403Z,1537474557.403 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180920T194020/Courier0006.lzma.parts/0000.sbd
2018-09-20T20:15:57.403Z,1537474557.403 [NAL9602](DEBUG): Completed sending Logs/20180920T194020/Courier0006.lzma
2018-09-20T20:16:01.980Z,1537474561.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:16:24.391Z,1537474584.391 [NAL9602](INFO): SBD MO Status=1, MOMSN=877, MT Status=0, MTMSN=0
2018-09-20T20:16:24.438Z,1537474584.438 [NAL9602](INFO): Sent 332 bytes from file Logs/20180920T194020/Express0007.lzma
2018-09-20T20:16:24.438Z,1537474584.438 [NAL9602](INFO): Packets left to send: 1
2018-09-20T20:16:24.440Z,1537474584.440 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180920T194020/Express0007.lzma.parts/0001.sbd
2018-09-20T20:16:48.983Z,1537474608.983 [NAL9602](INFO): SBD MO Status=2, MOMSN=878, MT Status=2, MTMSN=0
2018-09-20T20:16:48.983Z,1537474608.983 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-20T20:16:59.956Z,1537474619.956 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003729
2018-09-20T20:17:04.266Z,1537474624.266 [DataOverHttps](INFO): Sending 502 bytes from file Logs/20180920T194020/Express0007.lzma
2018-09-20T20:17:05.030Z,1537474625.030 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0007.lzma.bak
2018-09-20T20:17:05.030Z,1537474625.030 [DataOverHttps](INFO): SBD MOMSN=8549020
2018-09-20T20:17:05.517Z,1537474625.517 [profile_station:NeedComms:D] Stopped
2018-09-20T20:17:05.526Z,1537474625.526 [profile_station:NeedComms:E] Running Loop=1
2018-09-20T20:17:11.718Z,1537474631.718 [NAL9602](INFO): SBD MO Status=1, MOMSN=878, MT Status=0, MTMSN=0
2018-09-20T20:17:11.775Z,1537474631.775 [NAL9602](INFO): Sent 170 bytes from file Logs/20180920T194020/Express0007.lzma
2018-09-20T20:17:11.776Z,1537474631.776 [NAL9602](INFO): Packets left to send: 0
2018-09-20T20:17:11.779Z,1537474631.779 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180920T194020/Express0007.lzma.parts/0000.sbd
2018-09-20T20:17:11.780Z,1537474631.780 [NAL9602](DEBUG): Completed sending Logs/20180920T194020/Express0007.lzma
2018-09-20T20:17:15.708Z,1537474635.708 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T20:17:15.708Z,1537474635.708 [NAL9602] Data Fault, FailCount= 1
2018-09-20T20:17:15.708Z,1537474635.708 [NAL9602](ERROR): Data Fault
2018-09-20T20:17:15.798Z,1537474635.798 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T20:17:16.153Z,1537474636.153 [NAL9602](INFO): Powering down
2018-09-20T20:17:17.027Z,1537474637.027 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T20:17:17.027Z,1537474637.027 [NAL9602] No Fault, FailCount= 1
2018-09-20T20:17:46.511Z,1537474666.511 [NAL9602](INFO): Powering up NAL9602
2018-09-20T20:17:57.319Z,1537474677.319 [NAL9602](INFO): NAL9602 initialized
2018-09-20T20:20:03.915Z,1537474803.915 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-09-20T20:20:10.307Z,1537474810.307 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T20:20:10.427Z,1537474810.427 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T20:21:51.176Z,1537474911.176 [WetLabsSeaOWL_UV_A](DEBUG): Error parsing data: SEAOWLA2K-019	3795	118	729	729	2771	553	51	330

2018-09-20T20:23:56.508Z,1537475036.508 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:23:56.509Z,1537475036.509 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,180920135.0, -0.1,   0.0,1448.9,  0
2018-09-20T20:24:05.764Z,1537475045.764 [profile_station:NeedComms:E](INFO): Timed out from 2018-09-20T20:17:05.5Z
2018-09-20T20:24:05.764Z,1537475045.764 [profile_station:NeedComms:E:A_Timeout] Running Loop=1
2018-09-20T20:24:05.764Z,1537475045.764 [profile_station:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize profile_station:NeedComms:E:A_Timeout
2018-09-20T20:24:05.764Z,1537475045.764 [profile_station:NeedComms:E:A_Timeout:A] Running Loop=1
2018-09-20T20:24:05.764Z,1537475045.764 [profile_station:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2018-09-20T20:24:05.766Z,1537475045.766 [profile_station:NeedComms:E:A_Timeout:A] Stopped
2018-09-20T20:24:05.766Z,1537475045.766 [profile_station:NeedComms:E:A_Timeout](INFO): Completed profile_station:NeedComms:E:A_Timeout
2018-09-20T20:24:05.766Z,1537475045.766 [profile_station:NeedComms:E] Stopped
2018-09-20T20:24:05.767Z,1537475045.767 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-20T20:24:05.767Z,1537475045.767 [profile_station:NeedComms] Stopped
2018-09-20T20:24:05.768Z,1537475045.768 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-20T20:24:05.768Z,1537475045.768 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-20T20:24:05.768Z,1537475045.768 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T20:24:06.181Z,1537475046.181 [profile_station:NeedComms] Running Loop=1
2018-09-20T20:24:06.181Z,1537475046.181 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-20T20:24:06.181Z,1537475046.181 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-20T20:24:06.181Z,1537475046.181 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T20:24:06.194Z,1537475046.194 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-20T20:24:06.194Z,1537475046.194 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-20T20:24:06.195Z,1537475046.195 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-20T20:24:06.195Z,1537475046.195 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T20:24:06.195Z,1537475046.195 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T20:24:06.196Z,1537475046.196 [profile_station:NeedComms:A] Running Loop=1
2018-09-20T20:24:06.197Z,1537475046.197 [profile_station:NeedComms:A](INFO): last time_fix was: 1537472810.000000 second since 1970/01/01T00:00:00Z 
2018-09-20T20:24:06.206Z,1537475046.206 [profile_station:NeedComms:A] Stopped
2018-09-20T20:24:06.561Z,1537475046.561 [profile_station:NeedComms:C] Running Loop=1
2018-09-20T20:24:16.809Z,1537475056.809 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-20T20:24:16.809Z,1537475056.809 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.   +0.00,        +0.00,   0.00,1149.94
2018-09-20T20:26:20.100Z,1537475180.100 [NAL9602](INFO): GPS fix at 20180920T202620: (36.797808, -121.821754)
2018-09-20T20:26:20.174Z,1537475180.174 [UniversalFixResidualReporter](INFO): Fix residual: 32.1 %DT, over the last 1150.0 m. Residual distance 369.0 m at bearing -102.6 degrees. Fix at (36.7978, -121.8218) with 783.1 m made good.
2018-09-20T20:26:20.181Z,1537475180.181 [profile_station:NeedComms:C] Stopped
2018-09-20T20:26:20.190Z,1537475180.190 [profile_station:NeedComms:D] Running Loop=1
2018-09-20T20:26:24.839Z,1537475184.839 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20180920T194020/Courier0009.lzma
2018-09-20T20:26:25.603Z,1537475185.603 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0009.lzma.bak
2018-09-20T20:26:25.603Z,1537475185.603 [DataOverHttps](INFO): SBD MOMSN=8549074
2018-09-20T20:26:35.018Z,1537475195.018 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180920T194020/Express0010.lzma
2018-09-20T20:26:35.758Z,1537475195.758 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0010.lzma.bak
2018-09-20T20:26:35.759Z,1537475195.759 [DataOverHttps](INFO): SBD MOMSN=8549077
2018-09-20T20:26:36.260Z,1537475196.260 [profile_station:NeedComms:D] Stopped
2018-09-20T20:26:36.260Z,1537475196.260 [profile_station:NeedComms:E] Running Loop=1
2018-09-20T20:26:41.063Z,1537475201.063 [NAL9602](INFO): GPS fix at 20180920T202641: (36.797807, -121.821716)
2018-09-20T20:26:41.131Z,1537475201.131 [profile_station:NeedComms:E] Stopped
2018-09-20T20:26:41.132Z,1537475201.132 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-20T20:26:41.132Z,1537475201.132 [profile_station:NeedComms] Stopped
2018-09-20T20:26:41.132Z,1537475201.132 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-20T20:26:41.132Z,1537475201.132 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-20T20:26:41.132Z,1537475201.132 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T20:26:55.174Z,1537475215.174 [BuoyancyServo](FAULT): Overload Error
2018-09-20T20:26:55.174Z,1537475215.174 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-20T20:26:55.174Z,1537475215.174 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T20:26:55.332Z,1537475215.332 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-09-20T20:26:55.524Z,1537475215.524 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T20:26:55.525Z,1537475215.525 [BuoyancyServo](INFO): Powering down
2018-09-20T20:26:57.578Z,1537475217.578 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T20:26:57.578Z,1537475217.578 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T20:26:57.942Z,1537475217.942 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T20:26:58.063Z,1537475218.063 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T20:27:07.815Z,1537475227.815 [Radio_Surface](INFO): Powering down
2018-09-20T20:27:13.448Z,1537475233.448 [NAL9602](INFO): Powering down
2018-09-20T20:28:06.254Z,1537475286.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:28:35.492Z,1537475315.492 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:28:35.493Z,1537475315.493 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092013341788,35.0, -0.1,  8.9,  0
2018-09-20T20:29:06.254Z,1537475346.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:29:41.050Z,1537475381.050 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.003021
2018-09-20T20:30:25.491Z,1537475425.491 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:30:25.491Z,1537475425.491 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-3276,-32768,V
2018-09-20T20:31:07.053Z,1537475467.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:31:11.983Z,1537475471.983 [Reporter](INFO): platform_battery_voltage 16.030518 V
2018-09-20T20:32:06.670Z,1537475526.670 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:33:04.299Z,1537475584.299 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:33:04.300Z,1537475584.300 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-3276,V
2018-09-20T20:33:07.065Z,1537475587.065 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:34:07.462Z,1537475647.462 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:34:25.493Z,1537475665.493 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:34:25.494Z,1537475665.494 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-3278,-32768,-32768,V
2018-09-20T20:35:07.472Z,1537475707.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:35:54.309Z,1537475754.309 [RDI_Pathfinder](ERROR): only read 2 of 5 data items
2018-09-20T20:35:54.309Z,1537475754.309 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768-32768,V
2018-09-20T20:36:07.867Z,1537475767.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:37:07.863Z,1537475827.863 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:37:11.104Z,1537475831.104 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-20T20:37:39.134Z,1537475859.134 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T20:37:39.255Z,1537475859.255 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T20:38:07.862Z,1537475887.862 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:38:13.948Z,1537475893.948 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8c,0ffe,*14.678,*-0.086,*0.000
2018-09-20T20:39:07.497Z,1537475947.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:39:09.127Z,1537475949.127 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:39:09.128Z,1537475949.128 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-3276,-32768,-32768,V
2018-09-20T20:39:19.131Z,1537475959.131 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T20:39:19.132Z,1537475959.132 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-3278,-32768,-32768,V
2018-09-20T20:39:33.964Z,1537475973.964 [RDI_Pathfinder](ERROR): Failed to parse:

2018-09-20T20:40:07.903Z,1537476007.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:40:08.756Z,1537476008.756 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:40:08.756Z,1537476008.756 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092013455188,35.0, -0.1, 48.9,  0
2018-09-20T20:40:21.148Z,1537476021.148 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:40:21.148Z,1537476021.148 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,180920134603880.1,   0.0,1448.9,  0
2018-09-20T20:40:37.142Z,1537476037.142 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:40:37.142Z,1537476037.142 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,180920134619880.1,   .9,  0
2018-09-20T20:41:07.906Z,1537476067.906 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:42:07.924Z,1537476127.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:43:07.921Z,1537476187.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:43:26.774Z,1537476206.774 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8d,0ffe, 14.671, -0.098, 0.000
2018-09-20T20:44:08.323Z,1537476248.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:45:08.322Z,1537476308.322 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:46:08.342Z,1537476368.342 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:46:33.167Z,1537476393.167 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:46:33.167Z,1537476393.167 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092013521588,35.0, -0.1,448.9,  0
2018-09-20T20:47:08.356Z,1537476428.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:47:23.172Z,1537476443.172 [RDI_Pathfinder](ERROR): Failed to parse:
68,-32768,-32768,-32768,V
2018-09-20T20:47:24.816Z,1537476444.816 [profile_station:NeedComms] Running Loop=1
2018-09-20T20:47:24.816Z,1537476444.816 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-20T20:47:24.816Z,1537476444.816 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-20T20:47:24.816Z,1537476444.816 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T20:47:24.817Z,1537476444.817 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-20T20:47:24.817Z,1537476444.817 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-20T20:47:24.822Z,1537476444.822 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-20T20:47:24.822Z,1537476444.822 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T20:47:24.823Z,1537476444.823 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T20:47:24.823Z,1537476444.823 [profile_station:NeedComms:A] Running Loop=1
2018-09-20T20:47:24.825Z,1537476444.825 [profile_station:NeedComms:A](INFO): last time_fix was: 1537475201.000000 second since 1970/01/01T00:00:00Z 
2018-09-20T20:47:24.825Z,1537476444.825 [profile_station:NeedComms:A] Stopped
2018-09-20T20:48:08.590Z,1537476488.590 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:48:27.196Z,1537476507.196 [profile_station:NeedComms:C] Running Loop=1
2018-09-20T20:48:27.259Z,1537476507.259 [Radio_Surface](INFO): Powering up
2018-09-20T20:48:27.932Z,1537476507.932 [NAL9602](INFO): Powering up
2018-09-20T20:48:33.730Z,1537476513.730 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T20:48:33.730Z,1537476513.730 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T20:48:33.730Z,1537476513.730 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-20T20:48:33.731Z,1537476513.731 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T20:48:33.838Z,1537476513.838 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T20:48:34.005Z,1537476514.005 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T20:48:34.010Z,1537476514.010 [BuoyancyServo](INFO): Powering down
2018-09-20T20:48:36.378Z,1537476516.378 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T20:48:36.378Z,1537476516.378 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T20:48:36.786Z,1537476516.786 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T20:48:36.907Z,1537476516.907 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T20:48:39.066Z,1537476519.066 [NAL9602](INFO): NAL9602 initialized
2018-09-20T20:48:40.848Z,1537476520.848 [Reporter](INFO): platform_battery_voltage 16.021626 V
2018-09-20T20:48:41.894Z,1537476521.894 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T20:48:41.894Z,1537476521.894 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T20:48:41.894Z,1537476521.894 [BuoyancyServo] Communications Fault, FailCount= 2
2018-09-20T20:48:41.894Z,1537476521.894 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T20:48:42.019Z,1537476522.019 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T20:48:42.149Z,1537476522.149 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T20:48:42.154Z,1537476522.154 [BuoyancyServo](INFO): Powering down
2018-09-20T20:48:43.735Z,1537476523.735 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-20T20:48:43.735Z,1537476523.735 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-09-20T20:48:43.735Z,1537476523.735 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T20:48:44.234Z,1537476524.234 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T20:48:44.234Z,1537476524.234 [BuoyancyServo] No Fault, FailCount= 2
2018-09-20T20:48:44.515Z,1537476524.515 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T20:48:44.635Z,1537476524.635 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T20:48:52.454Z,1537476532.454 [NAL9602](INFO): SBD MO Status=0, MOMSN=879, MT Status=0, MTMSN=0
2018-09-20T20:48:52.454Z,1537476532.454 [NAL9602](INFO): No messages in MT queue
2018-09-20T20:49:08.843Z,1537476548.843 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T20:49:43.848Z,1537476583.848 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002799
2018-09-20T20:52:37.743Z,1537476757.743 [WetLabsSeaOWL_UV_A](DEBUG): Error parsing data: 704	644	6039	6039	3268	3268	79	338	338

2018-09-20T20:54:09.108Z,1537476849.108 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:54:09.108Z,1537476849.108 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092013595188,35.0, 0.0,1448.9,  0
2018-09-20T20:54:14.286Z,1537476854.286 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8d,0ffe,*14.859,*-0.086,*0.000
2018-09-20T20:54:21.103Z,1537476861.103 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:54:21.104Z,1537476861.104 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092014000388,35.0, -0.1,  8.9,  0
2018-09-20T20:54:34.287Z,1537476874.287 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:54:34.288Z,1537476874.288 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092014001688,35.0  0.0,1448.9,  0
2018-09-20T20:54:45.064Z,1537476885.064 [DataOverHttps](IMPORTANT): SBD MTMSN=20180920T205445
2018-09-20T20:54:49.449Z,1537476889.449 [DataOverHttps](INFO): Received command:configSet Express linearApprox WetLabsSeaOWL_UV_A.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.04 ug/l persist
2018-09-20T20:54:54.977Z,1537476894.977 [CommandLine](IMPORTANT): got command configSet Express linearApproximation 
2018-09-20T20:54:54.977Z,1537476894.977 [CommandLine](FAULT): Incomplete syntax. Try: help configSet
2018-09-20T20:54:56.242Z,1537476896.242 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8d,0ffe,*14.859,*-0.088,*0.000
2018-09-20T20:55:07.880Z,1537476907.880 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T20:55:08.045Z,1537476908.045 [Reporter](INFO): BPC1.platform_battery_voltage no_value
2018-09-20T20:55:27.440Z,1537476927.440 [profile_station:NeedComms:C](INFO): Timed out from 2018-09-20T20:48:27.2Z
2018-09-20T20:55:27.440Z,1537476927.440 [profile_station:NeedComms:C:A_Timeout] Running Loop=1
2018-09-20T20:55:27.440Z,1537476927.440 [profile_station:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize profile_station:NeedComms:C:A_Timeout
2018-09-20T20:55:27.440Z,1537476927.440 [profile_station:NeedComms:C:A_Timeout:A] Running Loop=1
2018-09-20T20:55:27.441Z,1537476927.441 [profile_station:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout
2018-09-20T20:55:27.441Z,1537476927.441 [profile_station:NeedComms:C:A_Timeout:A] Stopped
2018-09-20T20:55:27.441Z,1537476927.441 [profile_station:NeedComms:C:A_Timeout](INFO): Completed profile_station:NeedComms:C:A_Timeout
2018-09-20T20:55:27.441Z,1537476927.441 [profile_station:NeedComms:C] Stopped
2018-09-20T20:55:27.441Z,1537476927.441 [profile_station:NeedComms:D] Running Loop=1
2018-09-20T20:55:32.389Z,1537476932.389 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20180920T194020/Courier0012.lzma
2018-09-20T20:55:33.156Z,1537476933.156 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0012.lzma.bak
2018-09-20T20:55:33.156Z,1537476933.156 [DataOverHttps](INFO): SBD MOMSN=8549215
2018-09-20T20:55:36.813Z,1537476936.813 [CommandLine](IMPORTANT): got command configSet list
2018-09-20T20:55:36.813Z,1537476936.813 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-20T20:55:36.814Z,1537476936.814 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=275 cubic_centimeter;

2018-09-20T20:55:36.814Z,1537476936.814 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2018-09-20T20:55:42.866Z,1537476942.866 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Courier0015.lzma
2018-09-20T20:55:43.615Z,1537476943.615 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0015.lzma.bak
2018-09-20T20:55:43.615Z,1537476943.615 [DataOverHttps](INFO): SBD MOMSN=8549218
2018-09-20T20:55:54.048Z,1537476954.048 [DataOverHttps](INFO): Sending 482 bytes from file Logs/20180920T194020/Express0013.lzma
2018-09-20T20:55:54.815Z,1537476954.815 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0013.lzma.bak
2018-09-20T20:55:54.815Z,1537476954.815 [DataOverHttps](INFO): SBD MOMSN=8549220
2018-09-20T20:55:58.974Z,1537476958.974 [NAL9602](INFO): Powering down
2018-09-20T20:56:04.561Z,1537476964.561 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Express0016.lzma
2018-09-20T20:56:04.766Z,1537476964.766 [CommandLine](IMPORTANT): got command show variable WetLabsSeaOWL_UV_A.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2018-09-20T20:56:05.332Z,1537476965.332 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0016.lzma.bak
2018-09-20T20:56:05.332Z,1537476965.332 [DataOverHttps](INFO): SBD MOMSN=8549230
2018-09-20T20:56:05.821Z,1537476965.821 [profile_station:NeedComms:D] Stopped
2018-09-20T20:56:05.821Z,1537476965.821 [profile_station:NeedComms:E] Running Loop=1
2018-09-20T20:56:09.636Z,1537476969.636 [NAL9602](INFO): Powering up
2018-09-20T20:56:13.122Z,1537476973.122 [CommandLine](IMPORTANT): got command show variable chlorop
2018-09-20T20:56:13.125Z,1537476973.125 [CommandLine](IMPORTANT): mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter)
2018-09-20T20:56:13.288Z,1537476973.288 [CommandLine](IMPORTANT): WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water (microgram_per_liter)
2018-09-20T20:56:20.619Z,1537476980.619 [NAL9602](INFO): NAL9602 initialized
2018-09-20T20:56:36.612Z,1537476996.612 [NAL9602](INFO): SBD MO Status=0, MOMSN=880, MT Status=0, MTMSN=0
2018-09-20T20:56:36.613Z,1537476996.613 [NAL9602](INFO): No messages in MT queue
2018-09-20T20:56:38.479Z,1537476998.479 [CommandLine](IMPORTANT): got command configSet Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water 0.040000 microgram_per_liter persist
2018-09-20T20:56:42.974Z,1537477002.974 [CommandLine](IMPORTANT): got command configSet list
2018-09-20T20:56:42.974Z,1537477002.974 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-20T20:56:42.975Z,1537477002.975 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 minute;

2018-09-20T20:56:42.975Z,1537477002.975 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water 0.040000 microgram_per_liter;

2018-09-20T20:56:42.976Z,1537477002.976 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold=17 volt;

2018-09-20T20:56:42.976Z,1537477002.976 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=275 cubic_centimeter;

2018-09-20T20:56:42.976Z,1537477002.976 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2018-09-20T20:58:10.732Z,1537477090.732 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,3182.56
2018-09-20T20:58:39.883Z,1537477119.883 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T20:58:39.883Z,1537477119.883 [NAL9602] Data Fault, FailCount= 1
2018-09-20T20:58:39.883Z,1537477119.883 [NAL9602](ERROR): Data Fault
2018-09-20T20:58:40.070Z,1537477120.070 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T20:58:40.283Z,1537477120.283 [NAL9602](INFO): Powering down
2018-09-20T20:58:41.178Z,1537477121.178 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T20:58:41.178Z,1537477121.178 [NAL9602] No Fault, FailCount= 1
2018-09-20T20:58:47.111Z,1537477127.111 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T20:58:47.112Z,1537477127.112 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092014042988,35.0, -0.1,   0.0  0
2018-09-20T20:59:10.329Z,1537477150.329 [NAL9602](INFO): Powering up NAL9602
2018-09-20T20:59:11.090Z,1537477151.090 [DataOverHttps](IMPORTANT): SBD MTMSN=20180920T205911
2018-09-20T20:59:15.378Z,1537477155.378 [DataOverHttps](INFO): Received command:configSet Express linearApprox WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water 0.5 ug/l persist
2018-09-20T20:59:17.573Z,1537477157.573 [CommandLine](IMPORTANT): got command configSet Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_chlorophyll_in_sea_water 0.500000 microgram_per_liter persist
2018-09-20T20:59:21.360Z,1537477161.360 [NAL9602](INFO): NAL9602 initialized
2018-09-20T21:02:36.464Z,1537477356.464 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8d,0ffe,*14.859,*-0.086,*0.000
2018-09-20T21:03:06.110Z,1537477386.110 [profile_station:NeedComms:E](INFO): Timed out from 2018-09-20T20:56:05.8Z
2018-09-20T21:03:06.110Z,1537477386.110 [profile_station:NeedComms:E:A_Timeout] Running Loop=1
2018-09-20T21:03:06.111Z,1537477386.111 [profile_station:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize profile_station:NeedComms:E:A_Timeout
2018-09-20T21:03:06.111Z,1537477386.111 [profile_station:NeedComms:E:A_Timeout:A] Running Loop=1
2018-09-20T21:03:06.111Z,1537477386.111 [profile_station:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2018-09-20T21:03:06.111Z,1537477386.111 [profile_station:NeedComms:E:A_Timeout:A] Stopped
2018-09-20T21:03:06.112Z,1537477386.112 [profile_station:NeedComms:E:A_Timeout](INFO): Completed profile_station:NeedComms:E:A_Timeout
2018-09-20T21:03:06.112Z,1537477386.112 [profile_station:NeedComms:E] Stopped
2018-09-20T21:03:06.113Z,1537477386.113 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-20T21:03:06.113Z,1537477386.113 [profile_station:NeedComms] Stopped
2018-09-20T21:03:06.113Z,1537477386.113 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-20T21:03:06.113Z,1537477386.113 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-20T21:03:06.113Z,1537477386.113 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T21:03:06.509Z,1537477386.509 [profile_station:NeedComms] Running Loop=1
2018-09-20T21:03:06.509Z,1537477386.509 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2018-09-20T21:03:06.509Z,1537477386.509 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2018-09-20T21:03:06.514Z,1537477386.514 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T21:03:06.514Z,1537477386.514 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-20T21:03:06.514Z,1537477386.514 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-20T21:03:06.515Z,1537477386.515 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-20T21:03:06.515Z,1537477386.515 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T21:03:06.516Z,1537477386.516 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T21:03:06.516Z,1537477386.516 [profile_station:NeedComms:A] Running Loop=1
2018-09-20T21:03:06.517Z,1537477386.517 [profile_station:NeedComms:A](INFO): last time_fix was: 1537475201.000000 second since 1970/01/01T00:00:00Z 
2018-09-20T21:03:06.519Z,1537477386.519 [profile_station:NeedComms:A] Stopped
2018-09-20T21:03:06.918Z,1537477386.918 [profile_station:NeedComms:C] Running Loop=1
2018-09-20T21:03:31.987Z,1537477411.987 [NAL9602](INFO): GPS fix at 20180920T210332: (36.799922, -121.830548)
2018-09-20T21:03:32.066Z,1537477412.066 [UniversalFixResidualReporter](INFO): Fix residual: 44.2 %DT, over the last 1241.6 m. Residual distance 548.4 m at bearing -124.9 degrees. Fix at (36.7999, -121.8306) with 820.8 m made good.
2018-09-20T21:03:32.077Z,1537477412.077 [profile_station:NeedComms:C] Stopped
2018-09-20T21:03:32.077Z,1537477412.077 [profile_station:NeedComms:D] Running Loop=1
2018-09-20T21:03:36.914Z,1537477416.914 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20180920T194020/Courier0018.lzma
2018-09-20T21:03:37.676Z,1537477417.676 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0018.lzma.bak
2018-09-20T21:03:37.677Z,1537477417.677 [DataOverHttps](INFO): SBD MOMSN=8549279
2018-09-20T21:03:47.111Z,1537477427.111 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Courier0021.lzma
2018-09-20T21:03:47.871Z,1537477427.871 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0021.lzma.bak
2018-09-20T21:03:47.871Z,1537477427.871 [DataOverHttps](INFO): SBD MOMSN=8549282
2018-09-20T21:03:59.187Z,1537477439.187 [DataOverHttps](INFO): Sending 1424 bytes from file Logs/20180920T194020/Express0019.lzma
2018-09-20T21:03:59.955Z,1537477439.955 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0019.lzma.bak
2018-09-20T21:03:59.955Z,1537477439.955 [DataOverHttps](INFO): SBD MOMSN=8549284
2018-09-20T21:04:09.342Z,1537477449.342 [NAL9602](INFO): Powering down
2018-09-20T21:04:09.551Z,1537477449.551 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Express0022.lzma
2018-09-20T21:04:10.283Z,1537477450.283 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0022.lzma.bak
2018-09-20T21:04:10.283Z,1537477450.283 [DataOverHttps](INFO): SBD MOMSN=8549306
2018-09-20T21:04:10.792Z,1537477450.792 [profile_station:NeedComms:D] Stopped
2018-09-20T21:04:10.793Z,1537477450.793 [profile_station:NeedComms:E] Running Loop=1
2018-09-20T21:04:14.483Z,1537477454.483 [NAL9602](INFO): Powering up
2018-09-20T21:04:25.443Z,1537477465.443 [NAL9602](INFO): NAL9602 initialized
2018-09-20T21:04:31.066Z,1537477471.066 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-20T21:04:56.234Z,1537477496.234 [NAL9602](INFO): SBD MO Status=2, MOMSN=881, MT Status=2, MTMSN=0
2018-09-20T21:04:56.234Z,1537477496.234 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-09-20T21:05:05.437Z,1537477505.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=881, MT Status=0, MTMSN=0
2018-09-20T21:05:05.438Z,1537477505.438 [NAL9602](INFO): No messages in MT queue
2018-09-20T21:06:09.363Z,1537477569.363 [Reporter](INFO): platform_battery_voltage 16.018642 V
2018-09-20T21:06:16.423Z,1537477576.423 [RDI_Pathfinder](ERROR): Failed to parse:

2018-09-20T21:07:48.913Z,1537477668.913 [CommandLine](IMPORTANT): got command show stack
2018-09-20T21:07:48.913Z,1537477668.913 [CommandLine](IMPORTANT): Behavior Stack:

2018-09-20T21:07:48.914Z,1537477668.914 [profile_station:Science](IMPORTANT): Priority 0: profile_station:Science:A
2018-09-20T21:07:48.914Z,1537477668.914 [profile_station:Science](IMPORTANT): Priority 1: profile_station:Science:G
2018-09-20T21:07:48.915Z,1537477668.915 [profile_station:Science](IMPORTANT): Priority 2: profile_station:Science:Read_Oil
2018-09-20T21:07:48.915Z,1537477668.915 [profile_station:Science:PeakDetectChl](IMPORTANT): Priority 3: profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth
2018-09-20T21:07:48.915Z,1537477668.915 [profile_station:NeedComms](IMPORTANT): Priority 4: profile_station:NeedComms:B.GoToSurface
2018-09-20T21:07:48.916Z,1537477668.916 [profile_station:NeedComms](IMPORTANT): Priority 5: profile_station:NeedComms:E
2018-09-20T21:07:48.916Z,1537477668.916 [profile_station:StandardEnvelopes](IMPORTANT): Priority 6: profile_station:StandardEnvelopes:A.AltitudeEnvelope
2018-09-20T21:07:48.916Z,1537477668.916 [profile_station:StandardEnvelopes](IMPORTANT): Priority 7: profile_station:StandardEnvelopes:B.DepthEnvelope
2018-09-20T21:07:48.917Z,1537477668.917 [profile_station:StandardEnvelopes](IMPORTANT): Priority 8: profile_station:StandardEnvelopes:C.OffshoreEnvelope
2018-09-20T21:07:48.917Z,1537477668.917 [profile_station](IMPORTANT): Priority 9: profile_station:I.Pitch
2018-09-20T21:07:48.918Z,1537477668.918 [profile_station](IMPORTANT): Priority 10: profile_station:J.Buoyancy
2018-09-20T21:07:48.919Z,1537477668.919 [profile_station](IMPORTANT): Priority 11: profile_station:K.SetSpeed
2018-09-20T21:07:48.919Z,1537477668.919 [profile_station](IMPORTANT): Priority 12: profile_station:L.DepthEnvelope
2018-09-20T21:07:48.919Z,1537477668.919 [profile_station](IMPORTANT): Priority 13: profile_station:M.AltitudeEnvelope
2018-09-20T21:07:48.920Z,1537477668.920 [profile_station](IMPORTANT): Priority 14: profile_station:N.YoYo
2018-09-20T21:07:48.920Z,1537477668.920 [profile_station:CircleWrapper](IMPORTANT): Priority 15: profile_station:CircleWrapper:A
2018-09-20T21:07:48.920Z,1537477668.920 [profile_station:CircleWrapper](IMPORTANT): Priority 16: profile_station:CircleWrapper:B.Circle
2018-09-20T21:07:58.162Z,1537477678.162 [CommandLine](IMPORTANT): got command report clear
2018-09-20T21:08:23.994Z,1537477703.994 [CommandLine](IMPORTANT): got command get SpeedControl.propOmegaAction revolution_per_minute
2018-09-20T21:08:23.995Z,1537477703.995 [CommandLine](IMPORTANT): SpeedControl.propOmegaAction 0.000000 rpm
2018-09-20T21:08:27.914Z,1537477707.914 [CommandLine](IMPORTANT): got command show stack
2018-09-20T21:08:27.914Z,1537477707.914 [CommandLine](IMPORTANT): Behavior Stack:

2018-09-20T21:08:27.915Z,1537477707.915 [profile_station:Science](IMPORTANT): Priority 0: profile_station:Science:A
2018-09-20T21:08:27.915Z,1537477707.915 [profile_station:Science](IMPORTANT): Priority 1: profile_station:Science:G
2018-09-20T21:08:27.916Z,1537477707.916 [profile_station:Science](IMPORTANT): Priority 2: profile_station:Science:Read_Oil
2018-09-20T21:08:27.916Z,1537477707.916 [profile_station:Science:PeakDetectChl](IMPORTANT): Priority 3: profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth
2018-09-20T21:08:27.916Z,1537477707.916 [profile_station:NeedComms](IMPORTANT): Priority 4: profile_station:NeedComms:B.GoToSurface
2018-09-20T21:08:27.916Z,1537477707.916 [profile_station:NeedComms](IMPORTANT): Priority 5: profile_station:NeedComms:E
2018-09-20T21:08:27.917Z,1537477707.917 [profile_station:StandardEnvelopes](IMPORTANT): Priority 6: profile_station:StandardEnvelopes:A.AltitudeEnvelope
2018-09-20T21:08:27.917Z,1537477707.917 [profile_station:StandardEnvelopes](IMPORTANT): Priority 7: profile_station:StandardEnvelopes:B.DepthEnvelope
2018-09-20T21:08:27.918Z,1537477707.918 [profile_station:StandardEnvelopes](IMPORTANT): Priority 8: profile_station:StandardEnvelopes:C.OffshoreEnvelope
2018-09-20T21:08:27.919Z,1537477707.919 [profile_station](IMPORTANT): Priority 9: profile_station:I.Pitch
2018-09-20T21:08:27.919Z,1537477707.919 [profile_station](IMPORTANT): Priority 10: profile_station:J.Buoyancy
2018-09-20T21:08:27.919Z,1537477707.919 [profile_station](IMPORTANT): Priority 11: profile_station:K.SetSpeed
2018-09-20T21:08:27.920Z,1537477707.920 [profile_station](IMPORTANT): Priority 12: profile_station:L.DepthEnvelope
2018-09-20T21:08:27.920Z,1537477707.920 [profile_station](IMPORTANT): Priority 13: profile_station:M.AltitudeEnvelope
2018-09-20T21:08:27.920Z,1537477707.920 [profile_station](IMPORTANT): Priority 14: profile_station:N.YoYo
2018-09-20T21:08:27.921Z,1537477707.921 [profile_station:CircleWrapper](IMPORTANT): Priority 15: profile_station:CircleWrapper:A
2018-09-20T21:08:27.921Z,1537477707.921 [profile_station:CircleWrapper](IMPORTANT): Priority 16: profile_station:CircleWrapper:B.Circle
2018-09-20T21:10:23.269Z,1537477823.269 [WetLabsSeaOWL_UV_A](DEBUG): Error parsing data: SEAOWLA2K-019	3793	122	7251	78	326	326

2018-09-20T21:11:06.422Z,1537477866.422 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T21:11:06.422Z,1537477866.422 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092014164888,35.0, -.0,1448.9,  0
2018-09-20T21:11:10.864Z,1537477870.864 [profile_station:NeedComms:E](INFO): Timed out from 2018-09-20T21:04:10.8Z
2018-09-20T21:11:10.864Z,1537477870.864 [profile_station:NeedComms:E:A_Timeout] Running Loop=1
2018-09-20T21:11:10.864Z,1537477870.864 [profile_station:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize profile_station:NeedComms:E:A_Timeout
2018-09-20T21:11:10.865Z,1537477870.865 [profile_station:NeedComms:E:A_Timeout:A] Running Loop=1
2018-09-20T21:11:10.866Z,1537477870.866 [profile_station:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2018-09-20T21:11:10.870Z,1537477870.870 [profile_station:NeedComms:E:A_Timeout:A] Stopped
2018-09-20T21:11:10.871Z,1537477870.871 [profile_station:NeedComms:E:A_Timeout](INFO): Completed profile_station:NeedComms:E:A_Timeout
2018-09-20T21:11:10.871Z,1537477870.871 [profile_station:NeedComms:E] Stopped
2018-09-20T21:11:10.872Z,1537477870.872 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2018-09-20T21:11:10.872Z,1537477870.872 [profile_station:NeedComms] Stopped
2018-09-20T21:11:10.873Z,1537477870.873 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2018-09-20T21:11:10.873Z,1537477870.873 [profile_station:NeedComms:B.GoToSurface] Stopped
2018-09-20T21:11:10.873Z,1537477870.873 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T21:11:17.439Z,1537477877.439 [DataOverHttps](IMPORTANT): SBD MTMSN=20180920T211117
2018-09-20T21:11:21.948Z,1537477881.948 [DataOverHttps](INFO): Received command:run Maintenance/ballast_and_trim.xml
2018-09-20T21:11:22.027Z,1537477882.027 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/ballast_and_trim.xml
2018-09-20T21:11:22.028Z,1537477882.028 [MissionManager](INFO): Uninitializing Mission profile_station
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station] Stopped
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science] Stopped
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science:A] Stopped
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science:G] Stopped
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science:Read_Oil] Stopped
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science:PeakDetectChl] Stopped
2018-09-20T21:11:22.028Z,1537477882.028 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes] Stopped
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-09-20T21:11:22.029Z,1537477882.029 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:I.Pitch] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:J.Buoyancy] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:K.SetSpeed] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:L.DepthEnvelope] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:M.AltitudeEnvelope] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:N.YoYo] Stopped
2018-09-20T21:11:22.030Z,1537477882.030 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-09-20T21:11:22.031Z,1537477882.031 [profile_station:CircleWrapper] Stopped
2018-09-20T21:11:22.031Z,1537477882.031 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2018-09-20T21:11:22.031Z,1537477882.031 [profile_station:CircleWrapper:A] Stopped
2018-09-20T21:11:22.031Z,1537477882.031 [profile_station:CircleWrapper:B.Circle] Stopped
2018-09-20T21:11:22.031Z,1537477882.031 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2018-09-20T21:11:22.104Z,1537477882.104 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/ballast_and_trim.xml
2018-09-20T21:11:22.382Z,1537477882.382 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-20T21:11:22.402Z,1537477882.402 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min
2018-09-20T21:11:22.414Z,1537477882.414 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2018-09-20T21:11:22.423Z,1537477882.423 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m
2018-09-20T21:11:22.427Z,1537477882.427 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband1 = 0.100000 m
2018-09-20T21:11:22.438Z,1537477882.438 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2018-09-20T21:11:22.446Z,1537477882.446 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband2 = nan m
2018-09-20T21:11:22.449Z,1537477882.449 [MissionManager](INFO): DefineArg ballast_and_trim.SpeedToTargetDepth = 1.000000 m/s
2018-09-20T21:11:22.456Z,1537477882.456 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 45.000000 min
2018-09-20T21:11:22.460Z,1537477882.460 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 25.000000 min
2018-09-20T21:11:22.471Z,1537477882.471 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2018-09-20T21:11:22.479Z,1537477882.479 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2018-09-20T21:11:22.486Z,1537477882.486 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2018-09-20T21:11:22.494Z,1537477882.494 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2018-09-20T21:11:22.497Z,1537477882.497 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2018-09-20T21:11:22.505Z,1537477882.505 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2018-09-20T21:11:22.516Z,1537477882.516 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m
2018-09-20T21:11:22.520Z,1537477882.520 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m
2018-09-20T21:11:22.524Z,1537477882.524 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km
2018-09-20T21:11:22.539Z,1537477882.539 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool
2018-09-20T21:11:22.540Z,1537477882.540 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-09-20T21:11:22.898Z,1537477882.898 [Aanderaa_O2](INFO): Powering down
2018-09-20T21:11:22.921Z,1537477882.921 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUS = 0 bool
2018-09-20T21:11:22.944Z,1537477882.944 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-09-20T21:11:22.955Z,1537477882.955 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-20T21:11:22.965Z,1537477882.965 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-09-20T21:11:22.976Z,1537477882.976 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2018-09-20T21:11:22.983Z,1537477882.983 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2018-09-20T21:11:22.998Z,1537477882.998 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2018-09-20T21:11:23.010Z,1537477883.010 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2018-09-20T21:11:23.013Z,1537477883.013 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2018-09-20T21:11:23.020Z,1537477883.020 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2018-09-20T21:11:23.023Z,1537477883.023 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2018-09-20T21:11:23.045Z,1537477883.045 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-09-20T21:11:23.067Z,1537477883.067 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 1.000000 bool
2018-09-20T21:11:23.070Z,1537477883.070 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool
2018-09-20T21:11:23.095Z,1537477883.095 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 0.000000 bool
2018-09-20T21:11:23.099Z,1537477883.099 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-09-20T21:11:23.103Z,1537477883.103 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 0.000000 bool
2018-09-20T21:11:23.117Z,1537477883.117 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-09-20T21:11:23.120Z,1537477883.120 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledISUS = 0.000000 bool
2018-09-20T21:11:23.123Z,1537477883.123 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2018-09-20T21:11:23.151Z,1537477883.151 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m
2018-09-20T21:11:23.157Z,1537477883.157 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m
2018-09-20T21:11:23.204Z,1537477883.204 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2018-09-20T21:11:23.231Z,1537477883.231 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2018-09-20T21:11:23.250Z,1537477883.250 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2018-09-20T21:11:23.253Z,1537477883.253 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2018-09-20T21:11:23.272Z,1537477883.272 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2018-09-20T21:11:23.284Z,1537477883.284 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2018-09-20T21:11:23.287Z,1537477883.287 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2018-09-20T21:11:23.299Z,1537477883.299 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2018-09-20T21:11:23.306Z,1537477883.306 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2018-09-20T21:11:23.314Z,1537477883.314 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m
2018-09-20T21:11:23.319Z,1537477883.319 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2018-09-20T21:11:23.323Z,1537477883.323 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2018-09-20T21:11:23.330Z,1537477883.330 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2018-09-20T21:11:23.333Z,1537477883.333 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2018-09-20T21:11:23.341Z,1537477883.341 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2018-09-20T21:11:23.348Z,1537477883.348 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2018-09-20T21:11:23.352Z,1537477883.352 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2018-09-20T21:11:23.364Z,1537477883.364 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2018-09-20T21:11:23.396Z,1537477883.396 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-20T21:11:23.419Z,1537477883.419 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-09-20T21:11:23.444Z,1537477883.444 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-20T21:11:23.461Z,1537477883.461 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-20T21:11:23.477Z,1537477883.477 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-09-20T21:11:23.630Z,1537477883.630 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 3.000000 h
2018-09-20T21:11:23.638Z,1537477883.638 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min
2018-09-20T21:11:23.650Z,1537477883.650 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-09-20T21:11:23.653Z,1537477883.653 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s
2018-09-20T21:11:23.660Z,1537477883.660 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-09-20T21:11:23.668Z,1537477883.668 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min
2018-09-20T21:11:23.675Z,1537477883.675 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min
2018-09-20T21:11:23.685Z,1537477883.685 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-20T21:11:23.722Z,1537477883.722 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-09-20T21:11:23.757Z,1537477883.757 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinAltitude = 5.000000 m
2018-09-20T21:11:23.764Z,1537477883.764 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepth = 200.000000 m
2018-09-20T21:11:23.778Z,1537477883.778 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-09-20T21:11:23.779Z,1537477883.779 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-20T21:11:23.795Z,1537477883.795 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-20T21:11:23.813Z,1537477883.813 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-09-20T21:11:23.860Z,1537477883.860 [ballast_and_trim:CheckTargetDepths:CheckDepth1:B.Execute](DEBUG): Construct Execute.
2018-09-20T21:11:23.883Z,1537477883.883 [ballast_and_trim:CheckTargetDepths:CheckDepth2:B.Execute](DEBUG): Construct Execute.
2018-09-20T21:11:23.900Z,1537477883.900 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2018-09-20T21:11:24.157Z,1537477884.157 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepth = 25.000000 m
2018-09-20T21:11:24.173Z,1537477884.173 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2018-09-20T21:11:24.176Z,1537477884.176 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2018-09-20T21:11:24.187Z,1537477884.187 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2018-09-20T21:11:24.191Z,1537477884.191 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2018-09-20T21:11:24.194Z,1537477884.194 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2018-09-20T21:11:24.197Z,1537477884.197 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSettleTime = 10.000000 min
2018-09-20T21:11:24.220Z,1537477884.220 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstTimeout = 45.000000 min
2018-09-20T21:11:24.224Z,1537477884.224 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MinEstTime = 10.000000 min
2018-09-20T21:11:24.227Z,1537477884.227 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2018-09-20T21:11:24.235Z,1537477884.235 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2018-09-20T21:11:24.238Z,1537477884.238 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstConfidence = 99.900000 %
2018-09-20T21:11:24.256Z,1537477884.256 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2018-09-20T21:11:24.274Z,1537477884.274 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-09-20T21:11:24.276Z,1537477884.276 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct.
2018-09-20T21:11:24.296Z,1537477884.296 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-09-20T21:11:24.300Z,1537477884.300 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2018-09-20T21:11:24.317Z,1537477884.317 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2018-09-20T21:11:24.397Z,1537477884.397 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2018-09-20T21:11:24.408Z,1537477884.408 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2018-09-20T21:11:24.483Z,1537477884.483 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth1
2018-09-20T21:11:24.485Z,1537477884.485 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband1
2018-09-20T21:11:24.503Z,1537477884.503 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSpeedToTargetDepth = reader:ballast_and_trim.SpeedToTargetDepth
2018-09-20T21:11:24.505Z,1537477884.505 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertTargetDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2018-09-20T21:11:24.515Z,1537477884.515 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2018-09-20T21:11:24.516Z,1537477884.516 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2018-09-20T21:11:24.522Z,1537477884.522 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2018-09-20T21:11:24.524Z,1537477884.524 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2018-09-20T21:11:24.542Z,1537477884.542 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2018-09-20T21:11:24.544Z,1537477884.544 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2018-09-20T21:11:24.554Z,1537477884.554 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2018-09-20T21:11:24.556Z,1537477884.556 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2018-09-20T21:11:24.704Z,1537477884.704 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepth = 25.000000 m
2018-09-20T21:11:24.707Z,1537477884.707 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2018-09-20T21:11:24.725Z,1537477884.725 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSpeedToTargetDepth = 0.000000 m/s
2018-09-20T21:11:24.728Z,1537477884.728 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertTargetDepthTimeout = 45.000000 min
2018-09-20T21:11:24.783Z,1537477884.783 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSurfaceTimeout = 25.000000 min
2018-09-20T21:11:24.803Z,1537477884.803 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSurfaceThreshold = 5.000000 m
2018-09-20T21:11:24.832Z,1537477884.832 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSettleTime = 10.000000 min
2018-09-20T21:11:24.836Z,1537477884.836 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstTimeout = 45.000000 min
2018-09-20T21:11:24.859Z,1537477884.859 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MinEstTime = 10.000000 min
2018-09-20T21:11:24.862Z,1537477884.862 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2018-09-20T21:11:24.865Z,1537477884.865 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2018-09-20T21:11:24.905Z,1537477884.905 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstConfidence = 99.900000 %
2018-09-20T21:11:24.926Z,1537477884.926 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Construct.
2018-09-20T21:11:24.952Z,1537477884.952 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-09-20T21:11:24.970Z,1537477884.970 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Construct.
2018-09-20T21:11:24.977Z,1537477884.977 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-09-20T21:11:25.006Z,1537477885.006 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Construct.
2018-09-20T21:11:25.021Z,1537477885.021 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Construct.
2018-09-20T21:11:25.071Z,1537477885.071 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Construct Wait.
2018-09-20T21:11:25.082Z,1537477885.082 [ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2018-09-20T21:11:25.097Z,1537477885.097 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepth = reader:ballast_and_trim.Depth2
2018-09-20T21:11:25.107Z,1537477885.107 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband2
2018-09-20T21:11:25.109Z,1537477885.109 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSpeedToTargetDepth = reader:ballast_and_trim.SpeedToTargetDepth
2018-09-20T21:11:25.119Z,1537477885.119 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSurfaceTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2018-09-20T21:11:25.120Z,1537477885.120 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertTargetDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2018-09-20T21:11:25.130Z,1537477885.130 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2018-09-20T21:11:25.132Z,1537477885.132 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2018-09-20T21:11:25.205Z,1537477885.205 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2018-09-20T21:11:25.207Z,1537477885.207 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2018-09-20T21:11:25.209Z,1537477885.209 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2018-09-20T21:11:25.217Z,1537477885.217 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:RunDepth2:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2018-09-20T21:11:25.364Z,1537477885.364 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Units="Tethys/Units"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
       Id="ballast_and_trim">

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

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>35</Value></DefineArg>

    <DefineArg Name="DepthDeadband1"><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="SpeedToTargetDepth"><Description>
        Enables vehicle thruster at the commanded speed to reach target depth (set to 0 m/s to drift).
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>
    
    <DefineArg Name="TargetDepthTimeout"><Description>
        Maximum wait time for the vehicle to reach the targeted depth.
    </Description><Units:minute/><Value>45</Value></DefineArg>
    
    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>25</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Units:meter/><Value>52</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Units:kilometer/><Value>2</Value></DefineArg>
    
    <DefineArg Name="CheckDepths"><Units:bool/><Value>1</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

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

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

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

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

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

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

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

    <Aggregate Id="CheckTargetDepths">
        
        <Sequence/>
        
        <Break>
            <Not><Arg Name="CheckDepths"/></Not>
        </Break>
        
        <Aggregate Id="CheckDepth1">
            <Sequence/>
            
            <Break>
                <Not>
                    <Arg Name="Depth1"/>
                    <Gt><Arg Name="MaxDepth"/></Gt>
                </Not>
            </Break>
            
            <Syslog Severity="Important">Aborting ballast and trim mission. Target depth Depth1 exceeds the mission's MaxDepth setting (review mission parameters).</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Aggregate>
        
        <Aggregate Id="CheckDepth2">
            <Sequence/>
            
            <Break>
                <Not>
                    <Arg Name="Depth2"/>
                    <Gt><Arg Name="MaxDepth"/></Gt>
                </Not>
            </Break>
            
            <Syslog Severity="Important">Aborting ballast and trim mission. Target depth Depth2 exceeds the mission's MaxDepth setting (review mission parameters).</Syslog>
            <Guidance:Execute>
                <Sequence/>
                <Setting><Guidance:Execute.command/><String>stop</String></Setting>
            </Guidance:Execute>
        </Aggregate>
        
        <Assign><Sequence/><Arg Name="CheckDepths"/><Units:bool/><Value>0</Value></Assign>
        
    </Aggregate>


    <Aggregate Id="StartingMission">

        <Sequence/>

        <Break><Arg Name="SkipComms"/>
        </Break>

<!-- DO NOT REMOVE the syslog entry below. The MissionManager seems to ignore the value of SkipComms, does not evaluate the Break statement properly, and always calls NeedComms. Adding the syslog call somehow fixes it. -->

        <Syslog Severity="Info">Checking for additional instructions before submerging.</Syslog>

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

    </Aggregate>
    

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Aggregate Id="RunDepth1">

            <Sequence/>

            <Break>
                <IsNaN>
                    <Arg Name="Depth1"/>
                </IsNaN>
                <Or><IsNaN>
                    <Arg Name="DepthDeadband1"/>
                </IsNaN></Or>
            </Break>

            <Insert Filename="Insert/BallastAndTrim.xml">
                <RedefineArg Name="InsertDepth"><Arg Name="Depth1"/></RedefineArg>
                <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband1"/></RedefineArg>
                <RedefineArg Name="InsertSpeedToTargetDepth"><Arg Name="SpeedToTargetDepth"/></RedefineArg>
                <RedefineArg Name="InsertTargetDepthTimeout"><Arg Name="TargetDepthTimeout"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
                <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
                <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
                <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
                <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
            </Insert>

        </Aggregate>

        <Aggregate Id="RunDepth2">

            <Sequence/>

            <Break>
                <IsNaN>
                    <Arg Name="Depth2"/>
                </IsNaN>
                <Or><IsNaN>
                    <Arg Name="DepthDeadband2"/>
                </IsNaN></Or>
            </Break>

            <Insert Filename="Insert/BallastAndTrim.xml">
                <RedefineArg Name="InsertDepth"><Arg Name="Depth2"/></RedefineArg>
                <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband2"/></RedefineArg>
                <RedefineArg Name="InsertSpeedToTargetDepth"><Arg Name="SpeedToTargetDepth"/></RedefineArg>
                <RedefineArg Name="InsertSurfaceTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
                <RedefineArg Name="InsertTargetDepthTimeout"><Arg Name="TargetDepthTimeout"/></RedefineArg>
                <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
                <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
                <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
                <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
                <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
            </Insert>

        </Aggregate>

    </Aggregate>

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

</Mission>


2018-09-20T21:11:25.368Z,1537477885.368 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/ballast_and_trim.xml
2018-09-20T21:11:25.618Z,1537477885.618 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2018-09-20T21:11:25.618Z,1537477885.618 [ballast_and_trim] Running Loop=1
2018-09-20T21:11:25.618Z,1537477885.618 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2018-09-20T21:11:25.618Z,1537477885.618 [ballast_and_trim:Science] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:A] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:B] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:C] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:D] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:E] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:F] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:G] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2018-09-20T21:11:25.619Z,1537477885.619 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2018-09-20T21:11:25.620Z,1537477885.620 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-09-20T21:11:25.620Z,1537477885.620 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-20T21:11:25.621Z,1537477885.621 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2018-09-20T21:11:25.626Z,1537477885.626 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2018-09-20T21:11:25.626Z,1537477885.626 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-09-20T21:11:25.626Z,1537477885.626 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-09-20T21:11:25.626Z,1537477885.626 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2018-09-20T21:11:25.627Z,1537477885.627 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2018-09-20T21:11:25.627Z,1537477885.627 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-09-20T21:11:25.627Z,1537477885.627 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-20T21:11:25.631Z,1537477885.631 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2018-09-20T21:11:25.631Z,1537477885.631 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2018-09-20T21:11:25.631Z,1537477885.631 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-09-20T21:11:25.631Z,1537477885.631 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-20T21:11:25.633Z,1537477885.633 [ballast_and_trim:StandardEnvelopes] Running Loop=1
2018-09-20T21:11:25.633Z,1537477885.633 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::initialize ballast_and_trim:StandardEnvelopes
2018-09-20T21:11:25.633Z,1537477885.633 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-20T21:11:25.633Z,1537477885.633 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-20T21:11:25.633Z,1537477885.633 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-20T21:11:25.633Z,1537477885.633 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-20T21:11:25.634Z,1537477885.634 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-20T21:11:25.634Z,1537477885.634 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-09-20T21:11:25.634Z,1537477885.634 [ballast_and_trim:C] Running Loop=1
2018-09-20T21:11:25.635Z,1537477885.635 [ballast_and_trim:StandardEnvelopes] Running Loop=1
2018-09-20T21:11:25.635Z,1537477885.635 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-09-20T21:11:25.644Z,1537477885.644 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-09-20T21:11:25.653Z,1537477885.653 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-09-20T21:11:25.668Z,1537477885.668 [ballast_and_trim:C] Stopped
2018-09-20T21:11:25.669Z,1537477885.669 [ballast_and_trim:E] Running Loop=1
2018-09-20T21:11:25.669Z,1537477885.669 [ballast_and_trim:Science] Running Loop=1
2018-09-20T21:11:25.674Z,1537477885.674 [ballast_and_trim:Science:PeakDetectOil] Stopped
2018-09-20T21:11:25.674Z,1537477885.674 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2018-09-20T21:11:25.674Z,1537477885.674 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-09-20T21:11:25.674Z,1537477885.674 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-20T21:11:25.674Z,1537477885.674 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2018-09-20T21:11:25.674Z,1537477885.674 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:PeakDetectChl] Stopped
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-20T21:11:25.675Z,1537477885.675 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2018-09-20T21:11:25.679Z,1537477885.679 [ballast_and_trim:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-09-20T21:11:25.680Z,1537477885.680 [ballast_and_trim:Science:G] Stopped
2018-09-20T21:11:25.680Z,1537477885.680 [ballast_and_trim:Science:F] Stopped
2018-09-20T21:11:25.680Z,1537477885.680 [ballast_and_trim:Science:E] Stopped
2018-09-20T21:11:25.680Z,1537477885.680 [ballast_and_trim:Science:D] Stopped
2018-09-20T21:11:25.680Z,1537477885.680 [ballast_and_trim:Science:C] Stopped
2018-09-20T21:11:25.680Z,1537477885.680 [ballast_and_trim:Science:B] Stopped
2018-09-20T21:11:25.681Z,1537477885.681 [ballast_and_trim:Science:A] Running Loop=1
2018-09-20T21:11:25.690Z,1537477885.690 [ballast_and_trim:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-09-20T21:11:26.006Z,1537477886.006 [ballast_and_trim:E] Stopped
2018-09-20T21:11:26.007Z,1537477886.007 [ballast_and_trim:F] Running Loop=1
2018-09-20T21:11:26.026Z,1537477886.026 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2018-09-20T21:11:26.027Z,1537477886.027 [WetLabsSeaOWL_UV_A](INFO): Powering up
2018-09-20T21:11:26.433Z,1537477886.433 [ballast_and_trim:F] Stopped
2018-09-20T21:11:26.433Z,1537477886.433 [ballast_and_trim:G] Running Loop=1
2018-09-20T21:11:26.830Z,1537477886.830 [ballast_and_trim:G] Stopped
2018-09-20T21:11:26.830Z,1537477886.830 [ballast_and_trim:CheckTargetDepths] Running Loop=1
2018-09-20T21:11:26.830Z,1537477886.830 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths
2018-09-20T21:11:26.831Z,1537477886.831 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Running Loop=1
2018-09-20T21:11:26.831Z,1537477886.831 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth1
2018-09-20T21:11:26.831Z,1537477886.831 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Running Loop=1
2018-09-20T21:11:27.198Z,1537477887.198 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Stopped
2018-09-20T21:11:27.198Z,1537477887.198 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth1
2018-09-20T21:11:27.198Z,1537477887.198 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Stopped
2018-09-20T21:11:27.198Z,1537477887.198 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Running Loop=1
2018-09-20T21:11:27.198Z,1537477887.198 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth2
2018-09-20T21:11:27.198Z,1537477887.198 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Running Loop=1
2018-09-20T21:11:27.623Z,1537477887.623 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Stopped
2018-09-20T21:11:27.623Z,1537477887.623 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth2
2018-09-20T21:11:27.623Z,1537477887.624 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Stopped
2018-09-20T21:11:27.624Z,1537477887.624 [ballast_and_trim:CheckTargetDepths:C] Running Loop=1
2018-09-20T21:11:28.040Z,1537477888.040 [ballast_and_trim:CheckTargetDepths:C] Stopped
2018-09-20T21:11:28.040Z,1537477888.040 [ballast_and_trim:CheckTargetDepths](INFO): Completed ballast_and_trim:CheckTargetDepths
2018-09-20T21:11:28.040Z,1537477888.040 [ballast_and_trim:CheckTargetDepths] Stopped
2018-09-20T21:11:28.040Z,1537477888.040 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths
2018-09-20T21:11:28.041Z,1537477888.041 [ballast_and_trim:StartingMission] Running Loop=1
2018-09-20T21:11:28.041Z,1537477888.041 [ballast_and_trim:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission
2018-09-20T21:11:28.053Z,1537477888.053 [ballast_and_trim:StartingMission:A] Running Loop=1
2018-09-20T21:11:28.397Z,1537477888.397 [ballast_and_trim:StartingMission:A](INFO): Checking for additional instructions before submerging.
2018-09-20T21:11:28.411Z,1537477888.411 [ballast_and_trim:StartingMission:A] Stopped
2018-09-20T21:11:28.411Z,1537477888.411 [ballast_and_trim:StartingMission:StartingMission] Running Loop=1
2018-09-20T21:11:28.411Z,1537477888.411 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission:StartingMission
2018-09-20T21:11:28.842Z,1537477888.842 [ballast_and_trim:NeedComms] Running Loop=1
2018-09-20T21:11:28.842Z,1537477888.842 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms
2018-09-20T21:11:28.843Z,1537477888.843 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1
2018-09-20T21:11:28.843Z,1537477888.843 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T21:11:28.843Z,1537477888.843 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-20T21:11:28.844Z,1537477888.844 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-20T21:11:28.844Z,1537477888.844 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-20T21:11:28.844Z,1537477888.844 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T21:11:28.845Z,1537477888.845 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T21:11:28.845Z,1537477888.845 [ballast_and_trim:NeedComms:A] Running Loop=1
2018-09-20T21:11:28.859Z,1537477888.859 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1537477412.000000 second since 1970/01/01T00:00:00Z 
2018-09-20T21:11:28.861Z,1537477888.861 [ballast_and_trim:NeedComms:A] Stopped
2018-09-20T21:11:29.211Z,1537477889.211 [ballast_and_trim:NeedComms:C] Running Loop=1
2018-09-20T21:11:29.742Z,1537477889.742 [ballast_and_trim:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-20T21:11:33.545Z,1537477893.545 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		337.549		105.622		14.327		30.410		30.097		38.211		8.114		372.8		549.8		318.4	


	8.122		391.3		545.4		334.5	
	30.846		38.973		8.127		385.6		538.8		315.5	

2018-09-20T21:11:33.545Z,1537477893.545 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T21:11:34.744Z,1537477894.744 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		337.416		105.582		14.328		30.414		30.101		38.214		8.113		372.7		549.5		318.4	


	8.122		391.3		545.4		334.5	
	30.846		38.973		8.127		385.6		538.8		315.5	

2018-09-20T21:11:34.744Z,1537477894.744 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T21:11:35.944Z,1537477895.944 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		337.566		105.630		14.328		30.409		30.096		38.207		8.111		372.7		549.3		318.4	

	8.122		391.3		545.4		334.5	
	30.846		38.973		8.127		385.6		538.8		315.5	

2018-09-20T21:11:35.944Z,1537477895.944 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T21:11:37.944Z,1537477897.944 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		337.690		105.681		14.334		30.402		30.089		38.200		8.111		372.6		549.1		318.2	

	8.122		391.3		545.4		334.5	
	30.846		38.973		8.127		385.6		538.8		315.5	

2018-09-20T21:11:37.944Z,1537477897.944 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-20T21:12:35.152Z,1537477955.152 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T21:14:27.689Z,1537478067.689 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T21:14:27.694Z,1537478067.694 [NAL9602] Data Fault, FailCount= 1
2018-09-20T21:14:27.694Z,1537478067.694 [NAL9602](ERROR): Data Fault
2018-09-20T21:14:27.798Z,1537478067.798 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T21:14:28.075Z,1537478068.075 [NAL9602](INFO): Powering down
2018-09-20T21:14:28.975Z,1537478068.975 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T21:14:28.975Z,1537478068.975 [NAL9602] No Fault, FailCount= 1
2018-09-20T21:14:58.467Z,1537478098.467 [NAL9602](INFO): Powering up NAL9602
2018-09-20T21:15:09.679Z,1537478109.679 [NAL9602](INFO): NAL9602 initialized
2018-09-20T21:16:33.988Z,1537478193.988 [RDI_Pathfinder](ERROR): Failed to parse:
:H814.832, -0.087, 0.000
2018-09-20T21:18:29.456Z,1537478309.456 [ballast_and_trim:NeedComms:C](INFO): Timed out from 2018-09-20T21:11:29.2Z
2018-09-20T21:18:29.456Z,1537478309.456 [ballast_and_trim:NeedComms:C:A_Timeout] Running Loop=1
2018-09-20T21:18:29.456Z,1537478309.456 [ballast_and_trim:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms:C:A_Timeout
2018-09-20T21:18:29.456Z,1537478309.456 [ballast_and_trim:NeedComms:C:A_Timeout:A] Running Loop=1
2018-09-20T21:18:29.457Z,1537478309.457 [ballast_and_trim:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout
2018-09-20T21:18:29.462Z,1537478309.462 [ballast_and_trim:NeedComms:C:A_Timeout:A] Stopped
2018-09-20T21:18:29.463Z,1537478309.463 [ballast_and_trim:NeedComms:C:A_Timeout](INFO): Completed ballast_and_trim:NeedComms:C:A_Timeout
2018-09-20T21:18:29.463Z,1537478309.463 [ballast_and_trim:NeedComms:C] Stopped
2018-09-20T21:18:29.463Z,1537478309.463 [ballast_and_trim:NeedComms:D] Running Loop=1
2018-09-20T21:18:29.846Z,1537478309.846 [ballast_and_trim:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-20T21:18:33.994Z,1537478313.994 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T21:18:33.994Z,1537478313.994 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,
2018-09-20T21:18:34.140Z,1537478314.140 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T194020/Courier0024.lzma
2018-09-20T21:18:34.906Z,1537478314.906 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0024.lzma.bak
2018-09-20T21:18:34.907Z,1537478314.907 [DataOverHttps](INFO): SBD MOMSN=8549353
2018-09-20T21:18:44.280Z,1537478324.280 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Courier0027.lzma
2018-09-20T21:18:45.008Z,1537478325.008 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0027.lzma.bak
2018-09-20T21:18:45.008Z,1537478325.008 [DataOverHttps](INFO): SBD MOMSN=8549355
2018-09-20T21:18:54.349Z,1537478334.349 [DataOverHttps](INFO): Sending 2397 bytes from file Logs/20180920T194020/Express0025.lzma
2018-09-20T21:18:55.100Z,1537478335.100 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0025.lzma.bak
2018-09-20T21:18:55.100Z,1537478335.100 [DataOverHttps](INFO): SBD MOMSN=8549357
2018-09-20T21:19:04.559Z,1537478344.559 [NAL9602](INFO): Powering down
2018-09-20T21:19:04.743Z,1537478344.743 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180920T194020/Express0028.lzma
2018-09-20T21:19:05.498Z,1537478345.498 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0028.lzma.bak
2018-09-20T21:19:05.499Z,1537478345.499 [DataOverHttps](INFO): SBD MOMSN=8549407
2018-09-20T21:19:05.973Z,1537478345.973 [ballast_and_trim:NeedComms:D] Stopped
2018-09-20T21:19:05.978Z,1537478345.978 [ballast_and_trim:NeedComms:E] Running Loop=1
2018-09-20T21:19:06.374Z,1537478346.374 [ballast_and_trim:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-20T21:19:09.592Z,1537478349.592 [NAL9602](INFO): Powering up
2018-09-20T21:19:20.557Z,1537478360.557 [NAL9602](INFO): NAL9602 initialized
2018-09-20T21:19:33.765Z,1537478373.765 [NAL9602](INFO): SBD MO Status=0, MOMSN=882, MT Status=0, MTMSN=0
2018-09-20T21:19:33.765Z,1537478373.765 [NAL9602](INFO): No messages in MT queue
2018-09-20T21:25:11.344Z,1537478711.344 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T21:25:11.344Z,1537478711.344 [NAL9602] Data Fault, FailCount= 1
2018-09-20T21:25:11.344Z,1537478711.344 [NAL9602](ERROR): Data Fault
2018-09-20T21:25:11.424Z,1537478711.424 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T21:25:11.747Z,1537478711.747 [NAL9602](INFO): Powering down
2018-09-20T21:25:12.630Z,1537478712.630 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T21:25:12.630Z,1537478712.630 [NAL9602] No Fault, FailCount= 1
2018-09-20T21:25:42.143Z,1537478742.143 [NAL9602](INFO): Powering up NAL9602
2018-09-20T21:25:52.944Z,1537478752.944 [NAL9602](INFO): NAL9602 initialized
2018-09-20T21:26:06.211Z,1537478766.211 [ballast_and_trim:NeedComms:E](INFO): Timed out from 2018-09-20T21:19:05.0Z
2018-09-20T21:26:06.212Z,1537478766.212 [ballast_and_trim:NeedComms:E:A_Timeout] Running Loop=1
2018-09-20T21:26:06.212Z,1537478766.212 [ballast_and_trim:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms:E:A_Timeout
2018-09-20T21:26:06.212Z,1537478766.212 [ballast_and_trim:NeedComms:E:A_Timeout:A] Running Loop=1
2018-09-20T21:26:06.213Z,1537478766.213 [ballast_and_trim:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2018-09-20T21:26:06.215Z,1537478766.215 [ballast_and_trim:NeedComms:E:A_Timeout:A] Stopped
2018-09-20T21:26:06.215Z,1537478766.215 [ballast_and_trim:NeedComms:E:A_Timeout](INFO): Completed ballast_and_trim:NeedComms:E:A_Timeout
2018-09-20T21:26:06.215Z,1537478766.215 [ballast_and_trim:NeedComms:E] Stopped
2018-09-20T21:26:06.217Z,1537478766.217 [ballast_and_trim:NeedComms](INFO): Completed ballast_and_trim:NeedComms
2018-09-20T21:26:06.217Z,1537478766.217 [ballast_and_trim:NeedComms] Stopped
2018-09-20T21:26:06.217Z,1537478766.217 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms
2018-09-20T21:26:06.217Z,1537478766.217 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped
2018-09-20T21:26:06.218Z,1537478766.218 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T21:26:06.612Z,1537478766.612 [ballast_and_trim:StartingMission:StartingMission](INFO): Completed ballast_and_trim:StartingMission:StartingMission
2018-09-20T21:26:06.612Z,1537478766.612 [ballast_and_trim:StartingMission:StartingMission] Stopped
2018-09-20T21:26:06.613Z,1537478766.613 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission:StartingMission
2018-09-20T21:26:06.613Z,1537478766.613 [ballast_and_trim:StartingMission](INFO): Completed ballast_and_trim:StartingMission
2018-09-20T21:26:06.613Z,1537478766.613 [ballast_and_trim:StartingMission] Stopped
2018-09-20T21:26:06.613Z,1537478766.613 [ballast_and_trim:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission
2018-09-20T21:26:06.613Z,1537478766.613 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Running Loop=1
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim] Running Loop=1
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:A] Running Loop=1
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:B] Running Loop=1
2018-09-20T21:26:06.618Z,1537478766.618 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:C] Running Loop=1
2018-09-20T21:26:06.619Z,1537478766.619 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:D] Running Loop=1
2018-09-20T21:26:07.053Z,1537478767.053 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:D](INFO): Going to target depth. Speed set to 1.000000 m/s 
2018-09-20T21:26:07.062Z,1537478767.062 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:D] Stopped
2018-09-20T21:26:07.062Z,1537478767.062 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth] Running Loop=1
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Initialize.
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Running Loop=1
2018-09-20T21:26:07.063Z,1537478767.063 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch](DEBUG): Initialize.
2018-09-20T21:26:07.064Z,1537478767.064 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:C] Running Loop=1
2018-09-20T21:26:07.066Z,1537478767.066 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:B] Running Loop=1
2018-09-20T21:26:07.067Z,1537478767.067 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:A] Running Loop=1
2018-09-20T21:26:07.417Z,1537478767.417 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Running Loop=1
2018-09-20T21:26:07.418Z,1537478767.418 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Running Loop=1
2018-09-20T21:26:24.734Z,1537478784.734 [BuoyancyServo](FAULT): Overload Error
2018-09-20T21:26:24.734Z,1537478784.734 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-20T21:26:24.734Z,1537478784.734 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T21:26:24.862Z,1537478784.862 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-09-20T21:26:25.159Z,1537478785.159 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T21:26:25.159Z,1537478785.159 [BuoyancyServo](INFO): Powering down
2018-09-20T21:26:27.280Z,1537478787.280 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T21:26:27.280Z,1537478787.280 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T21:26:27.622Z,1537478787.622 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T21:26:27.743Z,1537478787.743 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T21:26:36.962Z,1537478796.962 [NAL9602](INFO): Powering down
2018-09-20T21:26:39.295Z,1537478799.295 [Radio_Surface](INFO): Powering down
2018-09-20T21:26:39.698Z,1537478799.698 [Radio_Surface](FAULT): LCB fault:  Software Overcurrent.
2018-09-20T21:26:39.698Z,1537478799.698 [Radio_Surface] Hardware Fault, FailCount= 1
2018-09-20T21:26:39.698Z,1537478799.698 [Radio_Surface](ERROR): Hardware Fault
2018-09-20T21:26:39.866Z,1537478799.866 [CBIT](ERROR): Hardware Fault in component: Radio_Surface
2018-09-20T21:26:40.578Z,1537478800.578 [CBIT](INFO): Clearing failed state for component Radio_Surface
2018-09-20T21:26:40.578Z,1537478800.578 [Radio_Surface] No Fault, FailCount= 1
2018-09-20T21:26:40.910Z,1537478800.910 [Radio_Surface](INFO): Powering up
2018-09-20T21:26:46.571Z,1537478806.571 [Radio_Surface](INFO): Powering down
2018-09-20T21:26:57.096Z,1537478817.096 [Radio_Surface](INFO): Powering up
2018-09-20T21:27:15.423Z,1537478835.423 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T21:27:15.423Z,1537478835.423 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,32768,-32768,V
2018-09-20T21:27:18.119Z,1537478838.119 [Radio_Surface](INFO): Powering down
2018-09-20T21:27:24.178Z,1537478844.178 [Radio_Surface](INFO): Powering up
2018-09-20T21:27:33.483Z,1537478853.483 [Radio_Surface](INFO): Powering down
2018-09-20T21:27:37.986Z,1537478857.986 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:27:39.542Z,1537478859.542 [Radio_Surface](INFO): Powering up
2018-09-20T21:27:52.087Z,1537478872.087 [Radio_Surface](INFO): Powering down
2018-09-20T21:27:55.055Z,1537478875.055 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-20T21:27:55.055Z,1537478875.055 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-2768,-32768,-32768,V
2018-09-20T21:28:38.090Z,1537478918.090 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:28:48.959Z,1537478928.959 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout] Stopped
2018-09-20T21:28:48.960Z,1537478928.960 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:LeaveSurfaceTimeout
2018-09-20T21:29:13.025Z,1537478953.025 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.170197
2018-09-20T21:30:06.820Z,1537479006.820 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T21:30:38.073Z,1537479038.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:31:38.218Z,1537479098.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:32:38.326Z,1537479158.326 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:33:32.710Z,1537479212.710 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T21:33:32.710Z,1537479212.710 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T21:33:32.710Z,1537479212.710 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-20T21:33:32.710Z,1537479212.710 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T21:33:32.748Z,1537479212.748 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T21:33:32.880Z,1537479212.880 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T21:33:32.881Z,1537479212.881 [BuoyancyServo](INFO): Powering down
2018-09-20T21:33:34.439Z,1537479214.439 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-20T21:33:34.439Z,1537479214.439 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-20T21:33:34.439Z,1537479214.439 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T21:33:34.877Z,1537479214.877 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T21:33:34.877Z,1537479214.877 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T21:33:35.253Z,1537479215.253 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T21:33:35.368Z,1537479215.368 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T21:33:38.491Z,1537479218.491 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:34:38.484Z,1537479278.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:35:38.488Z,1537479338.488 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:36:38.878Z,1537479398.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:37:38.878Z,1537479458.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:37:55.740Z,1537479475.740 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-20T21:37:55.741Z,1537479475.741 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,       +20. +410.83,       -0.17,  10.22,  0.00
2018-09-20T21:38:00.931Z,1537479480.931 [RDI_Pathfinder](ERROR): Failed to parse:
:H 14.765, -0.091, 0.000
2018-09-20T21:38:27.735Z,1537479507.735 [RDI_Pathfinder](ERROR): Unknown system health message
2018-09-20T21:38:27.735Z,1537479507.735 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2018-09-20T21:38:27.735Z,1537479507.735 [RDI_Pathfinder](ERROR): Hardware Fault
2018-09-20T21:38:27.950Z,1537479507.950 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2018-09-20T21:38:28.178Z,1537479508.178 [RDI_Pathfinder](INFO): Powering down
2018-09-20T21:38:28.996Z,1537479508.996 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-09-20T21:38:28.996Z,1537479508.996 [RDI_Pathfinder] No Fault, FailCount= 0
2018-09-20T21:38:38.878Z,1537479518.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:38:42.500Z,1537479522.500 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-20T21:39:39.277Z,1537479579.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:40:38.882Z,1537479638.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:41:39.282Z,1537479699.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:42:39.678Z,1537479759.678 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:43:39.678Z,1537479819.678 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:44:40.086Z,1537479880.086 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:45:40.077Z,1537479940.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:46:40.478Z,1537480000.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:47:38.932Z,1537480058.932 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T21:47:40.478Z,1537480060.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:48:40.483Z,1537480120.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:49:40.485Z,1537480180.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:50:40.878Z,1537480240.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:51:40.878Z,1537480300.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:52:41.277Z,1537480361.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:53:41.680Z,1537480421.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:54:42.095Z,1537480482.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:55:42.080Z,1537480542.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:56:42.479Z,1537480602.479 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:57:42.478Z,1537480662.478 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:58:42.482Z,1537480722.482 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T21:59:18.526Z,1537480758.526 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T21:59:18.526Z,1537480758.526 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092015050142,35.0, -0.1,   09,  0
2018-09-20T21:59:43.277Z,1537480783.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:00:43.278Z,1537480843.278 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:01:43.282Z,1537480903.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:02:43.678Z,1537480963.678 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:03:43.680Z,1537481023.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:04:44.080Z,1537481084.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:05:08.920Z,1537481108.920 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T22:05:44.477Z,1537481144.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:06:44.482Z,1537481204.482 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:07:44.885Z,1537481264.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:08:44.877Z,1537481324.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:09:40.275Z,1537481380.275 [WetLabsSeaOWL_UV_A](DEBUG): Error parsing data: SEA25	2685	263	2186	2186	3340	3340	76	305	305

2018-09-20T22:09:44.506Z,1537481384.506 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,  +179,  +874,    -7,-32768,A
2018-09-20T22:09:44.884Z,1537481384.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:10:45.282Z,1537481445.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:11:07.339Z,1537481467.339 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch](INFO): Timed out from 2018-09-20T21:26:07.1Z
2018-09-20T22:11:07.339Z,1537481467.339 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout] Running Loop=1
2018-09-20T22:11:07.340Z,1537481467.340 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout
2018-09-20T22:11:07.340Z,1537481467.340 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:A] Running Loop=1
2018-09-20T22:11:07.340Z,1537481467.340 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:A](IMPORTANT): Timed out trying to reach the target depth. Stopping mission at current depth of 34.505775 m 
2018-09-20T22:11:07.342Z,1537481467.342 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:A] Stopped
2018-09-20T22:11:07.343Z,1537481467.343 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute] Running Loop=1
2018-09-20T22:11:07.740Z,1537481467.740 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute](INFO): Executing command stop
2018-09-20T22:11:07.756Z,1537481467.756 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout:B.Execute] Stopped
2018-09-20T22:11:07.757Z,1537481467.757 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout](INFO): Completed ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch:A_Timeout
2018-09-20T22:11:07.757Z,1537481467.757 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:C.Pitch] Stopped
2018-09-20T22:11:07.757Z,1537481467.757 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth] Stopped
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed] Stopped
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:GoToTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:A] Running Loop=1
2018-09-20T22:11:07.762Z,1537481467.762 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed] Running Loop=1
2018-09-20T22:11:07.763Z,1537481467.763 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Initialize.
2018-09-20T22:11:07.763Z,1537481467.763 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch] Running Loop=1
2018-09-20T22:11:07.763Z,1537481467.763 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch](DEBUG): Initialize.
2018-09-20T22:11:07.763Z,1537481467.763 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:D] Running Loop=1
2018-09-20T22:11:07.838Z,1537481467.838 [CommandLine](IMPORTANT): got command stop
2018-09-20T22:11:07.838Z,1537481467.838 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-09-20T22:11:08.163Z,1537481468.163 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:D](INFO): Waiting for the vehicle to settle. Depth = 34.483166 m 
2018-09-20T22:11:08.165Z,1537481468.165 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:D] Stopped
2018-09-20T22:11:08.165Z,1537481468.165 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait] Running Loop=1
2018-09-20T22:11:08.165Z,1537481468.165 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Initialize Wait Component.
2018-09-20T22:11:08.165Z,1537481468.165 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch] Running Loop=1
2018-09-20T22:11:08.192Z,1537481468.192 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed] Running Loop=1
2018-09-20T22:11:08.214Z,1537481468.214 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:A] Running Loop=1
2018-09-20T22:11:08.229Z,1537481468.229 [MissionManager](INFO): MissionManager is completed.
2018-09-20T22:11:08.229Z,1537481468.229 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2018-09-20T22:11:08.229Z,1537481468.229 [ballast_and_trim] Stopped
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:Science] Stopped
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:Science:A] Stopped
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:Science:Read_Oil] Stopped
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:StandardEnvelopes] Stopped
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::uninitialize ballast_and_trim:StandardEnvelopes
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-20T22:11:08.230Z,1537481468.230 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim] Stopped
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Stopped
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim] Stopped
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim
2018-09-20T22:11:08.231Z,1537481468.231 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:A] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:B] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:C] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:A] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:B.SetSpeed](DEBUG): Uninitialize.
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:C.Pitch] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait] Stopped
2018-09-20T22:11:08.232Z,1537481468.232 [ballast_and_trim:RunBallastAndTrim:RunDepth1:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T22:11:08.506Z,1537481468.506 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-20T22:11:08.562Z,1537481468.562 [MissionManager](IMPORTANT): Started mission Default
2018-09-20T22:11:08.562Z,1537481468.562 [Default] Running Loop=1
2018-09-20T22:11:08.563Z,1537481468.563 [Default](DEBUG): Aggregate::initialize Default
2018-09-20T22:11:08.563Z,1537481468.563 [Default:B.GoToSurface] Running Loop=1
2018-09-20T22:11:08.563Z,1537481468.563 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-20T22:11:08.563Z,1537481468.563 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-20T22:11:08.563Z,1537481468.563 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-20T22:11:08.564Z,1537481468.564 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-20T22:11:08.564Z,1537481468.564 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-20T22:11:08.565Z,1537481468.565 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-20T22:11:08.565Z,1537481468.565 [Default:A.Wait] Running Loop=1
2018-09-20T22:11:08.565Z,1537481468.565 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-20T22:11:09.339Z,1537481469.339 [Aanderaa_O2](INFO): Powering down
2018-09-20T22:11:09.387Z,1537481469.387 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (3 active estimators).
2018-09-20T22:11:13.590Z,1537481473.590 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T22:11:13.590Z,1537481473.590 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T22:11:13.590Z,1537481473.590 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-20T22:11:13.590Z,1537481473.590 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T22:11:13.666Z,1537481473.666 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T22:11:13.748Z,1537481473.748 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T22:11:13.762Z,1537481473.762 [BuoyancyServo](INFO): Powering down
2018-09-20T22:11:15.367Z,1537481475.367 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-20T22:11:15.367Z,1537481475.367 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-20T22:11:15.367Z,1537481475.367 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T22:11:15.870Z,1537481475.870 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T22:11:15.870Z,1537481475.870 [BuoyancyServo] No Fault, FailCount= 1
2018-09-20T22:11:16.152Z,1537481476.152 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T22:11:16.270Z,1537481476.270 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T22:11:21.734Z,1537481481.734 [Default:A.Wait](INFO): Done Waiting.
2018-09-20T22:11:21.735Z,1537481481.735 [Default:A.Wait] Stopped
2018-09-20T22:11:21.735Z,1537481481.735 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T22:11:24.062Z,1537481484.062 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-20T22:11:24.062Z,1537481484.062 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-20T22:11:24.062Z,1537481484.062 [BuoyancyServo] Communications Fault, FailCount= 2
2018-09-20T22:11:24.062Z,1537481484.062 [BuoyancyServo](ERROR): Communications Fault
2018-09-20T22:11:24.146Z,1537481484.146 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-20T22:11:24.326Z,1537481484.326 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T22:11:24.327Z,1537481484.327 [BuoyancyServo](INFO): Powering down
2018-09-20T22:11:26.261Z,1537481486.261 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-20T22:11:26.261Z,1537481486.261 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-09-20T22:11:26.265Z,1537481486.265 [BuoyancyServo](ERROR): Hardware Fault
2018-09-20T22:11:26.590Z,1537481486.590 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T22:11:26.590Z,1537481486.590 [RDI_Pathfinder](ERROR): Failed to parse:
:TS5170942,35.0, -0.1,   0.0,1448.9,  0
2018-09-20T22:11:26.722Z,1537481486.722 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-20T22:11:26.722Z,1537481486.722 [BuoyancyServo] No Fault, FailCount= 2
2018-09-20T22:11:27.040Z,1537481487.040 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-20T22:11:27.159Z,1537481487.159 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-20T22:11:44.968Z,1537481504.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:12:06.595Z,1537481526.595 [RDI_Pathfinder](ERROR): only read 2 of 5 data items
2018-09-20T22:12:06.595Z,1537481526.595 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +958,   +48,A
2018-09-20T22:12:42.658Z,1537481562.658 [Default:B.GoToSurface](ERROR): Pitch down timeout. Pitch: -1.21
2018-09-20T22:12:43.050Z,1537481563.050 [Radio_Surface](INFO): Powering up
2018-09-20T22:12:44.966Z,1537481564.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:12:48.707Z,1537481568.707 [Radio_Surface](INFO): Powering down
2018-09-20T22:13:44.969Z,1537481624.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:14:44.968Z,1537481684.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:15:44.969Z,1537481744.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:16:45.768Z,1537481805.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:16:51.819Z,1537481811.819 [Default:CheckIn] Running Loop=1
2018-09-20T22:16:51.819Z,1537481811.819 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-20T22:16:51.819Z,1537481811.819 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-20T22:16:52.066Z,1537481812.066 [Radio_Surface](INFO): Powering up
2018-09-20T22:16:52.567Z,1537481812.567 [NAL9602](INFO): Powering up
2018-09-20T22:17:03.552Z,1537481823.552 [NAL9602](INFO): NAL9602 initialized
2018-09-20T22:17:04.340Z,1537481824.340 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T22:17:04.340Z,1537481824.340 [NAL9602] Data Fault, FailCount= 2
2018-09-20T22:17:04.340Z,1537481824.340 [NAL9602](ERROR): Data Fault
2018-09-20T22:17:04.463Z,1537481824.463 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T22:17:04.747Z,1537481824.747 [NAL9602](INFO): Powering down
2018-09-20T22:17:05.663Z,1537481825.663 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T22:17:05.663Z,1537481825.663 [NAL9602] No Fault, FailCount= 2
2018-09-20T22:17:35.143Z,1537481855.143 [NAL9602](INFO): Powering up NAL9602
2018-09-20T22:17:45.937Z,1537481865.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:17:45.947Z,1537481865.947 [NAL9602](INFO): NAL9602 initialized
2018-09-20T22:18:02.748Z,1537481882.748 [NAL9602](INFO): SBD MO Status=0, MOMSN=883, MT Status=0, MTMSN=0
2018-09-20T22:18:02.749Z,1537481882.749 [NAL9602](INFO): No messages in MT queue
2018-09-20T22:18:46.337Z,1537481926.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:19:46.737Z,1537481986.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-20T22:20:21.550Z,1537482021.550 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002455
2018-09-20T22:21:52.120Z,1537482112.120 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T22:16:51.8Z
2018-09-20T22:21:52.120Z,1537482112.120 [Default:CheckIn:Read_GPS] Stopped
2018-09-20T22:21:52.121Z,1537482112.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-20T22:21:56.987Z,1537482116.987 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T194020/Courier0030.lzma
2018-09-20T22:21:57.746Z,1537482117.746 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0030.lzma.bak
2018-09-20T22:21:57.747Z,1537482117.747 [DataOverHttps](INFO): SBD MOMSN=8549661
2018-09-20T22:22:06.951Z,1537482126.951 [DataOverHttps](INFO): Sending 3421 bytes from file Logs/20180920T194020/Express0031.lzma
2018-09-20T22:22:07.702Z,1537482127.702 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0031.lzma.bak
2018-09-20T22:22:07.702Z,1537482127.702 [DataOverHttps](INFO): SBD MOMSN=8549663
2018-09-20T22:22:08.190Z,1537482128.190 [Default:CheckIn:Read_Iridium] Stopped
2018-09-20T22:22:08.190Z,1537482128.190 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-20T22:22:08.191Z,1537482128.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-20T22:22:27.319Z,1537482147.319 [NAL9602](INFO): Powering down
2018-09-20T22:22:38.149Z,1537482158.149 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-20T22:23:13.455Z,1537482193.455 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2018-09-20T22:27:08.944Z,1537482428.944 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-09-20T22:27:08.944Z,1537482428.944 [Default:CheckIn:C.Wait] Stopped
2018-09-20T22:27:08.944Z,1537482428.944 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T22:27:08.944Z,1537482428.944 [Default:CheckIn:D] Running Loop=1
2018-09-20T22:27:09.353Z,1537482429.353 [Default:CheckIn:D] Stopped
2018-09-20T22:27:09.353Z,1537482429.353 [Default:CheckIn:E] Running Loop=1
2018-09-20T22:27:09.741Z,1537482429.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.013170 min 
2018-09-20T22:27:09.746Z,1537482429.746 [Default:CheckIn:E] Stopped
2018-09-20T22:27:09.746Z,1537482429.746 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-09-20T22:27:09.746Z,1537482429.746 [Default:CheckIn] Stopped
2018-09-20T22:27:09.746Z,1537482429.746 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-20T22:27:09.746Z,1537482429.746 [Default:CheckIn](INFO): Running loop #2
2018-09-20T22:27:09.747Z,1537482429.747 [Default:CheckIn] Running Loop=2
2018-09-20T22:27:09.747Z,1537482429.747 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-20T22:27:09.747Z,1537482429.747 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-20T22:27:13.272Z,1537482433.272 [NAL9602](INFO): Powering up
2018-09-20T22:27:24.259Z,1537482444.259 [NAL9602](INFO): NAL9602 initialized
2018-09-20T22:27:39.438Z,1537482459.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=884, MT Status=0, MTMSN=0
2018-09-20T22:27:39.438Z,1537482459.438 [NAL9602](INFO): No messages in MT queue
2018-09-20T22:27:48.615Z,1537482468.615 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-20T22:27:48.615Z,1537482468.615 [NAL9602] Data Fault, FailCount= 1
2018-09-20T22:27:48.615Z,1537482468.615 [NAL9602](ERROR): Data Fault
2018-09-20T22:27:48.709Z,1537482468.709 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-20T22:27:49.011Z,1537482469.011 [NAL9602](INFO): Powering down
2018-09-20T22:27:49.941Z,1537482469.941 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-20T22:27:49.942Z,1537482469.942 [NAL9602] No Fault, FailCount= 1
2018-09-20T22:28:19.139Z,1537482499.139 [NAL9602](INFO): Powering up NAL9602
2018-09-20T22:28:23.562Z,1537482503.562 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-20T22:28:23.563Z,1537482503.563 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092015340642,35.0, -0.1,   0.0,10
2018-09-20T22:28:30.323Z,1537482510.323 [NAL9602](INFO): NAL9602 initialized
2018-09-20T22:29:14.478Z,1537482554.478 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-20T22:32:10.041Z,1537482730.041 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-20T22:27:09.7Z
2018-09-20T22:32:10.041Z,1537482730.041 [Default:CheckIn:Read_GPS] Stopped
2018-09-20T22:32:10.042Z,1537482730.042 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-20T22:32:13.906Z,1537482733.906 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20180920T194020/Courier0033.lzma
2018-09-20T22:32:15.050Z,1537482735.050 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Courier0033.lzma.bak
2018-09-20T22:32:15.051Z,1537482735.051 [DataOverHttps](INFO): SBD MOMSN=8549725
2018-09-20T22:32:24.961Z,1537482744.961 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20180920T194020/Express0034.lzma
2018-09-20T22:32:25.746Z,1537482745.746 [DataOverHttps](INFO): Moved sent file to Logs/20180920T194020/Express0034.lzma.bak
2018-09-20T22:32:25.746Z,1537482745.746 [DataOverHttps](INFO): SBD MOMSN=8549727
2018-09-20T22:32:26.201Z,1537482746.201 [Default:CheckIn:Read_Iridium] Stopped
2018-09-20T22:32:26.201Z,1537482746.201 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-20T22:32:26.201Z,1537482746.201 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-20T22:32:44.019Z,1537482764.019 [NAL9602](INFO): Powering down
2018-09-20T22:33:31.298Z,1537482811.298 [DataOverHttps](IMPORTANT): SBD MTMSN=20180920T223331
2018-09-20T22:33:35.499Z,1537482815.499 [DataOverHttps](INFO): Received command:restart app
2018-09-20T22:33:35.570Z,1537482815.570 [CommandLine](IMPORTANT): got command restart application
2018-09-20T22:33:36.574Z,1537482816.574 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T22:33:36.574Z,1537482816.574 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:36.674Z,1537482816.674 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-09-20T22:33:36.674Z,1537482816.674 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:36.675Z,1537482816.675 [CommandLine](INFO): Join timeout helper Thread ID is 2783
2018-09-20T22:33:36.698Z,1537482816.698 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-20T22:33:36.698Z,1537482816.698 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:36.698Z,1537482816.698 [NavChartDb](INFO): Join timeout helper Thread ID is 2784
2018-09-20T22:33:37.022Z,1537482817.022 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T22:33:37.022Z,1537482817.022 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.022Z,1537482817.022 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-20T22:33:37.023Z,1537482817.023 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.023Z,1537482817.023 [Radio_Surface](INFO): Join timeout helper Thread ID is 2785
2018-09-20T22:33:37.054Z,1537482817.054 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T22:33:37.054Z,1537482817.054 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.062Z,1537482817.062 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-09-20T22:33:37.062Z,1537482817.062 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.062Z,1537482817.062 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2786
2018-09-20T22:33:37.210Z,1537482817.210 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T22:33:37.210Z,1537482817.210 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-20T22:33:37.211Z,1537482817.211 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.218Z,1537482817.218 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-20T22:33:37.218Z,1537482817.218 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.218Z,1537482817.218 [logger](INFO): Join timeout helper Thread ID is 2787
2018-09-20T22:33:37.246Z,1537482817.246 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T22:33:37.246Z,1537482817.246 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.258Z,1537482817.258 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-20T22:33:37.259Z,1537482817.259 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.259Z,1537482817.259 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-20T22:33:37.259Z,1537482817.259 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.259Z,1537482817.259 [controlThread](INFO): Join timeout helper Thread ID is 2788
2018-09-20T22:33:37.406Z,1537482817.406 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-20T22:33:37.406Z,1537482817.406 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-20T22:33:37.406Z,1537482817.406 [Aanderaa_O2](INFO): Powering down
2018-09-20T22:33:37.407Z,1537482817.407 [AHRS_M2](INFO): Powering down
2018-09-20T22:33:37.479Z,1537482817.479 [NAL9602](INFO): Powering down
2018-09-20T22:33:37.550Z,1537482817.550 [RDI_Pathfinder](INFO): Powering down
2018-09-20T22:33:37.551Z,1537482817.551 [RDI_PathfinderUp](INFO): Powering down
2018-09-20T22:33:37.552Z,1537482817.552 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-20T22:33:37.553Z,1537482817.553 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-20T22:33:37.554Z,1537482817.554 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-20T22:33:37.555Z,1537482817.555 [MissionManager](INFO): Uninitializing Mission Default
2018-09-20T22:33:37.555Z,1537482817.555 [Default] Stopped
2018-09-20T22:33:37.555Z,1537482817.555 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-20T22:33:37.555Z,1537482817.555 [Default:B.GoToSurface] Stopped
2018-09-20T22:33:37.555Z,1537482817.555 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-20T22:33:37.555Z,1537482817.555 [Default:CheckIn] Stopped
2018-09-20T22:33:37.555Z,1537482817.555 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-20T22:33:37.555Z,1537482817.555 [Default:CheckIn:C.Wait] Stopped
2018-09-20T22:33:37.556Z,1537482817.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-20T22:33:37.559Z,1537482817.559 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-20T22:33:37.559Z,1537482817.559 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-20T22:33:37.560Z,1537482817.560 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-20T22:33:37.560Z,1537482817.560 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-20T22:33:37.560Z,1537482817.560 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-20T22:33:37.560Z,1537482817.560 [BuoyancyServo](INFO): Powering down
2018-09-20T22:33:37.574Z,1537482817.574 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-09-20T22:33:37.574Z,1537482817.574 [ElevatorServo](INFO): Powering down
2018-09-20T22:33:37.575Z,1537482817.575 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-09-20T22:33:37.575Z,1537482817.575 [MassServo](INFO): Powering down
2018-09-20T22:33:37.576Z,1537482817.576 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-09-20T22:33:37.576Z,1537482817.576 [RudderServo](INFO): Powering down
2018-09-20T22:33:37.577Z,1537482817.577 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-20T22:33:37.577Z,1537482817.577 [ThrusterServo](INFO): Powering down
2018-09-20T22:33:37.578Z,1537482817.578 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-20T22:33:37.578Z,1537482817.578 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-20T22:33:37.579Z,1537482817.579 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-20T22:33:37.579Z,1537482817.579 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.644Z,1537482817.644 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.704Z,1537482817.704 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.747Z,1537482817.747 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-20T22:33:37.819Z,1537482817.819 [logger ThreadHandler](INFO): Thread cancelled.