2020-09-29T20:12:06.909Z,1601410326.909 [CommandLine](IMPORTANT): got command restart logs 2020-09-29T20:12:53.511Z,1601410373.511 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-29T20:12:53.511Z,1601410373.511 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-09-29T20:12:53.511Z,1601410373.511 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-29T20:12:53.511Z,1601410373.511 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-09-29T20:12:53.862Z,1601410373.862 [DefaultWithUndock:CheckIn:D] Stopped 2020-09-29T20:12:53.863Z,1601410373.863 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-09-29T20:12:54.271Z,1601410374.271 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 7.008996 min 2020-09-29T20:12:54.271Z,1601410374.271 [DefaultWithUndock:CheckIn:E] Stopped 2020-09-29T20:12:54.271Z,1601410374.271 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn 2020-09-29T20:12:54.271Z,1601410374.271 [DefaultWithUndock:CheckIn] Stopped 2020-09-29T20:12:54.271Z,1601410374.271 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-09-29T20:12:54.272Z,1601410374.272 [DefaultWithUndock:CheckIn](INFO): Running loop #2 2020-09-29T20:12:54.272Z,1601410374.272 [DefaultWithUndock:CheckIn] Running Loop=2 2020-09-29T20:12:54.272Z,1601410374.272 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-09-29T20:12:54.272Z,1601410374.272 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-09-29T20:12:56.275Z,1601410376.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201254.00,A,4133.20866,N,07042.49139,W,0.525,27.58,290920,,,A*4E 2020-09-29T20:12:56.280Z,1601410376.280 [NAL9602](INFO): GPS fix at 20200929T201254: (41.553478, -70.708190) 2020-09-29T20:12:56.331Z,1601410376.331 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-09-29T20:12:56.331Z,1601410376.331 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-09-29T20:13:00.068Z,1601410380.068 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200929T200236/Courier0007.lzma 2020-09-29T20:13:01.070Z,1601410381.070 [DataOverHttps](INFO): Moved sent file to Logs/20200929T200236/Courier0007.lzma.bak 2020-09-29T20:13:01.071Z,1601410381.070 [DataOverHttps](INFO): SBD MOMSN=41230 2020-09-29T20:13:10.109Z,1601410390.109 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20200929T201206/Courier0000.lzma 2020-09-29T20:13:11.110Z,1601410391.110 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Courier0000.lzma.bak 2020-09-29T20:13:11.111Z,1601410391.111 [DataOverHttps](INFO): SBD MOMSN=41232 2020-09-29T20:13:11.220Z,1601410391.220 [NAL9602](INFO): SBD MO Status=0, MOMSN=11398, MT Status=0, MTMSN=0 2020-09-29T20:13:11.220Z,1601410391.220 [NAL9602](INFO): No messages in MT queue 2020-09-29T20:13:20.148Z,1601410400.148 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20200929T200236/Express0008.lzma 2020-09-29T20:13:21.150Z,1601410401.150 [DataOverHttps](INFO): Moved sent file to Logs/20200929T200236/Express0008.lzma.bak 2020-09-29T20:13:21.151Z,1601410401.151 [DataOverHttps](INFO): SBD MOMSN=41236 2020-09-29T20:13:30.210Z,1601410410.210 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20200929T201206/Express0001.lzma 2020-09-29T20:13:31.190Z,1601410411.190 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Express0001.lzma.bak 2020-09-29T20:13:31.191Z,1601410411.191 [DataOverHttps](INFO): SBD MOMSN=41239 2020-09-29T20:13:40.230Z,1601410420.230 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20200929T201206/Express0004.lzma 2020-09-29T20:13:41.230Z,1601410421.230 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Express0004.lzma.bak 2020-09-29T20:13:41.230Z,1601410421.230 [DataOverHttps](INFO): SBD MOMSN=41243 2020-09-29T20:13:41.926Z,1601410421.926 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-29T20:13:42.397Z,1601410422.397 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-09-29T20:13:42.397Z,1601410422.397 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-09-29T20:13:42.397Z,1601410422.397 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-29T20:15:34.255Z,1601410534.255 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-09-29T20:15:54.454Z,1601410554.454 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-09-29T20:16:39.328Z,1601410599.328 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items 2020-09-29T20:17:19.333Z,1601410639.333 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-09-29T20:17:24.541Z,1601410644.541 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-09-29T20:17:24.546Z,1601410644.546 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-09-29T20:17:34.637Z,1601410654.637 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-09-29T20:17:39.483Z,1601410659.483 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2020-09-29T20:17:44.362Z,1601410664.362 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-09-29T20:17:49.580Z,1601410669.580 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2020-09-29T20:18:09.386Z,1601410689.386 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2020-09-29T20:18:27.387Z,1601410707.387 [DataOverHttps](IMPORTANT): SBD MTMSN=20200929T201826 2020-09-29T20:18:31.412Z,1601410711.412 [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-09-29T20:18:31.439Z,1601410711.439 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/ballast_and_trim.xml 2020-09-29T20:18:31.440Z,1601410711.440 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/ballast_and_trim.xml 2020-09-29T20:18:31.535Z,1601410711.535 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min 2020-09-29T20:18:31.538Z,1601410711.538 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2020-09-29T20:18:31.541Z,1601410711.541 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m 2020-09-29T20:18:31.544Z,1601410711.544 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2020-09-29T20:18:31.547Z,1601410711.547 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2020-09-29T20:18:31.565Z,1601410711.565 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSpeed = 1.000000 m/s 2020-09-29T20:18:31.568Z,1601410711.568 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthTimeout = 20.000000 min 2020-09-29T20:18:31.607Z,1601410711.607 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthRate = 0.400000 m/s 2020-09-29T20:18:31.614Z,1601410711.614 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachPitchLimit = 20.000000 arcdeg 2020-09-29T20:18:31.633Z,1601410711.633 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSettleTimePreDive = 0.000000 min 2020-09-29T20:18:31.636Z,1601410711.636 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2020-09-29T20:18:31.651Z,1601410711.651 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2020-09-29T20:18:31.658Z,1601410711.658 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2020-09-29T20:18:31.663Z,1601410711.663 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm 2020-09-29T20:18:31.674Z,1601410711.674 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc 2020-09-29T20:18:31.680Z,1601410711.680 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 % 2020-09-29T20:18:31.691Z,1601410711.691 [MissionManager](INFO): DefineArg ballast_and_trim.kiDepthBuoy = 0.300000 1/s 2020-09-29T20:18:31.705Z,1601410711.705 [MissionManager](INFO): DefineArg ballast_and_trim.massDeadband = 0.250000 mm 2020-09-29T20:18:31.708Z,1601410711.708 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m 2020-09-29T20:18:31.715Z,1601410711.715 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m 2020-09-29T20:18:31.718Z,1601410711.718 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km 2020-09-29T20:18:31.729Z,1601410711.729 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool 2020-09-29T20:18:31.730Z,1601410711.730 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2020-09-29T20:18:32.047Z,1601410712.047 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2020-09-29T20:18:32.058Z,1601410712.058 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2020-09-29T20:18:32.065Z,1601410712.065 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2020-09-29T20:18:32.067Z,1601410712.067 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2020-09-29T20:18:32.082Z,1601410712.082 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2020-09-29T20:18:32.089Z,1601410712.089 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2020-09-29T20:18:32.095Z,1601410712.095 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2020-09-29T20:18:32.097Z,1601410712.097 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2020-09-29T20:18:32.100Z,1601410712.100 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2020-09-29T20:18:32.107Z,1601410712.107 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2020-09-29T20:18:32.117Z,1601410712.117 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2020-09-29T20:18:32.125Z,1601410712.125 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool 2020-09-29T20:18:32.128Z,1601410712.128 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool 2020-09-29T20:18:32.132Z,1601410712.132 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1.000000 bool 2020-09-29T20:18:32.155Z,1601410712.155 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 0.000000 bool 2020-09-29T20:18:32.173Z,1601410712.173 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool 2020-09-29T20:18:32.187Z,1601410712.187 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool 2020-09-29T20:18:32.199Z,1601410712.199 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2020-09-29T20:18:32.202Z,1601410712.202 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2020-09-29T20:18:32.213Z,1601410712.213 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlShallowBound = nan m 2020-09-29T20:18:32.216Z,1601410712.216 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakChlDeepBound = nan m 2020-09-29T20:18:32.222Z,1601410712.222 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m 2020-09-29T20:18:32.225Z,1601410712.225 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m 2020-09-29T20:18:32.228Z,1601410712.228 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l 2020-09-29T20:18:32.239Z,1601410712.239 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m 2020-09-29T20:18:32.250Z,1601410712.250 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC 2020-09-29T20:18:32.254Z,1601410712.254 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2020-09-29T20:18:32.270Z,1601410712.270 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2020-09-29T20:18:32.284Z,1601410712.284 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l 2020-09-29T20:18:32.304Z,1601410712.304 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m 2020-09-29T20:18:32.322Z,1601410712.322 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2020-09-29T20:18:32.325Z,1601410712.325 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2020-09-29T20:18:32.328Z,1601410712.328 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 m 2020-09-29T20:18:32.335Z,1601410712.335 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m 2020-09-29T20:18:32.342Z,1601410712.342 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2020-09-29T20:18:32.349Z,1601410712.349 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2020-09-29T20:18:32.352Z,1601410712.352 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2020-09-29T20:18:32.364Z,1601410712.364 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3 2020-09-29T20:18:32.367Z,1601410712.367 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m 2020-09-29T20:18:32.369Z,1601410712.369 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2020-09-29T20:18:32.372Z,1601410712.372 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2020-09-29T20:18:32.423Z,1601410712.423 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb 2020-09-29T20:18:32.434Z,1601410712.434 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m 2020-09-29T20:18:32.441Z,1601410712.441 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2020-09-29T20:18:32.444Z,1601410712.444 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2020-09-29T20:18:32.459Z,1601410712.459 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m 2020-09-29T20:18:32.564Z,1601410712.564 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-09-29T20:18:32.587Z,1601410712.587 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2020-09-29T20:18:32.620Z,1601410712.620 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-09-29T20:18:32.642Z,1601410712.642 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-09-29T20:18:32.659Z,1601410712.659 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2020-09-29T20:18:32.676Z,1601410712.676 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2020-09-29T20:18:32.763Z,1601410712.763 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 3.000000 h 2020-09-29T20:18:32.767Z,1601410712.767 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min 2020-09-29T20:18:32.778Z,1601410712.778 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg 2020-09-29T20:18:32.781Z,1601410712.781 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s 2020-09-29T20:18:32.784Z,1601410712.784 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s 2020-09-29T20:18:32.827Z,1601410712.827 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min 2020-09-29T20:18:32.849Z,1601410712.849 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min 2020-09-29T20:18:32.863Z,1601410712.863 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-29T20:18:32.894Z,1601410712.894 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2020-09-29T20:18:32.927Z,1601410712.927 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinAltitude = 5.000000 m 2020-09-29T20:18:32.930Z,1601410712.930 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MaxDepth = 200.000000 m 2020-09-29T20:18:32.945Z,1601410712.945 [MissionManager](INFO): DefineArg ballast_and_trim:StandardEnvelopes.MinOffshore = 2000.000000 m 2020-09-29T20:18:32.946Z,1601410712.946 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2020-09-29T20:18:32.965Z,1601410712.966 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2020-09-29T20:18:32.982Z,1601410712.982 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2020-09-29T20:18:33.013Z,1601410713.013 [ballast_and_trim:CheckTargetDepths:CheckDepth1:B.Execute](DEBUG): Construct Execute. 2020-09-29T20:18:33.021Z,1601410713.021 [ballast_and_trim:CheckTargetDepths:CheckDepth2:B.Execute](DEBUG): Construct Execute. 2020-09-29T20:18:33.031Z,1601410713.031 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml 2020-09-29T20:18:33.263Z,1601410713.263 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m 2020-09-29T20:18:33.287Z,1601410713.287 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2020-09-29T20:18:33.325Z,1601410713.325 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2020-09-29T20:18:33.328Z,1601410713.328 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2020-09-29T20:18:33.335Z,1601410713.335 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2020-09-29T20:18:33.338Z,1601410713.338 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2020-09-29T20:18:33.357Z,1601410713.357 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min 2020-09-29T20:18:33.360Z,1601410713.360 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min 2020-09-29T20:18:33.367Z,1601410713.367 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min 2020-09-29T20:18:33.371Z,1601410713.371 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2020-09-29T20:18:33.381Z,1601410713.381 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2020-09-29T20:18:33.384Z,1601410713.384 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 % 2020-09-29T20:18:33.391Z,1601410713.391 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2020-09-29T20:18:33.395Z,1601410713.395 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = 0.300000 1/s 2020-09-29T20:18:33.406Z,1601410713.406 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2020-09-29T20:18:33.437Z,1601410713.437 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2020-09-29T20:18:33.443Z,1601410713.443 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2020-09-29T20:18:33.455Z,1601410713.455 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2020-09-29T20:18:33.471Z,1601410713.471 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2020-09-29T20:18:33.479Z,1601410713.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2020-09-29T20:18:33.486Z,1601410713.486 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2020-09-29T20:18:33.501Z,1601410713.501 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2020-09-29T20:18:33.509Z,1601410713.509 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2020-09-29T20:18:33.512Z,1601410713.512 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2020-09-29T20:18:33.538Z,1601410713.538 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2020-09-29T20:18:33.543Z,1601410713.543 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim. 2020-09-29T20:18:33.649Z,1601410713.649 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband 2020-09-29T20:18:33.651Z,1601410713.651 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = reader:ballast_and_trim.ApproachSpeed 2020-09-29T20:18:33.652Z,1601410713.652 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = reader:ballast_and_trim.ApproachDepthRate 2020-09-29T20:18:33.662Z,1601410713.662 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = reader:ballast_and_trim.ApproachPitchLimit 2020-09-29T20:18:33.664Z,1601410713.664 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = reader:ballast_and_trim.ApproachSettleTimePreDive 2020-09-29T20:18:33.673Z,1601410713.673 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.ApproachDepthTimeout 2020-09-29T20:18:33.675Z,1601410713.675 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime 2020-09-29T20:18:33.677Z,1601410713.677 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout 2020-09-29T20:18:33.679Z,1601410713.679 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime 2020-09-29T20:18:33.680Z,1601410713.680 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound 2020-09-29T20:18:33.686Z,1601410713.686 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound 2020-09-29T20:18:33.688Z,1601410713.688 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence 2020-09-29T20:18:33.697Z,1601410713.697 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = reader:ballast_and_trim.kiDepthBuoy 2020-09-29T20:18:33.699Z,1601410713.699 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = reader:ballast_and_trim.massDeadband 2020-09-29T20:18:33.787Z,1601410713.787 [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-09-29T20:18:33.787Z,1601410713.787 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/ballast_and_trim.xml 2020-09-29T20:18:42.945Z,1601410722.945 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-29T20:18:42.945Z,1601410722.945 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-09-29T20:18:42.945Z,1601410722.945 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-29T20:18:42.945Z,1601410722.945 [DefaultWithUndock:CheckIn:D] Running Loop=1 2020-09-29T20:18:43.418Z,1601410723.418 [DefaultWithUndock:CheckIn:D] Stopped 2020-09-29T20:18:43.418Z,1601410723.418 [DefaultWithUndock:CheckIn:E] Running Loop=1 2020-09-29T20:18:43.567Z,1601410723.567 [CommandLine](IMPORTANT): got command set ballast_and_trim.Depth1 6.000000 meter 2020-09-29T20:18:43.568Z,1601410723.568 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinAltitude 3.000000 meter 2020-09-29T20:18:43.570Z,1601410723.570 [CommandLine](IMPORTANT): got command set ballast_and_trim.MaxDepth 9.000000 meter 2020-09-29T20:18:43.571Z,1601410723.571 [CommandLine](IMPORTANT): got command set ballast_and_trim.MinOffshore 500.000000 meter 2020-09-29T20:18:43.572Z,1601410723.572 [CommandLine](IMPORTANT): got command run 2020-09-29T20:18:43.577Z,1601410723.577 [CommandLine](IMPORTANT): Running 2020-09-29T20:18:43.736Z,1601410723.736 [DefaultWithUndock] Stopped 2020-09-29T20:18:43.736Z,1601410723.736 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-09-29T20:18:43.736Z,1601410723.736 [DefaultWithUndock:C.GoToSurface] Stopped 2020-09-29T20:18:43.736Z,1601410723.736 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-29T20:18:43.736Z,1601410723.736 [DefaultWithUndock:CheckIn] Stopped 2020-09-29T20:18:43.737Z,1601410723.737 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-09-29T20:18:43.737Z,1601410723.737 [DefaultWithUndock:CheckIn:E] Stopped 2020-09-29T20:18:43.737Z,1601410723.737 [MissionManager](IMPORTANT): Started mission ballast_and_trim 2020-09-29T20:18:43.737Z,1601410723.737 [ballast_and_trim] Running Loop=1 2020-09-29T20:18:43.737Z,1601410723.737 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim 2020-09-29T20:18:43.737Z,1601410723.737 [ballast_and_trim:Science] Running Loop=1 2020-09-29T20:18:43.737Z,1601410723.737 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science 2020-09-29T20:18:43.737Z,1601410723.737 [ballast_and_trim:Science:A] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:B] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:C] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:D] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:E] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:F] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2020-09-29T20:18:43.738Z,1601410723.738 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-09-29T20:18:43.740Z,1601410723.740 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1 2020-09-29T20:18:43.740Z,1601410723.740 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport 2020-09-29T20:18:43.741Z,1601410723.741 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2020-09-29T20:18:43.741Z,1601410723.741 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2020-09-29T20:18:43.741Z,1601410723.741 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1 2020-09-29T20:18:43.741Z,1601410723.741 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3 2020-09-29T20:18:43.741Z,1601410723.741 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2020-09-29T20:18:43.741Z,1601410723.741 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-09-29T20:18:43.743Z,1601410723.743 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1 2020-09-29T20:18:43.743Z,1601410723.743 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil 2020-09-29T20:18:43.744Z,1601410723.744 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2020-09-29T20:18:43.744Z,1601410723.744 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-09-29T20:18:43.745Z,1601410723.745 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1 2020-09-29T20:18:43.745Z,1601410723.745 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM 2020-09-29T20:18:43.746Z,1601410723.746 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2020-09-29T20:18:43.746Z,1601410723.746 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2020-09-29T20:18:43.747Z,1601410723.747 [ballast_and_trim:Science:OceanCurrent] Running Loop=1 2020-09-29T20:18:43.747Z,1601410723.747 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent 2020-09-29T20:18:43.747Z,1601410723.747 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2020-09-29T20:18:43.747Z,1601410723.747 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2020-09-29T20:18:43.748Z,1601410723.748 [ballast_and_trim:StandardEnvelopes] Running Loop=1 2020-09-29T20:18:43.748Z,1601410723.748 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::initialize ballast_and_trim:StandardEnvelopes 2020-09-29T20:18:43.748Z,1601410723.748 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2020-09-29T20:18:43.748Z,1601410723.748 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2020-09-29T20:18:43.748Z,1601410723.748 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2020-09-29T20:18:43.748Z,1601410723.748 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2020-09-29T20:18:43.749Z,1601410723.749 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2020-09-29T20:18:43.749Z,1601410723.749 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2020-09-29T20:18:43.749Z,1601410723.749 [ballast_and_trim:C] Running Loop=1 2020-09-29T20:18:43.750Z,1601410723.750 [ballast_and_trim:StandardEnvelopes] Running Loop=1 2020-09-29T20:18:43.750Z,1601410723.750 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2020-09-29T20:18:43.750Z,1601410723.750 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2020-09-29T20:18:43.751Z,1601410723.751 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2020-09-29T20:18:43.752Z,1601410723.752 [ballast_and_trim:C] Stopped 2020-09-29T20:18:43.752Z,1601410723.752 [ballast_and_trim:E] Running Loop=1 2020-09-29T20:18:43.752Z,1601410723.752 [ballast_and_trim:Science] Running Loop=1 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectFDOM] Stopped 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectOil] Stopped 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil 2020-09-29T20:18:43.753Z,1601410723.753 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectNO3] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:HighestChlPeakReport] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectChl] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2020-09-29T20:18:43.754Z,1601410723.754 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2020-09-29T20:18:43.755Z,1601410723.755 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1 2020-09-29T20:18:43.756Z,1601410723.756 [ballast_and_trim:Science:Read_Oil] Running Loop=1 2020-09-29T20:18:43.757Z,1601410723.757 [ballast_and_trim:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2020-09-29T20:18:43.757Z,1601410723.757 [ballast_and_trim:Science:F] Stopped 2020-09-29T20:18:43.757Z,1601410723.757 [ballast_and_trim:Science:E] Stopped 2020-09-29T20:18:43.757Z,1601410723.757 [ballast_and_trim:Science:D] Stopped 2020-09-29T20:18:43.757Z,1601410723.757 [ballast_and_trim:Science:C] Running Loop=1 2020-09-29T20:18:43.758Z,1601410723.758 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2020-09-29T20:18:43.759Z,1601410723.759 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2020-09-29T20:18:43.759Z,1601410723.759 [ballast_and_trim:Science:B] Running Loop=1 2020-09-29T20:18:43.760Z,1601410723.760 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2020-09-29T20:18:43.760Z,1601410723.760 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2020-09-29T20:18:43.760Z,1601410723.760 [ballast_and_trim:Science:A] Stopped 2020-09-29T20:18:44.075Z,1601410724.075 [WetLabsSeaOWL_UV_A](INFO): Powering up 2020-09-29T20:18:44.150Z,1601410724.150 [ballast_and_trim:E] Stopped 2020-09-29T20:18:44.150Z,1601410724.150 [ballast_and_trim:F] Running Loop=1 2020-09-29T20:18:44.569Z,1601410724.569 [ballast_and_trim:F] Stopped 2020-09-29T20:18:44.569Z,1601410724.569 [ballast_and_trim:G] Running Loop=1 2020-09-29T20:18:44.937Z,1601410724.937 [ballast_and_trim:G] Stopped 2020-09-29T20:18:44.937Z,1601410724.937 [ballast_and_trim:CheckTargetDepths] Running Loop=1 2020-09-29T20:18:44.937Z,1601410724.937 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths 2020-09-29T20:18:44.937Z,1601410724.937 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Running Loop=1 2020-09-29T20:18:44.937Z,1601410724.937 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth1 2020-09-29T20:18:44.938Z,1601410724.938 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Running Loop=1 2020-09-29T20:18:45.361Z,1601410725.361 [ballast_and_trim:CheckTargetDepths:CheckDepth1] Stopped 2020-09-29T20:18:45.362Z,1601410725.362 [ballast_and_trim:CheckTargetDepths:CheckDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth1 2020-09-29T20:18:45.362Z,1601410725.362 [ballast_and_trim:CheckTargetDepths:CheckDepth1:A] Stopped 2020-09-29T20:18:45.362Z,1601410725.362 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Running Loop=1 2020-09-29T20:18:45.362Z,1601410725.362 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::initialize ballast_and_trim:CheckTargetDepths:CheckDepth2 2020-09-29T20:18:45.362Z,1601410725.362 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Running Loop=1 2020-09-29T20:18:45.759Z,1601410725.759 [ballast_and_trim:CheckTargetDepths:CheckDepth2] Stopped 2020-09-29T20:18:45.759Z,1601410725.759 [ballast_and_trim:CheckTargetDepths:CheckDepth2](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths:CheckDepth2 2020-09-29T20:18:45.759Z,1601410725.759 [ballast_and_trim:CheckTargetDepths:CheckDepth2:A] Stopped 2020-09-29T20:18:45.759Z,1601410725.759 [ballast_and_trim:CheckTargetDepths:C] Running Loop=1 2020-09-29T20:18:46.156Z,1601410726.156 [ballast_and_trim:CheckTargetDepths:C] Stopped 2020-09-29T20:18:46.173Z,1601410726.173 [ballast_and_trim:CheckTargetDepths](INFO): Completed ballast_and_trim:CheckTargetDepths 2020-09-29T20:18:46.173Z,1601410726.173 [ballast_and_trim:CheckTargetDepths] Stopped 2020-09-29T20:18:46.173Z,1601410726.173 [ballast_and_trim:CheckTargetDepths](DEBUG): Aggregate::uninitialize ballast_and_trim:CheckTargetDepths 2020-09-29T20:18:46.173Z,1601410726.173 [ballast_and_trim:StartingMission] Running Loop=1 2020-09-29T20:18:46.173Z,1601410726.173 [ballast_and_trim:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission 2020-09-29T20:18:46.174Z,1601410726.174 [ballast_and_trim:StartingMission:A] Running Loop=1 2020-09-29T20:18:46.590Z,1601410726.590 [ballast_and_trim:StartingMission:A](INFO): Checking for additional instructions before submerging. 2020-09-29T20:18:46.590Z,1601410726.590 [ballast_and_trim:StartingMission:A] Stopped 2020-09-29T20:18:46.590Z,1601410726.590 [ballast_and_trim:StartingMission:StartingMission] Running Loop=1 2020-09-29T20:18:46.591Z,1601410726.591 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::initialize ballast_and_trim:StartingMission:StartingMission 2020-09-29T20:18:46.966Z,1601410726.966 [ballast_and_trim:NeedComms] Running Loop=1 2020-09-29T20:18:46.966Z,1601410726.966 [ballast_and_trim:NeedComms](DEBUG): Aggregate::initialize ballast_and_trim:NeedComms 2020-09-29T20:18:46.966Z,1601410726.966 [ballast_and_trim:NeedComms:B.GoToSurface] Running Loop=1 2020-09-29T20:18:46.966Z,1601410726.966 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-29T20:18:46.966Z,1601410726.966 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2020-09-29T20:18:46.967Z,1601410726.967 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2020-09-29T20:18:46.967Z,1601410726.967 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2020-09-29T20:18:46.967Z,1601410726.967 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-29T20:18:46.968Z,1601410726.968 [ballast_and_trim:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-29T20:18:46.968Z,1601410726.968 [ballast_and_trim:NeedComms:A] Running Loop=1 2020-09-29T20:18:46.969Z,1601410726.969 [ballast_and_trim:NeedComms:A](INFO): last time_fix was: 1601410374.000000 second since 1970/01/01T00:00:00Z 2020-09-29T20:18:46.969Z,1601410726.969 [ballast_and_trim:NeedComms:A] Stopped 2020-09-29T20:18:47.445Z,1601410727.445 [ballast_and_trim:NeedComms:C] Running Loop=1 2020-09-29T20:18:47.558Z,1601410727.558 [CTD_Seabird](ERROR): Failed to parse device response: 2020-09-29T20:18:47.767Z,1601410727.767 [ballast_and_trim:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-09-29T20:18:49.407Z,1601410729.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201848.00,A,4133.24313,N,07042.45982,W,0.447,26.04,290920,,,A*4D 2020-09-29T20:18:49.409Z,1601410729.409 [NAL9602](INFO): GPS fix at 20200929T201848: (41.554052, -70.707664) 2020-09-29T20:18:49.463Z,1601410729.463 [ballast_and_trim:NeedComms:C] Stopped 2020-09-29T20:18:49.463Z,1601410729.463 [ballast_and_trim:NeedComms:D] Running Loop=1 2020-09-29T20:18:49.803Z,1601410729.803 [ballast_and_trim:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-29T20:18:53.620Z,1601410733.620 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20200929T201206/Courier0006.lzma 2020-09-29T20:18:54.623Z,1601410734.623 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Courier0006.lzma.bak 2020-09-29T20:18:54.623Z,1601410734.623 [DataOverHttps](INFO): SBD MOMSN=41247 2020-09-29T20:19:03.512Z,1601410743.512 [NAL9602](INFO): SBD MO Status=0, MOMSN=11399, MT Status=0, MTMSN=0 2020-09-29T20:19:03.512Z,1601410743.512 [NAL9602](INFO): No messages in MT queue 2020-09-29T20:19:03.661Z,1601410743.661 [DataOverHttps](INFO): Sending 552 bytes from file Logs/20200929T201206/Express0007.lzma 2020-09-29T20:19:04.666Z,1601410744.666 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Express0007.lzma.bak 2020-09-29T20:19:04.666Z,1601410744.666 [DataOverHttps](INFO): SBD MOMSN=41250 2020-09-29T20:19:13.704Z,1601410753.704 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20200929T201206/Express0010.lzma 2020-09-29T20:19:14.706Z,1601410754.706 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Express0010.lzma.bak 2020-09-29T20:19:14.706Z,1601410754.706 [DataOverHttps](INFO): SBD MOMSN=41262 2020-09-29T20:19:16.054Z,1601410756.054 [ballast_and_trim:NeedComms:D] Stopped 2020-09-29T20:19:16.054Z,1601410756.054 [ballast_and_trim:NeedComms:E] Running Loop=1 2020-09-29T20:19:16.488Z,1601410756.488 [ballast_and_trim:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-09-29T20:19:18.053Z,1601410758.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201917.00,A,4133.24607,N,07042.45698,W,0.622,14.93,290920,,,A*4C 2020-09-29T20:19:18.055Z,1601410758.055 [NAL9602](INFO): GPS fix at 20200929T201917: (41.554101, -70.707616) 2020-09-29T20:19:18.067Z,1601410758.067 [ballast_and_trim:NeedComms:E] Stopped 2020-09-29T20:19:18.073Z,1601410758.073 [ballast_and_trim:NeedComms](INFO): Completed ballast_and_trim:NeedComms 2020-09-29T20:19:18.073Z,1601410758.073 [ballast_and_trim:NeedComms] Stopped 2020-09-29T20:19:18.073Z,1601410758.073 [ballast_and_trim:NeedComms](DEBUG): Aggregate::uninitialize ballast_and_trim:NeedComms 2020-09-29T20:19:18.073Z,1601410758.073 [ballast_and_trim:NeedComms:B.GoToSurface] Stopped 2020-09-29T20:19:18.073Z,1601410758.073 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-29T20:19:18.502Z,1601410758.502 [ballast_and_trim:StartingMission:StartingMission](INFO): Completed ballast_and_trim:StartingMission:StartingMission 2020-09-29T20:19:18.502Z,1601410758.502 [ballast_and_trim:StartingMission:StartingMission] Stopped 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:StartingMission:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission:StartingMission 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:StartingMission](INFO): Completed ballast_and_trim:StartingMission 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:StartingMission] Stopped 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:StartingMission](DEBUG): Aggregate::uninitialize ballast_and_trim:StartingMission 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:RunBallastAndTrim] Running Loop=1 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Running Loop=1 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1 2020-09-29T20:19:18.503Z,1601410758.503 [ballast_and_trim:RunBallastAndTrim:RunDepth1:A] Running Loop=1 2020-09-29T20:19:18.888Z,1601410758.888 [ballast_and_trim:RunBallastAndTrim:RunDepth1:A] Stopped 2020-09-29T20:19:18.888Z,1601410758.888 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B] Running Loop=1 2020-09-29T20:19:18.888Z,1601410758.888 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:RunDepth1:B 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2020-09-29T20:19:19.318Z,1601410759.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Running Loop=1 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G](INFO): Going to target depth. Speed set to 1.000000 m/s 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Stopped 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2020-09-29T20:19:19.326Z,1601410759.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2020-09-29T20:19:19.327Z,1601410759.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2020-09-29T20:19:19.328Z,1601410759.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1 2020-09-29T20:19:19.328Z,1601410759.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1 2020-09-29T20:19:19.328Z,1601410759.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1 2020-09-29T20:19:19.328Z,1601410759.328 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1 2020-09-29T20:19:19.329Z,1601410759.329 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1 2020-09-29T20:19:19.330Z,1601410759.330 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1 2020-09-29T20:19:19.746Z,1601410759.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2020-09-29T20:19:19.746Z,1601410759.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive 2020-09-29T20:19:19.746Z,1601410759.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2020-09-29T20:19:19.747Z,1601410759.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2020-09-29T20:19:19.748Z,1601410759.748 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2020-09-29T20:19:19.748Z,1601410759.748 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2020-09-29T20:19:19.748Z,1601410759.748 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2020-09-29T20:19:20.090Z,1601410760.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2020-09-29T20:19:20.090Z,1601410760.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 6.000000 m 2020-09-29T20:19:20.091Z,1601410760.091 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2020-09-29T20:19:20.091Z,1601410760.091 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2020-09-29T20:19:20.091Z,1601410760.091 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2020-09-29T20:19:43.783Z,1601410783.783 [CTD_Seabird](ERROR): Salinity reading out of range: 27.482609 psu 2020-09-29T20:19:46.795Z,1601410786.795 [CTD_Seabird](ERROR): Salinity reading out of range: 6.047470 psu 2020-09-29T20:19:47.799Z,1601410787.799 [CTD_Seabird](ERROR): Salinity reading out of range: 27.757168 psu 2020-09-29T20:19:48.804Z,1601410788.804 [CTD_Seabird](ERROR): Salinity reading out of range: 22.724821 psu 2020-09-29T20:19:50.382Z,1601410790.382 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-29T20:19:50.811Z,1601410790.811 [CTD_Seabird](ERROR): Salinity reading out of range: 26.906891 psu 2020-09-29T20:20:55.117Z,1601410855.117 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.238579 2020-09-29T20:21:00.589Z,1601410860.589 [Radio_Surface](INFO): Powering down 2020-09-29T20:21:01.141Z,1601410861.141 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-09-29T20:21:22.097Z,1601410882.097 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2020-09-29T20:21:22.098Z,1601410882.098 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2020-09-29T20:21:22.098Z,1601410882.098 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2020-09-29T20:21:22.098Z,1601410882.098 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth 2020-09-29T20:21:22.098Z,1601410882.098 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2020-09-29T20:21:22.098Z,1601410882.098 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2020-09-29T20:21:22.099Z,1601410882.099 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize. 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2020-09-29T20:21:22.100Z,1601410882.100 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize. 2020-09-29T20:21:22.101Z,1601410882.101 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1 2020-09-29T20:21:22.527Z,1601410882.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 6.045233 m 2020-09-29T20:21:22.527Z,1601410882.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped 2020-09-29T20:21:22.527Z,1601410882.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1 2020-09-29T20:21:22.527Z,1601410882.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component. 2020-09-29T20:21:22.527Z,1601410882.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1 2020-09-29T20:21:22.528Z,1601410882.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1 2020-09-29T20:22:15.419Z,1601410935.419 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude 2020-09-29T20:23:33.447Z,1601411013.447 [CBIT](CRITICAL): STOP DEPTH REACHED. Terminating Mission. 2020-09-29T20:23:33.447Z,1601411013.447 [Supervisor](INFO): Stop Mission called by CBIT::checkDepth 2020-09-29T20:23:33.825Z,1601411013.825 [MissionManager](INFO): MissionManager is completed. 2020-09-29T20:23:33.825Z,1601411013.825 [MissionManager](INFO): Uninitializing Mission ballast_and_trim 2020-09-29T20:23:33.825Z,1601411013.825 [ballast_and_trim] Stopped 2020-09-29T20:23:33.825Z,1601411013.825 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim 2020-09-29T20:23:33.825Z,1601411013.825 [ballast_and_trim:Science] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science:B] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science:C] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science:Read_Oil] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science:OceanCurrent] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:Science:OceanCurrent:A.] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:StandardEnvelopes] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:StandardEnvelopes](DEBUG): Aggregate::uninitialize ballast_and_trim:StandardEnvelopes 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope] Stopped 2020-09-29T20:23:33.826Z,1601411013.826 [ballast_and_trim:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped 2020-09-29T20:23:33.827Z,1601411013.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize. 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:RunDepth1] Stopped 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:RunDepth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B] Stopped 2020-09-29T20:23:33.828Z,1601411013.828 [ballast_and_trim:RunBallastAndTrim:RunDepth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:RunDepth1:B 2020-09-29T20:23:33.974Z,1601411013.974 [CommandLine](FAULT): Scheduling is paused 2020-09-29T20:23:33.974Z,1601411013.974 [CBIT](INFO): Critical error at 20200929T202333 2020-09-29T20:23:34.009Z,1601411014.009 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-09-29T20:23:34.243Z,1601411014.243 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2020-09-29T20:23:34.244Z,1601411014.244 [DefaultWithUndock] Running Loop=1 2020-09-29T20:23:34.244Z,1601411014.244 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2020-09-29T20:23:34.244Z,1601411014.244 [DefaultWithUndock:C.GoToSurface] Running Loop=1 2020-09-29T20:23:34.244Z,1601411014.244 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-29T20:23:34.244Z,1601411014.244 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-29T20:23:34.261Z,1601411014.261 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-29T20:23:34.261Z,1601411014.261 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-29T20:23:34.261Z,1601411014.261 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-29T20:23:34.262Z,1601411014.262 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-29T20:23:34.262Z,1601411014.262 [DefaultWithUndock:A.Wait] Running Loop=1 2020-09-29T20:23:34.262Z,1601411014.262 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2020-09-29T20:23:34.636Z,1601411014.636 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -24.00 mm (1 active estimators). 2020-09-29T20:23:34.921Z,1601411014.921 [Radio_Surface](INFO): Powering up 2020-09-29T20:23:40.785Z,1601411020.785 [DataOverHttps](INFO): Radio surface powered ON. 2020-09-29T20:24:04.533Z,1601411044.533 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2020-09-29T20:24:04.533Z,1601411044.533 [DefaultWithUndock:A.Wait] Stopped 2020-09-29T20:24:04.533Z,1601411044.533 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2020-09-29T20:24:04.534Z,1601411044.534 [DefaultWithUndock:B.Undock] Running Loop=1 2020-09-29T20:24:04.534Z,1601411044.534 [DefaultWithUndock:B.Undock](DEBUG): Initialize. 2020-09-29T20:24:04.534Z,1601411044.534 [DefaultWithUndock:B.Undock](DEBUG): Initializing internal variables to default values. 2020-09-29T20:24:05.297Z,1601411045.297 [PowerOnly](INFO): Powering up loadControl 2020-09-29T20:24:06.103Z,1601411046.103 [DDM](INFO): Detaching. Opening Latch 2020-09-29T20:24:06.508Z,1601411046.508 [DDM](INFO): Detaching. Opening Latch 2020-09-29T20:24:06.911Z,1601411046.911 [DDM](INFO): Detaching. Opening Latch 2020-09-29T20:24:08.248Z,1601411048.248 [DefaultWithUndock:B.Undock](INFO): Detaching from dock. 2020-09-29T20:24:08.613Z,1601411048.613 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer. 2020-09-29T20:24:10.905Z,1601411050.905 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:24:28.790Z,1601411068.790 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby. 2020-09-29T20:24:29.534Z,1601411069.534 [DDM](INFO): Closing Latch 2020-09-29T20:24:29.938Z,1601411069.938 [DDM](INFO): Closing Latch 2020-09-29T20:24:30.344Z,1601411070.344 [DDM](INFO): Closing Latch 2020-09-29T20:24:31.669Z,1601411071.669 [DefaultWithUndock:B.Undock](INFO): DDM at standby. 2020-09-29T20:24:32.003Z,1601411072.003 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete. 2020-09-29T20:24:32.004Z,1601411072.004 [DefaultWithUndock:B.Undock] Stopped 2020-09-29T20:24:46.045Z,1601411086.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:25:21.203Z,1601411121.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:25:36.620Z,1601411136.620 [PowerOnly](INFO): Powering down loadControl 2020-09-29T20:25:56.345Z,1601411156.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:26:31.501Z,1601411191.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:27:06.641Z,1601411226.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:27:12.379Z,1601411232.379 [DefaultWithUndock:CheckIn] Running Loop=1 2020-09-29T20:27:12.379Z,1601411232.379 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn 2020-09-29T20:27:12.379Z,1601411232.379 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1 2020-09-29T20:27:26.505Z,1601411246.505 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error 2020-09-29T20:27:33.368Z,1601411253.368 [NAL9602](INFO): SBD MO Status=0, MOMSN=11400, MT Status=0, MTMSN=0 2020-09-29T20:27:33.368Z,1601411253.368 [NAL9602](INFO): No messages in MT queue 2020-09-29T20:27:34.571Z,1601411254.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202733.00,A,4133.23693,N,07042.55825,W,0.078,0.00,290920,,,A*72 2020-09-29T20:27:34.573Z,1601411254.573 [NAL9602](INFO): GPS fix at 20200929T202733: (41.553949, -70.709304) 2020-09-29T20:27:34.603Z,1601411254.603 [DefaultWithUndock:CheckIn:Read_GPS] Stopped 2020-09-29T20:27:34.603Z,1601411254.603 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1 2020-09-29T20:27:41.789Z,1601411261.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:28:07.315Z,1601411287.315 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-29T20:28:16.937Z,1601411296.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-09-29T20:28:25.977Z,1601411305.977 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003290 2020-09-29T20:28:29.992Z,1601411309.992 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20200929T201206/Courier0012.lzma 2020-09-29T20:28:30.994Z,1601411310.994 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Courier0012.lzma.bak 2020-09-29T20:28:30.995Z,1601411310.995 [DataOverHttps](INFO): SBD MOMSN=41264 2020-09-29T20:28:40.035Z,1601411320.035 [DataOverHttps](INFO): Sending 1595 bytes from file Logs/20200929T201206/Express0013.lzma 2020-09-29T20:28:41.035Z,1601411321.035 [DataOverHttps](INFO): Moved sent file to Logs/20200929T201206/Express0013.lzma.bak 2020-09-29T20:28:41.035Z,1601411321.035 [DataOverHttps](INFO): SBD MOMSN=41268 2020-09-29T20:28:42.478Z,1601411322.478 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped 2020-09-29T20:28:42.479Z,1601411322.479 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1 2020-09-29T20:28:42.479Z,1601411322.479 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-29T20:30:31.482Z,1601411431.482 [DataOverHttps](IMPORTANT): SBD MTMSN=20200929T203030 2020-09-29T20:30:35.500Z,1601411435.500 [DataOverHttps](INFO): Received command:restart app 2020-09-29T20:30:35.597Z,1601411435.597 [CommandLine](IMPORTANT): got command restart application 2020-09-29T20:30:36.601Z,1601411436.601 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:36.601Z,1601411436.601 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:36.636Z,1601411436.636 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-09-29T20:30:36.637Z,1601411436.637 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:36.637Z,1601411436.637 [CommandLine](INFO): Join timeout helper Thread ID is 2383 2020-09-29T20:30:36.638Z,1601411436.638 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-09-29T20:30:36.638Z,1601411436.638 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:36.638Z,1601411436.638 [NavChartDb](INFO): Join timeout helper Thread ID is 2384 2020-09-29T20:30:36.689Z,1601411436.689 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:36.689Z,1601411436.689 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:36.697Z,1601411436.697 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-09-29T20:30:36.697Z,1601411436.697 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:36.697Z,1601411436.697 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2385 2020-09-29T20:30:37.005Z,1601411437.005 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:37.005Z,1601411437.005 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-09-29T20:30:37.006Z,1601411437.006 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:37.021Z,1601411437.021 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-09-29T20:30:37.021Z,1601411437.021 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:37.021Z,1601411437.021 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2386 2020-09-29T20:30:37.521Z,1601411437.521 [CTD_Seabird](INFO): Powering down 2020-09-29T20:30:37.533Z,1601411437.533 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:37.533Z,1601411437.533 [CTD_Seabird](INFO): Powering down 2020-09-29T20:30:37.545Z,1601411437.545 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:37.545Z,1601411437.545 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-09-29T20:30:37.545Z,1601411437.545 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:37.545Z,1601411437.545 [Radio_Surface](INFO): Join timeout helper Thread ID is 2387 2020-09-29T20:30:37.913Z,1601411437.913 [Radio_Surface](INFO): Powering down 2020-09-29T20:30:37.914Z,1601411437.914 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:37.914Z,1601411437.914 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:37.921Z,1601411437.921 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-09-29T20:30:37.922Z,1601411437.922 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:37.922Z,1601411437.922 [Onboard](INFO): Join timeout helper Thread ID is 2388 2020-09-29T20:30:38.509Z,1601411438.509 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-09-29T20:30:39.905Z,1601411439.905 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:39.905Z,1601411439.905 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:39.909Z,1601411439.909 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-09-29T20:30:39.909Z,1601411439.909 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:39.910Z,1601411439.910 [DataOverHttps](INFO): Join timeout helper Thread ID is 2389 2020-09-29T20:30:40.517Z,1601411440.517 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:40.517Z,1601411440.517 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:40.529Z,1601411440.529 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-09-29T20:30:40.529Z,1601411440.529 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:40.530Z,1601411440.530 [logger](INFO): Join timeout helper Thread ID is 2390 2020-09-29T20:30:40.541Z,1601411440.541 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:40.541Z,1601411440.541 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:40.549Z,1601411440.549 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-09-29T20:30:40.549Z,1601411440.549 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:40.550Z,1601411440.550 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-09-29T20:30:40.550Z,1601411440.550 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:40.550Z,1601411440.550 [controlThread](INFO): Join timeout helper Thread ID is 2391 2020-09-29T20:30:40.801Z,1601411440.801 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-29T20:30:40.801Z,1601411440.801 [controlThread](DEBUG): Uninitializing ControlThread 2020-09-29T20:30:40.801Z,1601411440.801 [AHRS_M2](INFO): Powering down 2020-09-29T20:30:40.873Z,1601411440.873 [DDM](INFO): Powering down 2020-09-29T20:30:40.945Z,1601411440.945 [DUSBL_Hydroid](INFO): Powering down 2020-09-29T20:30:41.017Z,1601411441.017 [Micromodem](INFO): Powering down 2020-09-29T20:30:41.113Z,1601411441.113 [NAL9602](INFO): Powering down 2020-09-29T20:30:41.185Z,1601411441.185 [RDI_Pathfinder](INFO): Powering down 2020-09-29T20:30:41.257Z,1601411441.257 [RDI_PathfinderUp](INFO): Powering down 2020-09-29T20:30:41.258Z,1601411441.258 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-09-29T20:30:41.259Z,1601411441.259 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-09-29T20:30:41.260Z,1601411441.260 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-09-29T20:30:41.261Z,1601411441.261 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock] Stopped 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock:C.GoToSurface] Stopped 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock:CheckIn] Stopped 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock:CheckIn:C.Wait] Stopped 2020-09-29T20:30:41.261Z,1601411441.261 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-29T20:30:41.264Z,1601411441.264 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-09-29T20:30:41.277Z,1601411441.277 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-09-29T20:30:41.277Z,1601411441.277 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-09-29T20:30:41.278Z,1601411441.278 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-09-29T20:30:41.278Z,1601411441.278 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-09-29T20:30:41.278Z,1601411441.278 [BuoyancyServo](INFO): Powering down 2020-09-29T20:30:41.293Z,1601411441.293 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-09-29T20:30:41.293Z,1601411441.293 [ElevatorServo](INFO): Powering down 2020-09-29T20:30:41.294Z,1601411441.294 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-09-29T20:30:41.294Z,1601411441.294 [MassServo](INFO): Powering down 2020-09-29T20:30:41.294Z,1601411441.295 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-29T20:30:41.295Z,1601411441.295 [RudderServo](INFO): Powering down 2020-09-29T20:30:41.295Z,1601411441.295 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-09-29T20:30:41.295Z,1601411441.295 [ThrusterServo](INFO): Powering down 2020-09-29T20:30:41.296Z,1601411441.296 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-09-29T20:30:41.297Z,1601411441.297 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-09-29T20:30:41.297Z,1601411441.297 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-09-29T20:30:41.297Z,1601411441.297 [CBIT](DEBUG): Powering off loads. 2020-09-29T20:30:41.309Z,1601411441.309 [CBIT](DEBUG): Disabling WDT. 2020-09-29T20:30:41.321Z,1601411441.321 [CBIT](DEBUG): Opening all GF detection circuits. 2020-09-29T20:30:41.321Z,1601411441.321 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.373Z,1601411441.373 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.376Z,1601411441.376 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.385Z,1601411441.385 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.432Z,1601411441.432 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.435Z,1601411441.435 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.492Z,1601411441.492 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-29T20:30:41.562Z,1601411441.562 [logger ThreadHandler](INFO): Thread cancelled.