2020-05-07T14:15:28.305Z,1588860928.305 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-05-07T14:15:28.306Z,1588860928.306 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-05-07T14:15:28.306Z,1588860928.306 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T14:15:28.306Z,1588860928.306 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-05-07T14:15:28.718Z,1588860928.718 [DefaultWithUndock:CheckIn:D] Stopped 2020-05-07T14:15:28.718Z,1588860928.718 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-05-07T14:15:29.116Z,1588860929.116 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 96.391439 min 2020-05-07T14:15:29.116Z,1588860929.116 [DefaultWithUndock:CheckIn:E] Stopped 2020-05-07T14:15:29.116Z,1588860929.116 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-05-07T14:15:29.133Z,1588860929.133 [DefaultWithUndock:CheckIn] Stopped 2020-05-07T14:15:29.133Z,1588860929.133 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-05-07T14:15:29.133Z,1588860929.133 [DefaultWithUndock:CheckIn](INFO): Running loop #18 2020-05-07T14:15:29.133Z,1588860929.133 [DefaultWithUndock:CheckIn] Running Loop=18 2020-05-07T14:15:29.133Z,1588860929.133 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-05-07T14:15:29.133Z,1588860929.133 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-05-07T14:15:31.081Z,1588860931.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141517.00,A,4133.16576,N,07042.39800,W,0.136,158.07,070520,,,D*74 2020-05-07T14:15:31.083Z,1588860931.083 [NAL9602](INFO): GPS fix at 20200507T141517: (41.552763, -70.706633) 2020-05-07T14:15:31.163Z,1588860931.163 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-05-07T14:15:31.163Z,1588860931.163 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-05-07T14:15:34.720Z,1588860934.720 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200507T123628/Courier0064.lzma 2020-05-07T14:15:35.707Z,1588860935.707 [DataOverHttps](INFO): Moved sent file to Logs/20200507T123628/Courier0064.lzma.bak 2020-05-07T14:15:35.707Z,1588860935.707 [DataOverHttps](INFO): SBD MOMSN=10269 2020-05-07T14:15:44.745Z,1588860944.745 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20200507T141145/Courier0000.lzma 2020-05-07T14:15:45.746Z,1588860945.746 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Courier0000.lzma.bak 2020-05-07T14:15:45.747Z,1588860945.747 [DataOverHttps](INFO): SBD MOMSN=10271 2020-05-07T14:15:54.785Z,1588860954.785 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20200507T123628/Express0065.lzma 2020-05-07T14:15:55.789Z,1588860955.789 [DataOverHttps](INFO): Moved sent file to Logs/20200507T123628/Express0065.lzma.bak 2020-05-07T14:15:55.790Z,1588860955.790 [DataOverHttps](INFO): SBD MOMSN=10275 2020-05-07T14:15:58.155Z,1588860958.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=11003, MT Status=0, MTMSN=0 2020-05-07T14:15:58.155Z,1588860958.155 [NAL9602](INFO): No messages in MT queue 2020-05-07T14:16:04.833Z,1588860964.833 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20200507T141145/Express0001.lzma 2020-05-07T14:16:05.838Z,1588860965.838 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0001.lzma.bak 2020-05-07T14:16:05.839Z,1588860965.839 [DataOverHttps](INFO): SBD MOMSN=10279 2020-05-07T14:16:07.116Z,1588860967.116 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-05-07T14:16:07.116Z,1588860967.116 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-05-07T14:16:07.116Z,1588860967.116 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-05-07T14:16:28.868Z,1588860988.868 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T14:16:30.514Z,1588860990.514 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:17:10.488Z,1588861030.488 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:17:21.159Z,1588861041.159 [DataOverHttps](IMPORTANT): SBD MTMSN=20200507T141707 2020-05-07T14:17:25.177Z,1588861045.177 [DataOverHttps](INFO): Received command:load Maintenance/ballast_and_trim.xml;set ballast_and_trim.Depth1 6 meter;set ballast_and_trim.MinAltitude 3 meter;set ballast_and_trim.MaxDepth 9 meter;set ballast_and_trim.MinOffshore 500 meter;run 2020-05-07T14:17:25.200Z,1588861045.200 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/ballast_and_trim.xml 2020-05-07T14:17:25.200Z,1588861045.200 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/ballast_and_trim.xml 2020-05-07T14:17:25.304Z,1588861045.304 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min 2020-05-07T14:17:25.307Z,1588861045.307 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2020-05-07T14:17:25.310Z,1588861045.310 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m 2020-05-07T14:17:25.313Z,1588861045.313 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2020-05-07T14:17:25.316Z,1588861045.316 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2020-05-07T14:17:25.319Z,1588861045.319 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSpeed = 1.000000 m/s 2020-05-07T14:17:25.322Z,1588861045.322 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthTimeout = 20.000000 min 2020-05-07T14:17:25.324Z,1588861045.324 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthRate = 0.400000 m/s 2020-05-07T14:17:25.327Z,1588861045.327 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachPitchLimit = 20.000000 arcdeg 2020-05-07T14:17:25.346Z,1588861045.346 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSettleTimePreDive = 0.000000 min 2020-05-07T14:17:25.357Z,1588861045.357 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2020-05-07T14:17:25.360Z,1588861045.360 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2020-05-07T14:17:25.367Z,1588861045.367 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2020-05-07T14:17:25.370Z,1588861045.370 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2020-05-07T14:17:25.387Z,1588861045.387 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2020-05-07T14:17:25.390Z,1588861045.390 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 % 2020-05-07T14:17:25.393Z,1588861045.393 [MissionManager](INFO): DefineArg ballast_and_trim.kiDepthBuoy = 0.300000 1/s 2020-05-07T14:17:25.396Z,1588861045.396 [MissionManager](INFO): DefineArg ballast_and_trim.massDeadband = 0.250000 mm 2020-05-07T14:17:25.431Z,1588861045.431 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m 2020-05-07T14:17:25.434Z,1588861045.434 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m 2020-05-07T14:17:25.444Z,1588861045.444 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km 2020-05-07T14:17:25.463Z,1588861045.463 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool 2020-05-07T14:17:25.463Z,1588861045.463 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2020-05-07T14:17:25.890Z,1588861045.890 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUS = 0 bool 2020-05-07T14:17:25.905Z,1588861045.905 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSmaxPitch = 60.000000 arcdeg 2020-05-07T14:17:25.914Z,1588861045.914 [MissionManager](INFO): DefineArg ballast_and_trim:Science.SampleISUSminPitch = -60.000000 arcdeg 2020-05-07T14:17:25.917Z,1588861045.917 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2020-05-07T14:17:25.920Z,1588861045.920 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2020-05-07T14:17:25.927Z,1588861045.927 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2020-05-07T14:17:25.945Z,1588861045.945 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2020-05-07T14:17:25.948Z,1588861045.948 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2020-05-07T14:17:25.953Z,1588861045.953 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2020-05-07T14:17:25.956Z,1588861045.956 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2020-05-07T14:17:25.969Z,1588861045.969 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2020-05-07T14:17:25.971Z,1588861045.971 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2020-05-07T14:17:25.974Z,1588861045.974 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2020-05-07T14:17:25.985Z,1588861045.985 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2020-05-07T14:17:25.988Z,1588861045.988 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool 2020-05-07T14:17:25.996Z,1588861045.996 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool 2020-05-07T14:17:26.000Z,1588861046.000 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 0.000000 bool 2020-05-07T14:17:26.012Z,1588861046.012 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledTurbulence_NPS = 0.000000 bool 2020-05-07T14:17:26.026Z,1588861046.026 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 0.000000 bool 2020-05-07T14:17:26.034Z,1588861046.034 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool 2020-05-07T14:17:26.041Z,1588861046.041 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool 2020-05-07T14:17:26.049Z,1588861046.049 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledISUS = 0.000000 bool 2020-05-07T14:17:26.055Z,1588861046.055 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2020-05-07T14:17:26.058Z,1588861046.058 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2020-05-07T14:17:26.065Z,1588861046.065 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m 2020-05-07T14:17:26.068Z,1588861046.068 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m 2020-05-07T14:17:26.082Z,1588861046.082 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2020-05-07T14:17:26.084Z,1588861046.084 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2020-05-07T14:17:26.106Z,1588861046.106 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l 2020-05-07T14:17:26.124Z,1588861046.124 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m 2020-05-07T14:17:26.135Z,1588861046.135 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC 2020-05-07T14:17:26.139Z,1588861046.139 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2020-05-07T14:17:26.150Z,1588861046.150 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2020-05-07T14:17:26.155Z,1588861046.155 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l 2020-05-07T14:17:26.166Z,1588861046.166 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m 2020-05-07T14:17:26.173Z,1588861046.173 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2020-05-07T14:17:26.176Z,1588861046.176 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2020-05-07T14:17:26.183Z,1588861046.183 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m 2020-05-07T14:17:26.187Z,1588861046.187 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m 2020-05-07T14:17:26.198Z,1588861046.198 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2020-05-07T14:17:26.205Z,1588861046.205 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2020-05-07T14:17:26.208Z,1588861046.208 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2020-05-07T14:17:26.266Z,1588861046.266 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3 2020-05-07T14:17:26.278Z,1588861046.278 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m 2020-05-07T14:17:26.289Z,1588861046.289 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2020-05-07T14:17:26.292Z,1588861046.292 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2020-05-07T14:17:26.300Z,1588861046.300 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb 2020-05-07T14:17:26.311Z,1588861046.311 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m 2020-05-07T14:17:26.325Z,1588861046.325 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2020-05-07T14:17:26.328Z,1588861046.328 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2020-05-07T14:17:26.340Z,1588861046.340 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m 2020-05-07T14:17:26.379Z,1588861046.379 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-05-07T14:17:26.403Z,1588861046.403 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2020-05-07T14:17:26.428Z,1588861046.428 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-05-07T14:17:26.460Z,1588861046.460 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-05-07T14:17:26.495Z,1588861046.495 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-05-07T14:17:26.523Z,1588861046.523 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2020-05-07T14:17:26.612Z,1588861046.612 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 3.000000 h 2020-05-07T14:17:26.654Z,1588861046.654 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min 2020-05-07T14:17:26.662Z,1588861046.662 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg 2020-05-07T14:17:26.677Z,1588861046.677 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s 2020-05-07T14:17:26.680Z,1588861046.680 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s 2020-05-07T14:17:26.701Z,1588861046.701 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min 2020-05-07T14:17:26.704Z,1588861046.704 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min 2020-05-07T14:17:26.718Z,1588861046.718 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-07T14:17:26.758Z,1588861046.758 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2020-05-07T14:17:26.806Z,1588861046.806 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinAltitude = 5.000000 m 2020-05-07T14:17:26.813Z,1588861046.813 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepth = 200.000000 m 2020-05-07T14:17:26.816Z,1588861046.816 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinOffshore = 2000.000000 m 2020-05-07T14:17:26.822Z,1588861046.822 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2020-05-07T14:17:26.837Z,1588861046.837 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2020-05-07T14:17:26.858Z,1588861046.858 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2020-05-07T14:17:26.903Z,1588861046.903 [ballast_and_trim:CheckTargetDepths:CheckDepth1:B.Execute](DEBUG): Construct Execute. 2020-05-07T14:17:26.911Z,1588861046.911 [ballast_and_trim:CheckTargetDepths:CheckDepth2:B.Execute](DEBUG): Construct Execute. 2020-05-07T14:17:26.929Z,1588861046.929 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml 2020-05-07T14:17:27.228Z,1588861047.228 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2020-05-07T14:17:27.245Z,1588861047.245 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2020-05-07T14:17:27.248Z,1588861047.248 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2020-05-07T14:17:27.251Z,1588861047.251 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2020-05-07T14:17:27.255Z,1588861047.255 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2020-05-07T14:17:27.265Z,1588861047.265 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2020-05-07T14:17:27.268Z,1588861047.268 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2020-05-07T14:17:27.283Z,1588861047.283 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2020-05-07T14:17:27.286Z,1588861047.286 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2020-05-07T14:17:27.295Z,1588861047.295 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2020-05-07T14:17:27.306Z,1588861047.306 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2020-05-07T14:17:27.313Z,1588861047.313 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 % 2020-05-07T14:17:27.316Z,1588861047.316 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2020-05-07T14:17:27.323Z,1588861047.323 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = 0.300000 1/s 2020-05-07T14:17:27.337Z,1588861047.337 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2020-05-07T14:17:27.376Z,1588861047.376 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2020-05-07T14:17:27.390Z,1588861047.390 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2020-05-07T14:17:27.396Z,1588861047.396 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2020-05-07T14:17:27.408Z,1588861047.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2020-05-07T14:17:27.416Z,1588861047.416 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2020-05-07T14:17:27.482Z,1588861047.482 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2020-05-07T14:17:27.520Z,1588861047.520 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2020-05-07T14:17:27.523Z,1588861047.523 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2020-05-07T14:17:27.534Z,1588861047.534 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2020-05-07T14:17:27.550Z,1588861047.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2020-05-07T14:17:27.558Z,1588861047.558 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim. 2020-05-07T14:17:27.608Z,1588861047.608 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2020-05-07T14:17:27.614Z,1588861047.614 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = reader:ballast_and_trim.ApproachSpeed 2020-05-07T14:17:27.615Z,1588861047.615 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = reader:ballast_and_trim.ApproachDepthRate 2020-05-07T14:17:27.618Z,1588861047.618 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = reader:ballast_and_trim.ApproachPitchLimit 2020-05-07T14:17:27.620Z,1588861047.620 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = reader:ballast_and_trim.ApproachSettleTimePreDive 2020-05-07T14:17:27.630Z,1588861047.630 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.ApproachDepthTimeout 2020-05-07T14:17:27.632Z,1588861047.632 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2020-05-07T14:17:27.638Z,1588861047.638 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2020-05-07T14:17:27.639Z,1588861047.639 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2020-05-07T14:17:27.642Z,1588861047.642 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2020-05-07T14:17:27.643Z,1588861047.643 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2020-05-07T14:17:27.653Z,1588861047.653 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2020-05-07T14:17:27.655Z,1588861047.655 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = reader:ballast_and_trim.kiDepthBuoy 2020-05-07T14:17:27.661Z,1588861047.661 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = reader:ballast_and_trim.massDeadband 2020-05-07T14:17:27.742Z,1588861047.742 [MissionManager](DEBUG): Maximum duration of mission 90 Skip communications at start of mission. 35 NaN How much vertical drift from the specified depth is allowed durnig the mission. 0.1 Enables vehicle thruster at the commanded speed to reach target depth (set to 0 m/s to drift). 1 Maximum wait time for the vehicle to reach the targeted depth. 20 Descent depth rate (Positive depth rate means going down). 0.4 Max vehicle pitch (+/-) when decending from the surface. 20 Time duration for the vehicle to pump down the VBS *BEFORE* leaving the surface. 0 How long to wait after reaching target depth before starting ballast and trim. 10 Timeout for ballast and trim estimation. 45 Minimum time to run ballast and trim estimation before determining convergence (must be shorter than timeout). 10 Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). 0.25 Desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). 15 Confidence level that the estimator has reached the desired error range (larger values take longer to converge). 99.9 Bouyancy dive loop integral gain. 0.3 Degree of rounding in mass-shifter command output values. 0.25 5 52 2 1 Aborting ballast and trim mission. Target depth Depth1 exceeds the mission's MaxDepth setting (review mission parameters). stop Aborting ballast and trim mission. Target depth Depth2 exceeds the mission's MaxDepth setting (review mission parameters). stop 0 Checking for additional instructions before submerging. 2020-05-07T14:17:27.743Z,1588861047.743 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/ballast_and_trim.xml 2020-05-07T14:17:28.702Z,1588861048.702 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:17:37.767Z,1588861057.767 [CommandLine](IMPORTANT): got command set ballast_and_trim.Depth1 6.000000 meter 2020-05-07T14:17:37.768Z,1588861057.768 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinAltitude 3.000000 meter 2020-05-07T14:17:37.768Z,1588861057.768 [CommandLine](IMPORTANT): got command set ballast_and_trim.MaxDepth 9.000000 meter 2020-05-07T14:17:37.769Z,1588861057.769 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinOffshore 500.000000 meter 2020-05-07T14:17:37.770Z,1588861057.770 [CommandLine](IMPORTANT): got command run 2020-05-07T14:17:37.773Z,1588861057.773 [CommandLine](IMPORTANT): Running 2020-05-07T14:17:37.981Z,1588861057.981 [DefaultWithUndock] Stopped 2020-05-07T14:17:37.981Z,1588861057.981 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-05-07T14:17:37.981Z,1588861057.981 [DefaultWithUndock:C.GoToSurface] Stopped 2020-05-07T14:17:37.982Z,1588861057.982 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-07T14:17:37.982Z,1588861057.982 [DefaultWithUndock:CheckIn] Stopped 2020-05-07T14:17:37.982Z,1588861057.982 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-05-07T14:17:37.982Z,1588861057.982 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-05-07T14:17:37.982Z,1588861057.982 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T14:17:37.983Z,1588861057.983 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2020-05-07T14:17:37.983Z,1588861057.983 [ballast_and_trim] Running Loop=1 2020-05-07T14:17:37.984Z,1588861057.984 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2020-05-07T14:17:37.984Z,1588861057.984 [ballast_and_trim:Science] Running Loop=1 2020-05-07T14:17:37.984Z,1588861057.984 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2020-05-07T14:17:37.985Z,1588861057.985 [ballast_and_trim:Science:A] Running Loop=1 2020-05-07T14:17:37.985Z,1588861057.985 [ballast_and_trim:Science:B] Running Loop=1 2020-05-07T14:17:37.985Z,1588861057.985 [ballast_and_trim:Science:C] Running Loop=1 2020-05-07T14:17:37.985Z,1588861057.985 [ballast_and_trim:Science:D] Running Loop=1 2020-05-07T14:17:37.985Z,1588861057.985 [ballast_and_trim:Science:E] Running Loop=1 2020-05-07T14:17:37.986Z,1588861057.986 [ballast_and_trim:Science:F] Running Loop=1 2020-05-07T14:17:37.986Z,1588861057.986 [ballast_and_trim:Science:G] Running Loop=1 2020-05-07T14:17:37.986Z,1588861057.986 [ballast_and_trim:Science:H] Running Loop=1 2020-05-07T14:17:37.986Z,1588861057.986 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2020-05-07T14:17:37.986Z,1588861057.986 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2020-05-07T14:17:37.987Z,1588861057.987 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2020-05-07T14:17:37.987Z,1588861057.987 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2020-05-07T14:17:37.987Z,1588861057.987 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-05-07T14:17:37.993Z,1588861057.993 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2020-05-07T14:17:37.993Z,1588861057.993 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2020-05-07T14:17:37.994Z,1588861057.994 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2020-05-07T14:17:37.994Z,1588861057.994 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2020-05-07T14:17:37.995Z,1588861057.995 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2020-05-07T14:17:37.995Z,1588861057.995 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2020-05-07T14:17:37.995Z,1588861057.995 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2020-05-07T14:17:37.996Z,1588861057.996 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-05-07T14:17:38.012Z,1588861058.012 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2020-05-07T14:17:38.013Z,1588861058.013 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2020-05-07T14:17:38.014Z,1588861058.014 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2020-05-07T14:17:38.014Z,1588861058.014 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-05-07T14:17:38.019Z,1588861058.019 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2020-05-07T14:17:38.019Z,1588861058.019 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2020-05-07T14:17:38.019Z,1588861058.019 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2020-05-07T14:17:38.020Z,1588861058.020 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes] Running Loop=1 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::initialize ballast_and_trim:StandardEnvelopes 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2020-05-07T14:17:38.033Z,1588861058.033 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2020-05-07T14:17:38.034Z,1588861058.034 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2020-05-07T14:17:38.035Z,1588861058.035 [ballast_and_trim:C] Running Loop=1 2020-05-07T14:17:38.035Z,1588861058.035 [ballast_and_trim:StandardEnvelopes] Running Loop=1 2020-05-07T14:17:38.035Z,1588861058.035 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2020-05-07T14:17:38.036Z,1588861058.036 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2020-05-07T14:17:38.037Z,1588861058.037 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2020-05-07T14:17:38.037Z,1588861058.037 [ballast_and_trim:C] Stopped 2020-05-07T14:17:38.038Z,1588861058.038 [ballast_and_trim:E] Running Loop=1 2020-05-07T14:17:38.038Z,1588861058.038 [ballast_and_trim:Science] Running Loop=1 2020-05-07T14:17:38.038Z,1588861058.038 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectOil] Stopped 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-05-07T14:17:38.039Z,1588861058.039 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:PeakDetectChl] Stopped 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-05-07T14:17:38.040Z,1588861058.040 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2020-05-07T14:17:38.041Z,1588861058.041 [ballast_and_trim:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2020-05-07T14:17:38.041Z,1588861058.041 [ballast_and_trim:Science:H] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:G] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:F] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:E] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:D] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:C] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:B] Stopped 2020-05-07T14:17:38.042Z,1588861058.042 [ballast_and_trim:Science:A] Stopped 2020-05-07T14:17:38.090Z,1588861058.090 [WetLabsSeaOWL_UV_A](INFO): Powering up 2020-05-07T14:17:38.407Z,1588861058.407 [ballast_and_trim:E] Stopped 2020-05-07T14:17:38.407Z,1588861058.407 [ballast_and_trim:F] Running Loop=1 2020-05-07T14:17:38.802Z,1588861058.802 [ballast_and_trim:F] Stopped 2020-05-07T14:17:38.802Z,1588861058.802 [ballast_and_trim:G] Running Loop=1 2020-05-07T14:17:39.194Z,1588861059.194 [ballast_and_trim:G] Stopped 2020-05-07T14:17:39.194Z,1588861059.194 [ballast_and_trim:CheckTargetDepths] Running Loop=1 2020-05-07T14:17:39.194Z,1588861059.194 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths 2020-05-07T14:17:39.194Z,1588861059.194 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Running Loop=1 2020-05-07T14:17:39.194Z,1588861059.194 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth1 2020-05-07T14:17:39.194Z,1588861059.194 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Running Loop=1 2020-05-07T14:17:39.595Z,1588861059.595 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Stopped 2020-05-07T14:17:39.595Z,1588861059.595 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth1 2020-05-07T14:17:39.595Z,1588861059.595 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Stopped 2020-05-07T14:17:39.596Z,1588861059.596 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Running Loop=1 2020-05-07T14:17:39.596Z,1588861059.596 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth2 2020-05-07T14:17:39.596Z,1588861059.596 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Running Loop=1 2020-05-07T14:17:40.022Z,1588861060.022 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Stopped 2020-05-07T14:17:40.022Z,1588861060.022 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth2 2020-05-07T14:17:40.022Z,1588861060.022 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Stopped 2020-05-07T14:17:40.022Z,1588861060.022 [ballast_and_trim:CheckTargetDepths:C] Running Loop=1 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:CheckTargetDepths:C] Stopped 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:CheckTargetDepths](INFO): Completed ballast_and_trim:CheckTargetDepths 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:CheckTargetDepths] Stopped 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:StartingMission] Running Loop=1 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission 2020-05-07T14:17:40.400Z,1588861060.400 [ballast_and_trim:StartingMission:A] Running Loop=1 2020-05-07T14:17:40.813Z,1588861060.813 [ballast_and_trim:StartingMission:A](INFO): Checking for additional instructions before submerging. 2020-05-07T14:17:40.813Z,1588861060.813 [ballast_and_trim:StartingMission:A] Stopped 2020-05-07T14:17:40.813Z,1588861060.813 [ballast_and_trim:StartingMission:StartingMission] Running Loop=1 2020-05-07T14:17:40.813Z,1588861060.813 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission:StartingMission 2020-05-07T14:17:41.265Z,1588861061.265 [ballast_and_trim:NeedComms] Running Loop=1 2020-05-07T14:17:41.265Z,1588861061.265 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms 2020-05-07T14:17:41.266Z,1588861061.266 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1 2020-05-07T14:17:41.266Z,1588861061.266 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-07T14:17:41.266Z,1588861061.266 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2020-05-07T14:17:41.266Z,1588861061.266 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2020-05-07T14:17:41.267Z,1588861061.267 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2020-05-07T14:17:41.268Z,1588861061.268 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-07T14:17:41.268Z,1588861061.268 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-07T14:17:41.268Z,1588861061.268 [ballast_and_trim:NeedComms:A] Running Loop=1 2020-05-07T14:17:41.270Z,1588861061.270 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1588860917.000000 second since 1970/01/01T00:00:00Z 2020-05-07T14:17:41.270Z,1588861061.270 [ballast_and_trim:NeedComms:A] Stopped 2020-05-07T14:17:41.622Z,1588861061.622 [ballast_and_trim:NeedComms:C] Running Loop=1 2020-05-07T14:17:42.042Z,1588861062.042 [ballast_and_trim:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-05-07T14:17:43.600Z,1588861063.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141729.00,A,4133.15880,N,07042.39302,W,0.194,147.26,070520,,,D*70 2020-05-07T14:17:43.602Z,1588861063.602 [NAL9602](INFO): GPS fix at 20200507T141729: (41.552647, -70.706550) 2020-05-07T14:17:43.627Z,1588861063.627 [ballast_and_trim:NeedComms:C] Stopped 2020-05-07T14:17:43.627Z,1588861063.627 [ballast_and_trim:NeedComms:D] Running Loop=1 2020-05-07T14:17:44.081Z,1588861064.081 [ballast_and_trim:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-05-07T14:17:47.816Z,1588861067.816 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200507T141145/Courier0005.lzma 2020-05-07T14:17:48.818Z,1588861068.818 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Courier0005.lzma.bak 2020-05-07T14:17:48.819Z,1588861068.819 [DataOverHttps](INFO): SBD MOMSN=10285 2020-05-07T14:17:57.857Z,1588861077.857 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20200507T141145/Express0004.lzma 2020-05-07T14:17:58.858Z,1588861078.858 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0004.lzma.bak 2020-05-07T14:17:58.859Z,1588861078.859 [DataOverHttps](INFO): SBD MOMSN=10288 2020-05-07T14:18:01.389Z,1588861081.389 [NAL9602](INFO): SBD MO Status=0, MOMSN=11004, MT Status=0, MTMSN=0 2020-05-07T14:18:01.389Z,1588861081.389 [NAL9602](INFO): No messages in MT queue 2020-05-07T14:18:07.896Z,1588861087.896 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20200507T141145/Express0010.lzma 2020-05-07T14:18:08.898Z,1588861088.898 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0010.lzma.bak 2020-05-07T14:18:08.899Z,1588861088.899 [DataOverHttps](INFO): SBD MOMSN=10300 2020-05-07T14:18:10.318Z,1588861090.318 [ballast_and_trim:NeedComms:D] Stopped 2020-05-07T14:18:10.318Z,1588861090.318 [ballast_and_trim:NeedComms:E] Running Loop=1 2020-05-07T14:18:10.736Z,1588861090.736 [ballast_and_trim:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-05-07T14:18:12.292Z,1588861092.292 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141758.00,A,4133.15745,N,07042.39202,W,0.136,154.20,070520,,,D*7D 2020-05-07T14:18:12.294Z,1588861092.294 [NAL9602](INFO): GPS fix at 20200507T141758: (41.552624, -70.706534) 2020-05-07T14:18:12.350Z,1588861092.350 [ballast_and_trim:NeedComms:E] Stopped 2020-05-07T14:18:12.351Z,1588861092.351 [ballast_and_trim:NeedComms](INFO): Completed ballast_and_trim:NeedComms 2020-05-07T14:18:12.351Z,1588861092.351 [ballast_and_trim:NeedComms] Stopped 2020-05-07T14:18:12.351Z,1588861092.351 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms 2020-05-07T14:18:12.351Z,1588861092.351 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped 2020-05-07T14:18:12.351Z,1588861092.351 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-07T14:18:12.738Z,1588861092.738 [ballast_and_trim:StartingMission:StartingMission](INFO): Completed ballast_and_trim:StartingMission:StartingMission 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:StartingMission:StartingMission] Stopped 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission:StartingMission 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:StartingMission](INFO): Completed ballast_and_trim:StartingMission 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:StartingMission] Stopped 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Running Loop=1 2020-05-07T14:18:12.739Z,1588861092.739 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1 2020-05-07T14:18:12.740Z,1588861092.740 [ballast_and_trim:RunBallastAndTrim:RunDepth1:A] Running Loop=1 2020-05-07T14:18:13.174Z,1588861093.174 [ballast_and_trim:RunBallastAndTrim:RunDepth1:A] Stopped 2020-05-07T14:18:13.174Z,1588861093.174 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B] Running Loop=1 2020-05-07T14:18:13.176Z,1588861093.176 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:B 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2020-05-07T14:18:13.524Z,1588861093.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2020-05-07T14:18:13.541Z,1588861093.541 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Running Loop=1 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G](INFO): Going to target depth. Speed set to 1.000000 m/s 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Stopped 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2020-05-07T14:18:13.544Z,1588861093.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2020-05-07T14:18:13.549Z,1588861093.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2020-05-07T14:18:13.549Z,1588861093.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2020-05-07T14:18:13.549Z,1588861093.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2020-05-07T14:18:13.549Z,1588861093.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2020-05-07T14:18:13.549Z,1588861093.549 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2020-05-07T14:18:13.550Z,1588861093.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2020-05-07T14:18:13.550Z,1588861093.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2020-05-07T14:18:13.550Z,1588861093.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2020-05-07T14:18:13.550Z,1588861093.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2020-05-07T14:18:13.550Z,1588861093.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2020-05-07T14:18:13.550Z,1588861093.550 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2020-05-07T14:18:13.551Z,1588861093.551 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2020-05-07T14:18:13.551Z,1588861093.551 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2020-05-07T14:18:13.977Z,1588861093.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2020-05-07T14:18:13.977Z,1588861093.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2020-05-07T14:18:13.978Z,1588861093.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2020-05-07T14:18:13.979Z,1588861093.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2020-05-07T14:18:13.979Z,1588861093.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2020-05-07T14:18:13.979Z,1588861093.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2020-05-07T14:18:14.358Z,1588861094.358 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2020-05-07T14:18:14.359Z,1588861094.359 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 6.000000 m 2020-05-07T14:18:14.359Z,1588861094.359 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2020-05-07T14:18:14.359Z,1588861094.359 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2020-05-07T14:18:14.359Z,1588861094.359 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2020-05-07T14:18:44.601Z,1588861124.601 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T14:18:53.137Z,1588861133.137 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:18:54.752Z,1588861134.752 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:19:14.149Z,1588861154.149 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:19:26.683Z,1588861166.683 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:19:31.509Z,1588861171.509 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:19:49.317Z,1588861189.317 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.249176 2020-05-07T14:20:00.586Z,1588861200.586 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2020-05-07T14:20:00.586Z,1588861200.586 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2020-05-07T14:20:00.586Z,1588861200.586 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2020-05-07T14:20:00.587Z,1588861200.587 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2020-05-07T14:20:00.587Z,1588861200.587 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2020-05-07T14:20:00.587Z,1588861200.587 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2020-05-07T14:20:00.588Z,1588861200.588 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2020-05-07T14:20:00.589Z,1588861200.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2020-05-07T14:20:00.590Z,1588861200.590 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2020-05-07T14:20:01.019Z,1588861201.019 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 6.086820 m 2020-05-07T14:20:01.019Z,1588861201.019 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2020-05-07T14:20:01.019Z,1588861201.019 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2020-05-07T14:20:01.019Z,1588861201.019 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2020-05-07T14:20:01.019Z,1588861201.019 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2020-05-07T14:20:01.020Z,1588861201.020 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2020-05-07T14:20:04.486Z,1588861204.486 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:20:17.690Z,1588861217.690 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:20:18.464Z,1588861218.464 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:20:20.101Z,1588861220.101 [Radio_Surface](INFO): Powering down 2020-05-07T14:20:20.205Z,1588861220.205 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2020-05-07T14:20:20.205Z,1588861220.205 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-05-07T14:20:20.205Z,1588861220.205 [RDI_Pathfinder](ERROR): Communications Fault 2020-05-07T14:20:20.332Z,1588861220.332 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-05-07T14:20:20.569Z,1588861220.569 [RDI_Pathfinder](INFO): Powering down 2020-05-07T14:20:21.601Z,1588861221.601 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-05-07T14:20:21.601Z,1588861221.601 [RDI_Pathfinder] No Fault, FailCount= 1 2020-05-07T14:20:25.473Z,1588861225.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-05-07T14:20:26.478Z,1588861226.478 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-05-07T14:20:41.078Z,1588861241.078 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:20:47.122Z,1588861247.122 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:20:49.142Z,1588861249.142 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:21:26.074Z,1588861286.074 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:21:40.130Z,1588861300.130 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:21:41.792Z,1588861301.792 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:22:00.093Z,1588861320.093 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:22:42.110Z,1588861362.110 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:22:44.143Z,1588861364.143 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:23:16.090Z,1588861396.090 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:23:38.142Z,1588861418.142 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:23:49.159Z,1588861429.159 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:24:04.136Z,1588861444.136 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:24:05.766Z,1588861445.766 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:24:21.774Z,1588861461.774 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:24:36.119Z,1588861476.119 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:25:06.131Z,1588861506.131 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:25:19.774Z,1588861519.774 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:26:10.163Z,1588861570.163 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:26:13.077Z,1588861573.077 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:26:21.144Z,1588861581.144 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:26:32.068Z,1588861592.068 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:26:41.218Z,1588861601.218 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:26:46.054Z,1588861606.054 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:26:48.141Z,1588861608.141 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:26:57.692Z,1588861617.692 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:27:16.066Z,1588861636.066 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:27:37.074Z,1588861657.074 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:27:53.389Z,1588861673.389 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:28:05.782Z,1588861685.782 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:29:01.065Z,1588861741.065 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:29:17.790Z,1588861757.790 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:29:35.121Z,1588861775.121 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:29:48.061Z,1588861788.061 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:29:59.084Z,1588861799.084 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:30:01.510Z,1588861801.510 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting. 2020-05-07T14:30:01.511Z,1588861801.511 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2020-05-07T14:30:01.511Z,1588861801.511 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T14:30:01.511Z,1588861801.511 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1 2020-05-07T14:30:01.931Z,1588861801.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 5.918665 m 2020-05-07T14:30:01.931Z,1588861801.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped 2020-05-07T14:30:01.931Z,1588861801.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1 2020-05-07T14:30:01.931Z,1588861801.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim. 2020-05-07T14:30:19.080Z,1588861819.080 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:31:04.055Z,1588861864.055 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:31:05.705Z,1588861865.705 [RDI_PathfinderUp](ERROR): DVL BIT error. See manual. Result code: 44 2020-05-07T14:31:21.148Z,1588861881.148 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:31:32.062Z,1588861892.062 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:31:50.773Z,1588861910.773 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout 2020-05-07T14:31:50.773Z,1588861910.773 [RDI_PathfinderUp] Communications Fault, FailCount= 1 2020-05-07T14:31:50.773Z,1588861910.773 [RDI_PathfinderUp](ERROR): Communications Fault 2020-05-07T14:31:50.833Z,1588861910.833 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp 2020-05-07T14:31:51.145Z,1588861911.145 [RDI_PathfinderUp](INFO): Powering down 2020-05-07T14:31:51.961Z,1588861911.961 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp 2020-05-07T14:31:51.961Z,1588861911.961 [RDI_PathfinderUp] No Fault, FailCount= 1 2020-05-07T14:32:29.777Z,1588861949.777 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:32:51.182Z,1588861971.182 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:33:06.191Z,1588861986.191 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:33:24.160Z,1588862004.160 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:33:24.190Z,1588862004.190 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:34:22.130Z,1588862062.130 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:34:23.783Z,1588862063.783 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:34:59.785Z,1588862099.785 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:35:13.202Z,1588862113.202 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:35:25.175Z,1588862125.175 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:35:26.775Z,1588862126.775 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:35:57.557Z,1588862157.557 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:36:07.787Z,1588862167.787 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:36:19.127Z,1588862179.127 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:36:36.562Z,1588862196.562 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:36:55.547Z,1588862215.547 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:37:43.775Z,1588862263.775 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:37:58.769Z,1588862278.769 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:38:13.784Z,1588862293.784 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:38:27.129Z,1588862307.129 [RDI_Pathfinder](ERROR): only read 1 of 4 data items 2020-05-07T14:38:29.554Z,1588862309.554 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:38:55.539Z,1588862335.539 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:39:31.186Z,1588862371.186 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:39:52.175Z,1588862392.175 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:39:54.193Z,1588862394.193 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:40:11.169Z,1588862411.169 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:40:51.772Z,1588862451.772 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:40:52.549Z,1588862452.549 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:41:13.182Z,1588862473.182 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:41:39.127Z,1588862499.127 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:41:53.768Z,1588862513.768 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:41:55.841Z,1588862515.841 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2020-05-07T14:41:55.841Z,1588862515.841 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-05-07T14:41:55.841Z,1588862515.841 [RDI_Pathfinder](ERROR): Communications Fault 2020-05-07T14:41:55.871Z,1588862515.871 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-05-07T14:41:56.249Z,1588862516.249 [RDI_Pathfinder](INFO): Powering down 2020-05-07T14:41:56.995Z,1588862516.995 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-05-07T14:41:56.995Z,1588862516.995 [RDI_Pathfinder] No Fault, FailCount= 1 2020-05-07T14:43:01.649Z,1588862581.649 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:43:06.200Z,1588862586.200 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:43:19.182Z,1588862599.182 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:43:38.626Z,1588862618.626 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:43:51.569Z,1588862631.569 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:44:41.271Z,1588862681.271 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:44:52.175Z,1588862692.175 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:45:11.637Z,1588862711.637 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:45:41.649Z,1588862741.649 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:45:59.560Z,1588862759.560 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:46:50.635Z,1588862810.635 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:46:52.288Z,1588862812.288 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:47:33.553Z,1588862853.553 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:47:45.285Z,1588862865.285 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:47:54.557Z,1588862874.557 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:47:58.661Z,1588862878.661 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:48:10.442Z,1588862890.442 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 18.14 minutes. 2020-05-07T14:48:10.443Z,1588862890.443 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 242.692227 +/- 10.526852 cc (conf. level 99.90%, sigma: 17.242730 cc). 2020-05-07T14:48:10.443Z,1588862890.443 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: 2.873673 +/- 0.249944 mm (conf. level 99.90%, sigma: 0.409401 mm). 2020-05-07T14:48:10.444Z,1588862890.444 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 242.692227 cc buoyancy neutral and 2.873673 mm mass default 2020-05-07T14:48:10.449Z,1588862890.449 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown. 2020-05-07T14:48:10.449Z,1588862890.449 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown. 2020-05-07T14:48:10.449Z,1588862890.449 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-05-07T14:48:10.449Z,1588862890.449 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped 2020-05-07T14:48:10.449Z,1588862890.449 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim. 2020-05-07T14:48:10.450Z,1588862890.450 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2020-05-07T14:48:10.450Z,1588862890.450 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2020-05-07T14:48:10.451Z,1588862890.451 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2020-05-07T14:48:10.451Z,1588862890.451 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2020-05-07T14:48:10.451Z,1588862890.451 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2020-05-07T14:48:10.451Z,1588862890.451 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2020-05-07T14:48:10.452Z,1588862890.452 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2020-05-07T14:48:10.821Z,1588862890.821 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:RunDepth1:B 2020-05-07T14:48:10.821Z,1588862890.821 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B] Stopped 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:B 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:RunDepth1 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Stopped 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth2] Running Loop=1 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth2 2020-05-07T14:48:10.822Z,1588862890.822 [ballast_and_trim:RunBallastAndTrim:RunDepth2:A] Running Loop=1 2020-05-07T14:48:11.240Z,1588862891.240 [ballast_and_trim:RunBallastAndTrim:RunDepth2] Stopped 2020-05-07T14:48:11.240Z,1588862891.240 [ballast_and_trim:RunBallastAndTrim:RunDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth2 2020-05-07T14:48:11.242Z,1588862891.242 [ballast_and_trim:RunBallastAndTrim:RunDepth2:A] Stopped 2020-05-07T14:48:11.243Z,1588862891.243 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim 2020-05-07T14:48:11.243Z,1588862891.243 [ballast_and_trim:RunBallastAndTrim] Stopped 2020-05-07T14:48:11.243Z,1588862891.243 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2020-05-07T14:48:11.244Z,1588862891.244 [ballast_and_trim:EndingMission] Running Loop=1 2020-05-07T14:48:11.244Z,1588862891.244 [ballast_and_trim:EndingMission](DEBUG): Aggregate::initialize ballast_and_trim:EndingMission 2020-05-07T14:48:11.621Z,1588862891.621 [ballast_and_trim:NeedComms] Running Loop=1 2020-05-07T14:48:11.621Z,1588862891.621 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms 2020-05-07T14:48:11.621Z,1588862891.621 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1 2020-05-07T14:48:11.622Z,1588862891.622 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-07T14:48:11.622Z,1588862891.622 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2020-05-07T14:48:11.622Z,1588862891.622 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2020-05-07T14:48:11.623Z,1588862891.623 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2020-05-07T14:48:11.623Z,1588862891.623 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-07T14:48:11.623Z,1588862891.623 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-07T14:48:11.623Z,1588862891.623 [ballast_and_trim:NeedComms:A] Running Loop=1 2020-05-07T14:48:11.625Z,1588862891.625 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1588861078.000000 second since 1970/01/01T00:00:00Z 2020-05-07T14:48:11.625Z,1588862891.625 [ballast_and_trim:NeedComms:A] Stopped 2020-05-07T14:48:25.635Z,1588862905.635 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:48:33.580Z,1588862913.580 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:48:46.882Z,1588862926.882 [ballast_and_trim:NeedComms:C] Running Loop=1 2020-05-07T14:48:47.386Z,1588862927.386 [Radio_Surface](INFO): Powering up 2020-05-07T14:48:47.676Z,1588862927.676 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2020-05-07T14:48:53.461Z,1588862933.461 [DataOverHttps](INFO): Radio surface powered ON. 2020-05-07T14:48:55.551Z,1588862935.551 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:48:59.188Z,1588862939.188 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:48:59.543Z,1588862939.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=11005, MT Status=0, MTMSN=0 2020-05-07T14:48:59.543Z,1588862939.543 [NAL9602](INFO): No messages in MT queue 2020-05-07T14:49:00.753Z,1588862940.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144847.00,A,4132.98690,N,07042.48699,W,0.855,186.31,070520,,,A*72 2020-05-07T14:49:00.755Z,1588862940.755 [NAL9602](INFO): GPS fix at 20200507T144847: (41.549782, -70.708117) 2020-05-07T14:49:00.798Z,1588862940.798 [ballast_and_trim:NeedComms:C] Stopped 2020-05-07T14:49:00.798Z,1588862940.798 [ballast_and_trim:NeedComms:D] Running Loop=1 2020-05-07T14:49:23.581Z,1588862963.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-05-07T14:49:33.479Z,1588862973.479 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T14:49:58.721Z,1588862998.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-05-07T14:50:10.670Z,1588863010.670 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T14:50:31.303Z,1588863031.303 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:50:33.861Z,1588863033.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-05-07T14:50:50.666Z,1588863050.666 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:50:57.549Z,1588863057.549 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:50:59.180Z,1588863059.180 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-05-07T14:51:09.009Z,1588863069.009 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-05-07T14:51:11.300Z,1588863071.300 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:51:44.150Z,1588863104.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-05-07T14:51:58.160Z,1588863118.160 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T14:52:13.281Z,1588863133.281 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.011082 2020-05-07T14:52:13.537Z,1588863133.537 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:52:17.291Z,1588863137.291 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200507T141145/Courier0012.lzma 2020-05-07T14:52:18.294Z,1588863138.294 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Courier0012.lzma.bak 2020-05-07T14:52:18.294Z,1588863138.294 [DataOverHttps](INFO): SBD MOMSN=10304 2020-05-07T14:52:27.325Z,1588863147.325 [DataOverHttps](INFO): Sending 503 bytes from file Logs/20200507T141145/Express0013.lzma 2020-05-07T14:52:28.326Z,1588863148.326 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0013.lzma.bak 2020-05-07T14:52:28.327Z,1588863148.327 [DataOverHttps](INFO): SBD MOMSN=10307 2020-05-07T14:52:29.665Z,1588863149.665 [ballast_and_trim:NeedComms:D] Stopped 2020-05-07T14:52:29.665Z,1588863149.665 [ballast_and_trim:NeedComms:E] Running Loop=1 2020-05-07T14:52:31.643Z,1588863151.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145218.00,A,4132.97370,N,07042.49194,W,0.389,156.50,070520,,,A*7C 2020-05-07T14:52:31.645Z,1588863151.645 [NAL9602](INFO): GPS fix at 20200507T145218: (41.549562, -70.708199) 2020-05-07T14:52:31.696Z,1588863151.696 [ballast_and_trim:NeedComms:E] Stopped 2020-05-07T14:52:31.697Z,1588863151.697 [ballast_and_trim:NeedComms](INFO): Completed ballast_and_trim:NeedComms 2020-05-07T14:52:31.697Z,1588863151.697 [ballast_and_trim:NeedComms] Stopped 2020-05-07T14:52:31.697Z,1588863151.697 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms 2020-05-07T14:52:31.697Z,1588863151.697 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped 2020-05-07T14:52:31.697Z,1588863151.697 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-07T14:52:32.094Z,1588863152.094 [ballast_and_trim:EndingMission](INFO): Completed ballast_and_trim:EndingMission 2020-05-07T14:52:32.094Z,1588863152.094 [ballast_and_trim:EndingMission] Stopped 2020-05-07T14:52:32.094Z,1588863152.094 [ballast_and_trim:EndingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:EndingMission 2020-05-07T14:52:32.099Z,1588863152.099 [ballast_and_trim](INFO): Completed ballast_and_trim 2020-05-07T14:52:32.100Z,1588863152.100 [MissionManager](INFO): ballast_and_trim is completed. 2020-05-07T14:52:32.100Z,1588863152.100 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim] Stopped 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:Science] Stopped 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:Science:Read_Oil] Stopped 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:StandardEnvelopes] Stopped 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::uninitialize ballast_and_trim:StandardEnvelopes 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2020-05-07T14:52:32.101Z,1588863152.101 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2020-05-07T14:52:32.102Z,1588863152.102 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Stopped 2020-05-07T14:52:32.102Z,1588863152.102 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2020-05-07T14:52:32.102Z,1588863152.102 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2020-05-07T14:52:32.102Z,1588863152.102 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2020-05-07T14:52:32.341Z,1588863152.341 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-05-07T14:52:32.521Z,1588863152.521 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2020-05-07T14:52:32.522Z,1588863152.522 [DefaultWithUndock] Running Loop=1 2020-05-07T14:52:32.522Z,1588863152.522 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2020-05-07T14:52:32.522Z,1588863152.522 [DefaultWithUndock:C.GoToSurface] Running Loop=1 2020-05-07T14:52:32.522Z,1588863152.522 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-07T14:52:32.522Z,1588863152.522 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-07T14:52:32.522Z,1588863152.522 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-07T14:52:32.523Z,1588863152.523 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-07T14:52:32.523Z,1588863152.523 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-07T14:52:32.523Z,1588863152.523 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-07T14:52:32.524Z,1588863152.524 [DefaultWithUndock:A.Wait] Running Loop=1 2020-05-07T14:52:32.524Z,1588863152.524 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2020-05-07T14:52:59.934Z,1588863179.934 [NAL9602](INFO): SBD MO Status=0, MOMSN=11006, MT Status=0, MTMSN=0 2020-05-07T14:52:59.934Z,1588863179.934 [NAL9602](INFO): No messages in MT queue 2020-05-07T14:53:02.817Z,1588863182.817 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2020-05-07T14:53:02.817Z,1588863182.817 [DefaultWithUndock:A.Wait] Stopped 2020-05-07T14:53:02.817Z,1588863182.817 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T14:53:02.818Z,1588863182.818 [DefaultWithUndock:B.Undock] Running Loop=1 2020-05-07T14:53:02.818Z,1588863182.818 [DefaultWithUndock:B.Undock](DEBUG): Initialize. 2020-05-07T14:53:02.818Z,1588863182.818 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values. 2020-05-07T14:53:03.605Z,1588863183.605 [PowerOnly](INFO): Powering up 2020-05-07T14:53:06.163Z,1588863186.163 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items 2020-05-07T14:53:06.608Z,1588863186.608 [DefaultWithUndock:B.Undock](INFO): Detaching from dock. 2020-05-07T14:53:07.002Z,1588863187.002 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer. 2020-05-07T14:53:27.400Z,1588863207.400 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby. 2020-05-07T14:53:30.606Z,1588863210.606 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T14:53:30.652Z,1588863210.652 [DefaultWithUndock:B.Undock](INFO): DDM at standby. 2020-05-07T14:53:31.006Z,1588863211.006 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete. 2020-05-07T14:53:31.006Z,1588863211.006 [DefaultWithUndock:B.Undock] Stopped 2020-05-07T14:53:31.570Z,1588863211.570 [DefaultWithUndock:CheckIn] Running Loop=1 2020-05-07T14:53:31.570Z,1588863211.570 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-05-07T14:53:31.570Z,1588863211.570 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-05-07T14:53:33.505Z,1588863213.505 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145319.00,A,4132.97116,N,07042.49166,W,0.039,156.50,070520,,,A*7B 2020-05-07T14:53:33.507Z,1588863213.507 [NAL9602](INFO): GPS fix at 20200507T145319: (41.549519, -70.708194) 2020-05-07T14:53:33.586Z,1588863213.586 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-05-07T14:53:33.586Z,1588863213.586 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-05-07T14:53:37.604Z,1588863217.604 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200507T141145/Courier0015.lzma 2020-05-07T14:53:38.606Z,1588863218.606 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Courier0015.lzma.bak 2020-05-07T14:53:38.607Z,1588863218.607 [DataOverHttps](INFO): SBD MOMSN=10317 2020-05-07T14:53:47.645Z,1588863227.645 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20200507T141145/Express0016.lzma 2020-05-07T14:53:48.647Z,1588863228.647 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0016.lzma.bak 2020-05-07T14:53:48.647Z,1588863228.647 [DataOverHttps](INFO): SBD MOMSN=10320 2020-05-07T14:53:50.096Z,1588863230.096 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-05-07T14:53:50.096Z,1588863230.096 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-05-07T14:53:50.096Z,1588863230.096 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-05-07T14:54:09.060Z,1588863249.060 [NAL9602](INFO): SBD MO Status=0, MOMSN=11007, MT Status=0, MTMSN=0 2020-05-07T14:54:09.061Z,1588863249.061 [NAL9602](INFO): No messages in MT queue 2020-05-07T14:54:29.342Z,1588863269.342 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:54:34.958Z,1588863274.958 [PowerOnly](INFO): Powering down 2020-05-07T14:54:39.771Z,1588863279.771 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T14:55:07.664Z,1588863307.664 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-05-07T14:57:58.565Z,1588863478.565 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:58:27.636Z,1588863507.636 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T14:58:36.957Z,1588863516.957 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-05-07T14:58:36.960Z,1588863516.960 [BPC1](INFO): Received data from all battery sticks. 2020-05-07T14:58:50.687Z,1588863530.687 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-05-07T14:58:50.687Z,1588863530.687 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-05-07T14:58:50.687Z,1588863530.687 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T14:58:50.688Z,1588863530.688 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-05-07T14:58:51.084Z,1588863531.084 [DefaultWithUndock:CheckIn:D] Stopped 2020-05-07T14:58:51.084Z,1588863531.084 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-05-07T14:58:51.511Z,1588863531.511 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 6.309363 min 2020-05-07T14:58:51.511Z,1588863531.511 [DefaultWithUndock:CheckIn:E] Stopped 2020-05-07T14:58:51.511Z,1588863531.511 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-05-07T14:58:51.511Z,1588863531.511 [DefaultWithUndock:CheckIn] Stopped 2020-05-07T14:58:51.511Z,1588863531.511 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-05-07T14:58:51.511Z,1588863531.511 [DefaultWithUndock:CheckIn](INFO): Running loop #2 2020-05-07T14:58:51.512Z,1588863531.512 [DefaultWithUndock:CheckIn] Running Loop=2 2020-05-07T14:58:51.512Z,1588863531.512 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-05-07T14:58:51.512Z,1588863531.512 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-05-07T14:58:53.481Z,1588863533.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145840.00,A,4132.95414,N,07042.49153,W,0.447,163.83,070520,,,D*7F 2020-05-07T14:58:53.483Z,1588863533.483 [NAL9602](INFO): GPS fix at 20200507T145840: (41.549236, -70.708192) 2020-05-07T14:58:53.555Z,1588863533.555 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-05-07T14:58:53.555Z,1588863533.555 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-05-07T14:58:57.911Z,1588863537.911 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20200507T141145/Courier0018.lzma 2020-05-07T14:58:58.911Z,1588863538.911 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Courier0018.lzma.bak 2020-05-07T14:58:58.911Z,1588863538.911 [DataOverHttps](INFO): SBD MOMSN=10325 2020-05-07T14:59:07.949Z,1588863547.949 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200507T141145/Express0019.lzma 2020-05-07T14:59:08.951Z,1588863548.951 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0019.lzma.bak 2020-05-07T14:59:08.951Z,1588863548.951 [DataOverHttps](INFO): SBD MOMSN=10328 2020-05-07T14:59:10.042Z,1588863550.042 [NAL9602](INFO): SBD MO Status=0, MOMSN=11008, MT Status=0, MTMSN=0 2020-05-07T14:59:10.042Z,1588863550.042 [NAL9602](INFO): No messages in MT queue 2020-05-07T14:59:10.559Z,1588863550.559 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-05-07T14:59:10.559Z,1588863550.559 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-05-07T14:59:10.559Z,1588863550.559 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-05-07T14:59:40.747Z,1588863580.747 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T15:00:33.346Z,1588863633.346 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T15:00:48.645Z,1588863648.645 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T15:01:36.351Z,1588863696.351 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-05-07T15:02:10.643Z,1588863730.643 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T15:03:39.541Z,1588863819.541 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T15:03:55.355Z,1588863835.355 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T15:04:00.567Z,1588863840.567 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-05-07T15:04:11.056Z,1588863851.056 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-05-07T15:04:11.056Z,1588863851.056 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-05-07T15:04:11.056Z,1588863851.056 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T15:04:11.056Z,1588863851.056 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-05-07T15:04:11.478Z,1588863851.478 [DefaultWithUndock:CheckIn:D] Stopped 2020-05-07T15:04:11.478Z,1588863851.478 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-05-07T15:04:11.853Z,1588863851.853 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 11.649275 min 2020-05-07T15:04:11.853Z,1588863851.853 [DefaultWithUndock:CheckIn:E] Stopped 2020-05-07T15:04:11.853Z,1588863851.853 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-05-07T15:04:11.853Z,1588863851.853 [DefaultWithUndock:CheckIn] Stopped 2020-05-07T15:04:11.854Z,1588863851.854 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-05-07T15:04:11.854Z,1588863851.854 [DefaultWithUndock:CheckIn](INFO): Running loop #3 2020-05-07T15:04:11.854Z,1588863851.854 [DefaultWithUndock:CheckIn] Running Loop=3 2020-05-07T15:04:11.854Z,1588863851.854 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-05-07T15:04:11.854Z,1588863851.854 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-05-07T15:04:12.661Z,1588863852.661 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-05-07T15:04:13.845Z,1588863853.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150400.00,A,4132.93725,N,07042.49337,W,0.369,174.13,070520,,,D*70 2020-05-07T15:04:13.847Z,1588863853.847 [NAL9602](INFO): GPS fix at 20200507T150400: (41.548954, -70.708223) 2020-05-07T15:04:13.897Z,1588863853.897 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-05-07T15:04:13.897Z,1588863853.897 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-05-07T15:04:18.208Z,1588863858.208 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200507T141145/Courier0021.lzma 2020-05-07T15:04:19.210Z,1588863859.210 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Courier0021.lzma.bak 2020-05-07T15:04:19.211Z,1588863859.211 [DataOverHttps](INFO): SBD MOMSN=10332 2020-05-07T15:04:28.256Z,1588863868.256 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200507T141145/Express0022.lzma 2020-05-07T15:04:29.255Z,1588863869.255 [DataOverHttps](INFO): Moved sent file to Logs/20200507T141145/Express0022.lzma.bak 2020-05-07T15:04:29.256Z,1588863869.256 [DataOverHttps](INFO): SBD MOMSN=10335 2020-05-07T15:04:30.465Z,1588863870.465 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-05-07T15:04:30.465Z,1588863870.465 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-05-07T15:04:30.465Z,1588863870.465 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-05-07T15:04:36.470Z,1588863876.470 [NAL9602](INFO): SBD MO Status=0, MOMSN=11009, MT Status=0, MTMSN=0 2020-05-07T15:04:36.470Z,1588863876.470 [NAL9602](INFO): No messages in MT queue 2020-05-07T15:05:07.177Z,1588863907.177 [NAL9602](INFO): Not Powering down - fast GPS 2020-05-07T15:05:11.634Z,1588863911.634 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-05-07T15:06:18.358Z,1588863978.358 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude 2020-05-07T15:06:19.698Z,1588863979.698 [DataOverHttps](IMPORTANT): SBD MTMSN=20200507T150605 2020-05-07T15:06:23.716Z,1588863983.716 [DataOverHttps](INFO): Received command:restart system 2020-05-07T15:06:23.785Z,1588863983.785 [CommandLine](IMPORTANT): got command restart system 2020-05-07T15:06:25.889Z,1588863985.889 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2020-05-07T15:06:25.889Z,1588863985.889 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:25.889Z,1588863985.889 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:25.909Z,1588863985.909 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-05-07T15:06:25.909Z,1588863985.909 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:25.910Z,1588863985.910 [CommandLine](INFO): Join timeout helper Thread ID is 1301 2020-05-07T15:06:25.910Z,1588863985.910 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-05-07T15:06:25.910Z,1588863985.910 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:25.911Z,1588863985.911 [NavChartDb](INFO): Join timeout helper Thread ID is 1302 2020-05-07T15:06:25.945Z,1588863985.945 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:25.945Z,1588863985.945 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:25.949Z,1588863985.949 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-05-07T15:06:25.949Z,1588863985.949 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:25.949Z,1588863985.949 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1303 2020-05-07T15:06:26.297Z,1588863986.297 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:26.297Z,1588863986.297 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-05-07T15:06:26.298Z,1588863986.298 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.301Z,1588863986.301 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-05-07T15:06:26.301Z,1588863986.301 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.301Z,1588863986.301 [Radio_Surface](INFO): Join timeout helper Thread ID is 1304 2020-05-07T15:06:26.681Z,1588863986.681 [Radio_Surface](INFO): Powering down 2020-05-07T15:06:26.682Z,1588863986.682 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:26.682Z,1588863986.682 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.689Z,1588863986.689 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-05-07T15:06:26.689Z,1588863986.689 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.689Z,1588863986.689 [DataOverHttps](INFO): Join timeout helper Thread ID is 1305 2020-05-07T15:06:26.725Z,1588863986.725 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:26.725Z,1588863986.725 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.730Z,1588863986.730 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-05-07T15:06:26.730Z,1588863986.730 [logger ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.730Z,1588863986.730 [logger](INFO): Join timeout helper Thread ID is 1306 2020-05-07T15:06:26.765Z,1588863986.765 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:26.766Z,1588863986.766 [logger ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.782Z,1588863986.782 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-05-07T15:06:26.782Z,1588863986.782 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.782Z,1588863986.782 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-05-07T15:06:26.782Z,1588863986.782 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:26.782Z,1588863986.782 [controlThread](INFO): Join timeout helper Thread ID is 1307 2020-05-07T15:06:26.802Z,1588863986.802 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-07T15:06:26.802Z,1588863986.802 [controlThread](DEBUG): Uninitializing ControlThread 2020-05-07T15:06:26.802Z,1588863986.802 [AHRS_M2](INFO): Powering down 2020-05-07T15:06:26.873Z,1588863986.873 [DDM](INFO): Powering down 2020-05-07T15:06:26.965Z,1588863986.965 [DUSBL_Hydroid](INFO): Powering down 2020-05-07T15:06:27.057Z,1588863987.057 [Micromodem](INFO): Powering down 2020-05-07T15:06:27.154Z,1588863987.154 [NAL9602](INFO): Powering down 2020-05-07T15:06:27.225Z,1588863987.225 [RDI_Pathfinder](INFO): Powering down 2020-05-07T15:06:27.297Z,1588863987.297 [RDI_PathfinderUp](INFO): Powering down 2020-05-07T15:06:27.299Z,1588863987.299 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-05-07T15:06:27.299Z,1588863987.299 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-05-07T15:06:27.300Z,1588863987.300 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-05-07T15:06:27.301Z,1588863987.301 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock] Stopped 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock:C.GoToSurface] Stopped 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock:CheckIn] Stopped 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-05-07T15:06:27.301Z,1588863987.301 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-05-07T15:06:27.305Z,1588863987.305 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-05-07T15:06:27.305Z,1588863987.305 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-05-07T15:06:27.306Z,1588863987.306 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-05-07T15:06:27.306Z,1588863987.306 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-05-07T15:06:27.306Z,1588863987.306 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-05-07T15:06:27.307Z,1588863987.307 [BuoyancyServo](INFO): Powering down 2020-05-07T15:06:27.321Z,1588863987.321 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-05-07T15:06:27.321Z,1588863987.321 [ElevatorServo](INFO): Powering down 2020-05-07T15:06:27.322Z,1588863987.322 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-05-07T15:06:27.322Z,1588863987.322 [MassServo](INFO): Powering down 2020-05-07T15:06:27.323Z,1588863987.323 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-05-07T15:06:27.323Z,1588863987.323 [RudderServo](INFO): Powering down 2020-05-07T15:06:27.324Z,1588863987.324 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-05-07T15:06:27.324Z,1588863987.324 [ThrusterServo](INFO): Powering down 2020-05-07T15:06:27.325Z,1588863987.325 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-05-07T15:06:27.325Z,1588863987.325 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-05-07T15:06:27.326Z,1588863987.326 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-05-07T15:06:27.326Z,1588863987.326 [CBIT](DEBUG): Powering off loads. 2020-05-07T15:06:27.337Z,1588863987.337 [CBIT](DEBUG): Disabling WDT. 2020-05-07T15:06:27.349Z,1588863987.349 [CBIT](DEBUG): Opening all GF detection circuits. 2020-05-07T15:06:27.350Z,1588863987.350 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:27.409Z,1588863987.409 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:27.421Z,1588863987.421 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:27.474Z,1588863987.474 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:27.541Z,1588863987.541 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-07T15:06:27.612Z,1588863987.612 [logger ThreadHandler](INFO): Thread cancelled.