2025-04-22T16:33:01.016Z,1745339581.016 [CommandExec](IMPORTANT): got command restart logs 2025-04-22T16:33:14.248Z,1745339594.248 [NAL9602](INFO): Powering up NAL9602 2025-04-22T16:33:25.156Z,1745339605.156 [NAL9602](INFO): NAL9602 initialized 2025-04-22T16:36:45.989Z,1745339805.989 [CommandExec](IMPORTANT): got command schedule 2025-04-22T16:36:45.990Z,1745339805.990 [CommandExec](FAULT): Scheduling was paused by a command at 2025-04-22T14:29:13Z 2025-04-22T16:36:54.221Z,1745339814.221 [CommandExec](IMPORTANT): got command schedule clear 2025-04-22T16:36:54.221Z,1745339814.221 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2025-04-22T16:36:54.222Z,1745339814.222 [CommandExec](IMPORTANT): got command schedule resume 2025-04-22T16:36:54.222Z,1745339814.222 [CommandExec](IMPORTANT): Scheduling is resumed 2025-04-22T16:37:42.166Z,1745339862.166 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:32:41.0Z 2025-04-22T16:37:42.166Z,1745339862.166 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:37:42.167Z,1745339862.167 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:37:50.996Z,1745339870.996 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T012554/Courier0160.lzma 2025-04-22T16:37:51.998Z,1745339871.998 [DataOverHttps](INFO): Moved sent file to Logs/20250422T012554/Courier0160.lzma.bak 2025-04-22T16:37:51.998Z,1745339871.998 [DataOverHttps](INFO): SBD MOMSN=24710290 2025-04-22T16:38:10.634Z,1745339890.634 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250422T163301/Courier0000.lzma 2025-04-22T16:38:11.635Z,1745339891.635 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Courier0000.lzma.bak 2025-04-22T16:38:11.635Z,1745339891.635 [DataOverHttps](INFO): SBD MOMSN=24710299 2025-04-22T16:38:30.830Z,1745339910.830 [DataOverHttps](INFO): Sending 492 bytes from file Logs/20250422T012554/Express0161.lzma 2025-04-22T16:38:31.830Z,1745339911.830 [DataOverHttps](INFO): Moved sent file to Logs/20250422T012554/Express0161.lzma.bak 2025-04-22T16:38:31.830Z,1745339911.830 [DataOverHttps](INFO): SBD MOMSN=24710308 2025-04-22T16:38:44.662Z,1745339924.662 [NAL9602](INFO): SBD MO Status=2, MOMSN=8274, MT Status=2, MTMSN=0 2025-04-22T16:38:44.662Z,1745339924.662 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T16:38:45.044Z,1745339925.044 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:38:51.225Z,1745339931.225 [DataOverHttps](INFO): Sending 423 bytes from file Logs/20250422T163301/Express0001.lzma 2025-04-22T16:38:52.220Z,1745339932.220 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0001.lzma.bak 2025-04-22T16:38:52.221Z,1745339932.221 [DataOverHttps](INFO): SBD MOMSN=24710333 2025-04-22T16:39:11.872Z,1745339951.872 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250422T163301/Express0004.lzma 2025-04-22T16:39:12.875Z,1745339952.875 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0004.lzma.bak 2025-04-22T16:39:12.875Z,1745339952.875 [DataOverHttps](INFO): SBD MOMSN=24710342 2025-04-22T16:39:14.202Z,1745339954.202 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:39:14.203Z,1745339954.203 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:39:14.203Z,1745339954.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:39:15.752Z,1745339955.752 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:39:31.593Z,1745339971.593 [Waterlinked](INFO): Powering down 2025-04-22T16:40:32.658Z,1745340032.658 [DataOverHttps](IMPORTANT): SBD MTMSN=20250422T164031 2025-04-22T16:40:40.609Z,1745340040.609 [DataOverHttps](INFO): Received command: sched 20250422T17 "gfisolate" 2025-04-22T16:40:40.660Z,1745340040.660 [CommandExec](IMPORTANT): got command schedule 20250422T170000 "gfisolate" 2025-04-22T16:40:40.660Z,1745340040.660 [CommandExec](IMPORTANT): Scheduled #1: "gfisolate", AT TIME: 2025-04-22T17:00:00Z 2025-04-22T16:40:41.610Z,1745340041.610 [DataOverHttps](IMPORTANT): SBD MTMSN=20250422T164040 2025-04-22T16:40:43.200Z,1745340043.200 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 2025-04-22T16:40:43.444Z,1745340043.444 [Reporter](INFO): platform_buoyancy_position 986.003418 cc 2025-04-22T16:40:49.377Z,1745340049.377 [DataOverHttps](INFO): Received command: load Engineering/sink.tl;set sink.SinkDuration 0.6 h;set sink.TargetDepthTimeout 12 h;set sink.MinAltitude 0.00001 m;set sink.MinOffshore 0.0001 km;run 2025-04-22T16:40:49.484Z,1745340049.484 [CommandExec](IMPORTANT): got command load ./Missions/Engineering/sink.tl 2025-04-22T16:40:49.484Z,1745340049.484 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/sink.tl 2025-04-22T16:40:49.489Z,1745340049.489 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/sink.tl 2025-04-22T16:40:49.491Z,1745340049.491 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/sink.tl 2025-04-22T16:40:54.019Z,1745340054.019 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/sink.tl translated into: 2 20 20 1 2.5 20 10 45 10 0.5 25 98 5 30 2 2025-04-22T16:40:54.025Z,1745340054.025 [MissionManager](INFO): DefineArg sink.SinkDuration = 2.000000 h 2025-04-22T16:40:54.027Z,1745340054.027 [MissionManager](INFO): DefineArg sink.WaitToFloat = 20.000000 min 2025-04-22T16:40:54.029Z,1745340054.029 [MissionManager](INFO): DefineArg sink.Depth = 20.000000 m 2025-04-22T16:40:54.031Z,1745340054.031 [MissionManager](INFO): DefineArg sink.DepthDeadband = 1.000000 m 2025-04-22T16:40:54.034Z,1745340054.034 [MissionManager](INFO): DefineArg sink.SurfaceThreshold = 2.500000 m 2025-04-22T16:40:54.036Z,1745340054.036 [MissionManager](INFO): DefineArg sink.TargetDepthTimeout = 20.000000 min 2025-04-22T16:40:54.038Z,1745340054.038 [MissionManager](INFO): DefineArg sink.DoBallastAndTrim = 0 bool 2025-04-22T16:40:54.040Z,1745340054.040 [MissionManager](INFO): DefineArg sink.BTSettleTime = 10.000000 min 2025-04-22T16:40:54.042Z,1745340054.042 [MissionManager](INFO): DefineArg sink.EstimationTimeout = 45.000000 min 2025-04-22T16:40:54.044Z,1745340054.044 [MissionManager](INFO): DefineArg sink.MinEstimationTime = 10.000000 min 2025-04-22T16:40:54.046Z,1745340054.046 [MissionManager](INFO): DefineArg sink.MassEstimationErrorBound = 0.500000 mm 2025-04-22T16:40:54.049Z,1745340054.049 [MissionManager](INFO): DefineArg sink.BuoyEstimationErrorBound = 25.000000 cc 2025-04-22T16:40:54.051Z,1745340054.051 [MissionManager](INFO): DefineArg sink.EstimationConfidence = 98.000000 % 2025-04-22T16:40:54.104Z,1745340054.104 [MissionManager](INFO): DefineArg sink.MinAltitude = 5.000000 m 2025-04-22T16:40:54.106Z,1745340054.106 [MissionManager](INFO): DefineArg sink.MaxDepth = 30.000000 m 2025-04-22T16:40:54.118Z,1745340054.118 [MissionManager](INFO): DefineArg sink.MinOffshore = 2.000000 km 2025-04-22T16:40:54.120Z,1745340054.120 [MissionManager](INFO): DefineArg sink.SkipComms = 0 bool 2025-04-22T16:40:54.122Z,1745340054.122 [MissionManager](INFO): DefineOutput sink.MissionTimeout = 2.000000 h 2025-04-22T16:40:54.124Z,1745340054.124 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2025-04-22T16:40:54.124Z,1745340054.124 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2025-04-22T16:40:54.155Z,1745340054.155 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2025-04-22T16:40:57.387Z,1745340057.387 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN 0 NaN NaN 5 0 0 0 0 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN PeakDetectVsDepth EdgeDetectVsDepth GoDown, ChlPeakThresh, PeakChl, PeakChlDepth, EdgeChl, EdgeChlDepth = , , , , , PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth _.planktivore_LM_AvgRois EdgeDetectVsDepth _.planktivore_LM_AvgRois GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth = , , , , , PeakDetectVsDepth _.planktivore_HM_AvgRois EdgeDetectVsDepth _.planktivore_HM_AvgRois GoDown, PlanktivoreHMavgROIPeakThresh, PeakPlanktivoreHMavgROI, PeakPlanktivoreHMavgROIDepth, EdgePlanktivoreHMavgROI, EdgePlanktivoreHMavgROIDepth = , , , , , PeakDetectVsDepth _.planktivore_diatoms EdgeDetectVsDepth _.planktivore_diatoms GoDown, PlanktivoreDiatomsPeakThresh, PeakPlanktivoreDiatoms, PeakPlanktivoreDiatomsDepth, EdgePlanktivoreDiatoms, EdgePlanktivoreDiatomsDepth = , , , , , PeakDetectVsDepth _.planktivore_dinoflagellates EdgeDetectVsDepth _.planktivore_dinoflagellates GoDown, PlanktivoreDinoflagellatesPeakThresh, PeakPlanktivoreDinoflagellates, PeakPlanktivoreDinoflagellatesDepth, EdgePlanktivoreDinoflagellates, EdgePlanktivoreDinoflagellatesDepth = , , , , , Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2025-04-22T16:40:57.427Z,1745340057.427 [MissionManager](INFO): DefineArg sink:Science.PeakDetectChlActive = 0 bool 2025-04-22T16:40:57.429Z,1745340057.429 [MissionManager](INFO): DefineArg sink:Science.EdgeDetectChlActive = 0 bool 2025-04-22T16:40:57.431Z,1745340057.431 [MissionManager](INFO): DefineArg sink:Science.TimeWindowPeakReport = nan min 2025-04-22T16:40:57.433Z,1745340057.433 [MissionManager](INFO): DefineArg sink:Science.HighestChlPeakReportActive = 0 bool 2025-04-22T16:40:57.434Z,1745340057.434 [MissionManager](INFO): DefineArg sink:Science.HighestSaltPeakReportActive = 0 bool 2025-04-22T16:40:57.436Z,1745340057.436 [MissionManager](INFO): DefineArg sink:Science.HighestOilPeakReportActive = 0 bool 2025-04-22T16:40:57.438Z,1745340057.438 [MissionManager](INFO): DefineArg sink:Science.PatchTracking = 0 bool 2025-04-22T16:40:57.440Z,1745340057.440 [MissionManager](INFO): DefineArg sink:Science.FilterWidthHorizontal = 3.000000 count 2025-04-22T16:40:57.443Z,1745340057.443 [MissionManager](INFO): DefineArg sink:Science.NumProfilesSlidingwindow = 100.000000 count 2025-04-22T16:40:57.445Z,1745340057.445 [MissionManager](INFO): DefineArg sink:Science.OffPeakFractionHorizontal = 80.000000 % 2025-04-22T16:40:57.447Z,1745340057.447 [MissionManager](INFO): DefineArg sink:Science.PeakDetectNO3Active = 0 bool 2025-04-22T16:40:57.449Z,1745340057.449 [MissionManager](INFO): DefineArg sink:Science.PeakDetectOilActive = 0 bool 2025-04-22T16:40:57.451Z,1745340057.451 [MissionManager](INFO): DefineArg sink:Science.PeakDetectFDOMActive = 0 bool 2025-04-22T16:40:57.453Z,1745340057.453 [MissionManager](INFO): DefineArg sink:Science.PeakDetectSalinityActive = 0 bool 2025-04-22T16:40:57.455Z,1745340057.455 [MissionManager](INFO): DefineArg sink:Science.PeakDetectPlanktivoreLMavgROIActive = 0 bool 2025-04-22T16:40:57.457Z,1745340057.457 [MissionManager](INFO): DefineArg sink:Science.EdgeDetectPlanktivoreLMavgROIActive = 0 bool 2025-04-22T16:40:57.459Z,1745340057.459 [MissionManager](INFO): DefineArg sink:Science.PeakDetectPlanktivoreHMavgROIActive = 0 bool 2025-04-22T16:40:57.501Z,1745340057.501 [MissionManager](INFO): DefineArg sink:Science.EdgeDetectPlanktivoreHMavgROIActive = 0 bool 2025-04-22T16:40:57.503Z,1745340057.503 [MissionManager](INFO): DefineArg sink:Science.PeakDetectPlanktivoreDiatomsActive = 0 bool 2025-04-22T16:40:57.509Z,1745340057.509 [MissionManager](INFO): DefineArg sink:Science.EdgeDetectPlanktivoreDiatomsActive = 0 bool 2025-04-22T16:40:57.511Z,1745340057.511 [MissionManager](INFO): DefineArg sink:Science.PeakDetectPlanktivoreDinoflagellatesActive = 0 bool 2025-04-22T16:40:57.529Z,1745340057.529 [MissionManager](INFO): DefineArg sink:Science.EdgeDetectPlanktivoreDinoflagellatesActive = 0 bool 2025-04-22T16:40:57.531Z,1745340057.531 [MissionManager](INFO): DefineArg sink:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2025-04-22T16:40:57.539Z,1745340057.539 [MissionManager](INFO): DefineArg sink:Science.EnabledAanderaaO2 = 0 bool 2025-04-22T16:40:57.605Z,1745340057.605 [MissionManager](INFO): DefineArg sink:Science.EnabledNeilBrown = 0 bool 2025-04-22T16:40:57.607Z,1745340057.607 [MissionManager](INFO): DefineArg sink:Science.EnabledSeabird = 1 bool 2025-04-22T16:40:57.623Z,1745340057.623 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsBB2FL = 1 bool 2025-04-22T16:40:57.637Z,1745340057.637 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2025-04-22T16:40:57.640Z,1745340057.640 [MissionManager](INFO): DefineArg sink:Science.EnabledWetLabsUBAT = 0 bool 2025-04-22T16:40:57.656Z,1745340057.656 [MissionManager](INFO): DefineArg sink:Science.LowPassWindowLength = 20.000000 count 2025-04-22T16:40:57.662Z,1745340057.662 [MissionManager](INFO): DefineArg sink:Science.MedianFilterLen = 5.000000 count 2025-04-22T16:40:57.664Z,1745340057.664 [MissionManager](INFO): DefineArg sink:Science.ShallowBound = nan m 2025-04-22T16:40:57.666Z,1745340057.666 [MissionManager](INFO): DefineArg sink:Science.DeepBound = nan m 2025-04-22T16:40:57.687Z,1745340057.687 [MissionManager](INFO): DefineArg sink:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2025-04-22T16:40:57.690Z,1745340057.690 [MissionManager](INFO): DefineArg sink:Science.OffPeakFractionVertical = nan % 2025-04-22T16:40:57.692Z,1745340057.692 [MissionManager](INFO): DefineArg sink:Science.ChlPeakThresh = 0.000000 ug/l 2025-04-22T16:40:57.725Z,1745340057.725 [MissionManager](INFO): DefineArg sink:Science.ChlThreshTriggerActive = 0 bool 2025-04-22T16:40:57.727Z,1745340057.727 [MissionManager](INFO): DefineArg sink:Science.ChlLowerThresh = nan ug/l 2025-04-22T16:40:57.757Z,1745340057.757 [MissionManager](INFO): DefineArg sink:Science.ChlUpperThresh = nan ug/l 2025-04-22T16:40:57.759Z,1745340057.759 [MissionManager](INFO): DefineArg sink:Science.NumProfilesThresh = 5.000000 count 2025-04-22T16:40:57.762Z,1745340057.762 [MissionManager](INFO): DefineArg sink:Science.PlanktivoreLMavgROIPeakThresh = 0.000000 count/s 2025-04-22T16:40:57.764Z,1745340057.764 [MissionManager](INFO): DefineArg sink:Science.PlanktivoreHMavgROIPeakThresh = 0.000000 count/s 2025-04-22T16:40:57.780Z,1745340057.780 [MissionManager](INFO): DefineArg sink:Science.PlanktivoreDiatomsPeakThresh = 0.000000 count/ml 2025-04-22T16:40:57.782Z,1745340057.782 [MissionManager](INFO): DefineArg sink:Science.PlanktivoreDinoflagellatesPeakThresh = 0.000000 count/ml 2025-04-22T16:40:57.784Z,1745340057.784 [MissionManager](INFO): DefineOutput sink:Science.GoDown = 1 bool 2025-04-22T16:40:57.787Z,1745340057.787 [MissionManager](INFO): DefineOutput sink:Science.PeakChl = nan ug/l 2025-04-22T16:40:57.805Z,1745340057.805 [MissionManager](INFO): DefineOutput sink:Science.PeakChlDepth = nan m 2025-04-22T16:40:57.807Z,1745340057.807 [MissionManager](INFO): DefineOutput sink:Science.PeakChlTemperature = nan degC 2025-04-22T16:40:57.809Z,1745340057.809 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLatitude = nan arcdeg 2025-04-22T16:40:57.812Z,1745340057.812 [MissionManager](INFO): DefineOutput sink:Science.PeakChlLongitude = nan arcdeg 2025-04-22T16:40:57.814Z,1745340057.814 [MissionManager](INFO): DefineOutput sink:Science.EdgeChl = nan ug/l 2025-04-22T16:40:57.830Z,1745340057.830 [MissionManager](INFO): DefineOutput sink:Science.EdgeChlDepth = nan m 2025-04-22T16:40:57.832Z,1745340057.832 [MissionManager](INFO): DefineOutput sink:Science.EdgeChlTemperature = nan degC 2025-04-22T16:40:57.835Z,1745340057.835 [MissionManager](INFO): DefineOutput sink:Science.EdgeChlLatitude = nan arcdeg 2025-04-22T16:40:57.837Z,1745340057.837 [MissionManager](INFO): DefineOutput sink:Science.EdgeChlLongitude = nan arcdeg 2025-04-22T16:40:57.839Z,1745340057.839 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3 = nan umol/l 2025-04-22T16:40:57.865Z,1745340057.865 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Depth = nan m 2025-04-22T16:40:57.867Z,1745340057.867 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Latitude = nan arcdeg 2025-04-22T16:40:57.870Z,1745340057.870 [MissionManager](INFO): DefineOutput sink:Science.PeakNO3Longitude = nan arcdeg 2025-04-22T16:40:57.872Z,1745340057.872 [MissionManager](INFO): DefineOutput sink:Science.PeakOil = nan kg/m3 2025-04-22T16:40:57.907Z,1745340057.907 [MissionManager](INFO): DefineOutput sink:Science.PeakOilDepth = nan m 2025-04-22T16:40:57.909Z,1745340057.909 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLatitude = nan arcdeg 2025-04-22T16:40:57.912Z,1745340057.912 [MissionManager](INFO): DefineOutput sink:Science.PeakOilLongitude = nan arcdeg 2025-04-22T16:40:57.916Z,1745340057.916 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOM = nan ppb 2025-04-22T16:40:57.918Z,1745340057.918 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMDepth = nan m 2025-04-22T16:40:57.920Z,1745340057.920 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMTemperature = nan degC 2025-04-22T16:40:57.923Z,1745340057.923 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLatitude = nan arcdeg 2025-04-22T16:40:57.989Z,1745340057.989 [MissionManager](INFO): DefineOutput sink:Science.PeakFDOMLongitude = nan arcdeg 2025-04-22T16:40:57.991Z,1745340057.991 [MissionManager](INFO): DefineOutput sink:Science.PeakSalt = nan psu 2025-04-22T16:40:58.010Z,1745340058.010 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltDepth = nan m 2025-04-22T16:40:58.012Z,1745340058.012 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltTemperature = nan degC 2025-04-22T16:40:58.027Z,1745340058.027 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLatitude = nan arcdeg 2025-04-22T16:40:58.041Z,1745340058.041 [MissionManager](INFO): DefineOutput sink:Science.PeakSaltLongitude = nan arcdeg 2025-04-22T16:40:58.044Z,1745340058.044 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreLMavgROI = nan count/s 2025-04-22T16:40:58.062Z,1745340058.062 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreLMavgROIDepth = nan m 2025-04-22T16:40:58.077Z,1745340058.077 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreLMavgROITemperature = nan degC 2025-04-22T16:40:58.079Z,1745340058.079 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreLMavgROILatitude = nan arcdeg 2025-04-22T16:40:58.091Z,1745340058.091 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreLMavgROILongitude = nan arcdeg 2025-04-22T16:40:58.143Z,1745340058.143 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreLMavgROI = nan count/s 2025-04-22T16:40:58.197Z,1745340058.197 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreLMavgROIDepth = nan m 2025-04-22T16:40:58.199Z,1745340058.199 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreLMavgROITemperature = nan degC 2025-04-22T16:40:58.206Z,1745340058.206 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreLMavgROILatitude = nan arcdeg 2025-04-22T16:40:58.208Z,1745340058.208 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreLMavgROILongitude = nan arcdeg 2025-04-22T16:40:58.227Z,1745340058.227 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreHMavgROI = nan count/s 2025-04-22T16:40:58.250Z,1745340058.250 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreHMavgROIDepth = nan m 2025-04-22T16:40:58.252Z,1745340058.252 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreHMavgROITemperature = nan degC 2025-04-22T16:40:58.267Z,1745340058.267 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreHMavgROILatitude = nan arcdeg 2025-04-22T16:40:58.289Z,1745340058.289 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreHMavgROILongitude = nan arcdeg 2025-04-22T16:40:58.292Z,1745340058.292 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreHMavgROI = nan count/s 2025-04-22T16:40:58.302Z,1745340058.302 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreHMavgROIDepth = nan m 2025-04-22T16:40:58.314Z,1745340058.314 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreHMavgROITemperature = nan degC 2025-04-22T16:40:58.316Z,1745340058.316 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreHMavgROILatitude = nan arcdeg 2025-04-22T16:40:58.331Z,1745340058.331 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreHMavgROILongitude = nan arcdeg 2025-04-22T16:40:58.346Z,1745340058.346 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDiatoms = nan count/ml 2025-04-22T16:40:58.348Z,1745340058.348 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDiatomsDepth = nan m 2025-04-22T16:40:58.391Z,1745340058.391 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDiatomsTemperature = nan degC 2025-04-22T16:40:58.410Z,1745340058.410 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDiatomsLatitude = nan arcdeg 2025-04-22T16:40:58.429Z,1745340058.429 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDiatomsLongitude = nan arcdeg 2025-04-22T16:40:58.431Z,1745340058.431 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDiatoms = nan count/ml 2025-04-22T16:40:58.444Z,1745340058.444 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDiatomsDepth = nan m 2025-04-22T16:40:58.466Z,1745340058.466 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDiatomsTemperature = nan degC 2025-04-22T16:40:58.477Z,1745340058.477 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDiatomsLatitude = nan arcdeg 2025-04-22T16:40:58.479Z,1745340058.479 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDiatomsLongitude = nan arcdeg 2025-04-22T16:40:58.482Z,1745340058.482 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDinoflagellates = nan count/ml 2025-04-22T16:40:58.484Z,1745340058.484 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDinoflagellatesDepth = nan m 2025-04-22T16:40:58.499Z,1745340058.499 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDinoflagellatesTemperature = nan degC 2025-04-22T16:40:58.506Z,1745340058.506 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDinoflagellatesLatitude = nan arcdeg 2025-04-22T16:40:58.509Z,1745340058.509 [MissionManager](INFO): DefineOutput sink:Science.PeakPlanktivoreDinoflagellatesLongitude = nan arcdeg 2025-04-22T16:40:58.511Z,1745340058.511 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDinoflagellates = nan count/ml 2025-04-22T16:40:58.540Z,1745340058.540 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDinoflagellatesDepth = nan m 2025-04-22T16:40:58.543Z,1745340058.543 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDinoflagellatesTemperature = nan degC 2025-04-22T16:40:58.573Z,1745340058.573 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDinoflagellatesLatitude = nan arcdeg 2025-04-22T16:40:58.576Z,1745340058.576 [MissionManager](INFO): DefineOutput sink:Science.EdgePlanktivoreDinoflagellatesLongitude = nan arcdeg 2025-04-22T16:40:58.584Z,1745340058.584 [MissionManager](INFO): DefineOutput sink:Science.PatchChl = nan ug/l 2025-04-22T16:40:58.586Z,1745340058.586 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDepth = nan m 2025-04-22T16:40:58.613Z,1745340058.613 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLatitude = nan arcdeg 2025-04-22T16:40:58.615Z,1745340058.615 [MissionManager](INFO): DefineOutput sink:Science.PatchChlLongitude = nan arcdeg 2025-04-22T16:40:58.618Z,1745340058.618 [MissionManager](INFO): DefineOutput sink:Science.PatchChlDistance = nan m 2025-04-22T16:40:58.620Z,1745340058.620 [MissionManager](INFO): DefineOutput sink:Science.PatchSalt = nan psu 2025-04-22T16:40:58.637Z,1745340058.637 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDepth = nan m 2025-04-22T16:40:58.639Z,1745340058.639 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLatitude = nan arcdeg 2025-04-22T16:40:58.641Z,1745340058.641 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltLongitude = nan arcdeg 2025-04-22T16:40:58.643Z,1745340058.643 [MissionManager](INFO): DefineOutput sink:Science.PatchSaltDistance = nan m 2025-04-22T16:40:58.666Z,1745340058.666 [MissionManager](INFO): DefineOutput sink:Science.PatchOil = nan psu 2025-04-22T16:40:58.677Z,1745340058.677 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDepth = nan m 2025-04-22T16:40:58.679Z,1745340058.679 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLatitude = nan arcdeg 2025-04-22T16:40:58.687Z,1745340058.687 [MissionManager](INFO): DefineOutput sink:Science.PatchOilLongitude = nan arcdeg 2025-04-22T16:40:58.702Z,1745340058.702 [MissionManager](INFO): DefineOutput sink:Science.PatchOilDistance = nan m 2025-04-22T16:40:58.704Z,1745340058.704 [MissionManager](INFO): DefineOutput sink:Science.TriggeredOnChl = 0 bool 2025-04-22T16:40:58.732Z,1745340058.732 [MissionManager](INFO): DefineOutput sink:Science.ChlTriggering = nan ug/l 2025-04-22T16:40:58.822Z,1745340058.822 [sink:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:58.866Z,1745340058.866 [sink:Science:EdgeDetectChl:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-04-22T16:40:58.948Z,1745340058.948 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-04-22T16:40:58.998Z,1745340058.998 [sink:Science:PeakDetectNO3:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.008Z,1745340059.008 [sink:Science:PeakDetectOil:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.036Z,1745340059.036 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-04-22T16:40:59.094Z,1745340059.094 [sink:Science:PeakDetectFDOM:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.118Z,1745340059.118 [sink:Science:PeakDetectSalinity:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.148Z,1745340059.148 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-04-22T16:40:59.244Z,1745340059.244 [sink:Science:PeakDetectPlanktivoreLMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.303Z,1745340059.303 [sink:Science:EdgeDetectPlanktivoreLMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-04-22T16:40:59.406Z,1745340059.406 [sink:Science:PeakDetectPlanktivoreHMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.447Z,1745340059.447 [sink:Science:EdgeDetectPlanktivoreHMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-04-22T16:40:59.488Z,1745340059.488 [sink:Science:PeakDetectPlanktivoreDiatoms:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.525Z,1745340059.525 [sink:Science:EdgeDetectPlanktivoreDiatoms:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-04-22T16:40:59.609Z,1745340059.609 [sink:Science:PeakDetectPlanktivoreDinoflagellates:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-04-22T16:40:59.634Z,1745340059.634 [sink:Science:EdgeDetectPlanktivoreDinoflagellates:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-04-22T16:40:59.673Z,1745340059.673 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2025-04-22T16:40:59.680Z,1745340059.680 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-04-22T16:40:59.680Z,1745340059.680 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-04-22T16:40:59.681Z,1745340059.681 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-04-22T16:40:59.867Z,1745340059.867 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-04-22T16:40:59.871Z,1745340059.871 [MissionManager](INFO): DefineArg sink:NeedComms.DiveInterval = 1.000000 h 2025-04-22T16:40:59.874Z,1745340059.874 [MissionManager](INFO): DefineArg sink:NeedComms.WaitForPitchUp = 10.000000 min 2025-04-22T16:40:59.876Z,1745340059.876 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-04-22T16:40:59.878Z,1745340059.878 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceDepthRate = nan m/s 2025-04-22T16:40:59.880Z,1745340059.880 [MissionManager](INFO): DefineArg sink:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-04-22T16:40:59.882Z,1745340059.882 [MissionManager](INFO): DefineArg sink:NeedComms.SurfacingTimeout = 1000.000000 s 2025-04-22T16:40:59.884Z,1745340059.884 [MissionManager](INFO): DefineArg sink:NeedComms.GPSTimeout = 7.000000 min 2025-04-22T16:40:59.887Z,1745340059.887 [MissionManager](INFO): DefineArg sink:NeedComms.CommsTimeout = 30.000000 min 2025-04-22T16:40:59.892Z,1745340059.892 [sink:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-22T16:40:59.997Z,1745340059.997 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-04-22T16:40:59.998Z,1745340059.998 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-04-22T16:40:59.999Z,1745340059.999 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-04-22T16:41:00.140Z,1745340060.140 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-04-22T16:41:00.146Z,1745340060.146 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinAltitude = 5.000000 m 2025-04-22T16:41:00.148Z,1745340060.148 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-04-22T16:41:00.150Z,1745340060.150 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MaxDepth = 200.000000 m 2025-04-22T16:41:00.152Z,1745340060.152 [MissionManager](INFO): DefineArg sink:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-04-22T16:41:00.153Z,1745340060.153 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-04-22T16:41:00.159Z,1745340060.159 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-04-22T16:41:00.171Z,1745340060.171 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-04-22T16:41:00.179Z,1745340060.179 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-04-22T16:41:00.179Z,1745340060.179 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-04-22T16:41:00.180Z,1745340060.180 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-04-22T16:41:00.253Z,1745340060.253 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-04-22T16:41:00.256Z,1745340060.256 [MissionManager](INFO): DefineArg sink:BackseatDriver.EnableBackseat = 0 bool 2025-04-22T16:41:00.259Z,1745340060.259 [sink:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-04-22T16:41:00.261Z,1745340060.261 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-04-22T16:41:00.261Z,1745340060.261 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-04-22T16:41:00.262Z,1745340060.262 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-04-22T16:41:00.440Z,1745340060.440 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-04-22T16:41:00.444Z,1745340060.444 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleAll = 0 bool 2025-04-22T16:41:00.470Z,1745340060.470 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad1 = 0 bool 2025-04-22T16:41:00.472Z,1745340060.472 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad2 = 0 bool 2025-04-22T16:41:00.478Z,1745340060.478 [MissionManager](INFO): DefineArg sink:PowerOnly.SampleLoad3 = 0 bool 2025-04-22T16:41:00.480Z,1745340060.480 [MissionManager](INFO): DefineArg sink:PowerOnly.EnabledPowerOnly = 0 bool 2025-04-22T16:41:00.507Z,1745340060.507 [sink:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-04-22T16:41:00.515Z,1745340060.515 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl 2025-04-22T16:41:00.515Z,1745340060.515 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BallastAndTrim.tl 2025-04-22T16:41:00.516Z,1745340060.516 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BallastAndTrim.tl 2025-04-22T16:41:00.998Z,1745340060.998 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BallastAndTrim.tl translated into: 25 0 0.4 20 0 20 10 45 10 0.25 15 99.9 1 0.25 0.005 0.00075 0 Going to target depth. Speed set to 0 13 0 Waiting for while pumping down to neutral. Moving to Timed out trying to reach the target depth. Stopping mission at current depth of stop 0 Waiting for the vehicle to settle. Depth = gfscan Running ballast and trim. Depth = 2025-04-22T16:41:01.004Z,1745340061.004 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepth = 25.000000 m 2025-04-22T16:41:01.006Z,1745340061.006 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s 2025-04-22T16:41:01.008Z,1745340061.008 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s 2025-04-22T16:41:01.011Z,1745340061.011 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg 2025-04-22T16:41:01.013Z,1745340061.013 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min 2025-04-22T16:41:01.015Z,1745340061.015 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min 2025-04-22T16:41:01.017Z,1745340061.017 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = 10.000000 min 2025-04-22T16:41:01.019Z,1745340061.019 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstTimeout = 45.000000 min 2025-04-22T16:41:01.021Z,1745340061.021 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MinEstTime = 10.000000 min 2025-04-22T16:41:01.023Z,1745340061.023 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = 0.250000 mm 2025-04-22T16:41:01.025Z,1745340061.025 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc 2025-04-22T16:41:01.028Z,1745340061.028 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.EstConfidence = 99.900002 % 2025-04-22T16:41:01.030Z,1745340061.030 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = 1.000000 m 2025-04-22T16:41:01.032Z,1745340061.032 [MissionManager](INFO): DefineArg sink:RunSink:BallastAndTrim.InsertMassDeadband = 0.250000 mm 2025-04-22T16:41:01.044Z,1745340061.044 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct. 2025-04-22T16:41:01.091Z,1745340061.091 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-22T16:41:01.106Z,1745340061.106 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct. 2025-04-22T16:41:01.140Z,1745340061.140 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait. 2025-04-22T16:41:01.148Z,1745340061.148 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct. 2025-04-22T16:41:01.163Z,1745340061.163 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct. 2025-04-22T16:41:01.206Z,1745340061.206 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute. 2025-04-22T16:41:01.226Z,1745340061.226 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct. 2025-04-22T16:41:01.228Z,1745340061.228 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct. 2025-04-22T16:41:01.250Z,1745340061.250 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait. 2025-04-22T16:41:01.251Z,1745340061.251 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute. 2025-04-22T16:41:01.263Z,1745340061.263 [sink:RunSink:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim. 2025-04-22T16:41:01.268Z,1745340061.268 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertDepthDeadband = reader:sink.DepthDeadband 2025-04-22T16:41:01.302Z,1745340061.302 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertApproachDepthTimeout = reader:sink.TargetDepthTimeout 2025-04-22T16:41:01.304Z,1745340061.304 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.InsertSettleTime = reader:sink.BTSettleTime 2025-04-22T16:41:01.305Z,1745340061.305 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstTimeout = reader:sink.EstimationTimeout 2025-04-22T16:41:01.307Z,1745340061.307 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MinEstTime = reader:sink.MinEstimationTime 2025-04-22T16:41:01.308Z,1745340061.308 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.MassEstErrorBound = reader:sink.MassEstimationErrorBound 2025-04-22T16:41:01.309Z,1745340061.309 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.BuoyEstErrorBound = reader:sink.BuoyEstimationErrorBound 2025-04-22T16:41:01.311Z,1745340061.311 [MissionManager](INFO): RedefineArg sink:RunSink:BallastAndTrim.EstConfidence = reader:sink.EstimationConfidence 2025-04-22T16:41:01.321Z,1745340061.321 [sink:FloatUp:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-22T16:41:01.324Z,1745340061.324 [sink:FloatUp:B.Wait](DEBUG): Construct Wait. 2025-04-22T16:41:01.486Z,1745340061.486 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission sink { arguments { SinkDuration = 2 hour """ Overall B&T timeout, or time to sink before ascending if not running B&T. Mission timeout will be SinkDuration + WaitToFloat. """ WaitToFloat = 20 minute """ Timeout for reaching surface threshold on float ascent, vehicle will drive up when reached """ Depth = 20 meter """ Target depth """ DepthDeadband = 1 meter """ How much vertical drift from the specified depth is allowed during the mission. """ SurfaceThreshold = 2.5 meter """ Surface threshold depth, relevant only to the ascent. """ TargetDepthTimeout = 20 minute """ Maximum wait time for the vehicle to reach the target depth. """ DoBallastAndTrim = false """ If true, run B&T, if false, just sit at depth. """ BTSettleTime = 10 minute """ If running B&T, how long to wait after reaching target depth before starting """ EstimationTimeout = 45 minute """ If running B&T, timeout for estimation. """ MinEstimationTime = 10 minute """ If running B&T, minimum time to run estimation before determining convergence (must be shorter than timeout). """ MassEstimationErrorBound = 0.5 millimeter """ If running B&T, desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ BuoyEstimationErrorBound = 25 cubic_centimeter """ If running B&T, desired range of the estimated sample mean from the true mean statistic (smaller values take longer to converge). """ EstimationConfidence = 98 percent """ If running B&T, confidence level that the estimator has reached the desired error range (larger values take longer to converge). """ MinAltitude = 5 meter """ Minimum height above the sea floor for the entire mission. """ MaxDepth = 30 meter """ Maximum depth for the entire mission. """ MinOffshore = 2 kilometer """ Minimum offshore distance for the entire mission. """ SkipComms = false """ Skip start-of-mission GPS fix and comms if true """ } output { MissionTimeout = SinkDuration } timeout duration=MissionTimeout assign in sequence MissionTimeout = SinkDuration + WaitToFloat insert Insert/Science.tl insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl aggregate startComms { run in sequence break if ( SkipComms ) call id="StartingMission" refId="NeedComms" } aggregate RunSink { run in sequence timeout duration=SinkDuration insert Insert/BallastAndTrim.tl { redefineArg InsertDepthDeadband = DepthDeadband redefineArg InsertApproachDepthTimeout = TargetDepthTimeout redefineArg InsertSettleTime = BTSettleTime redefineArg EstTimeout = EstimationTimeout redefineArg MinEstTime = MinEstimationTime redefineArg MassEstErrorBound = MassEstimationErrorBound redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound redefineArg EstConfidence = EstimationConfidence } aggregate RunBallastAndTrim { run in sequence break if ( isNaN ( Depth ) or not DoBallastAndTrim ) assign in sequence BallastAndTrim:InsertDepth = Depth call refId="BallastAndTrim" } aggregate JustSink { run in sequence break if ( isNaN ( Depth ) or DoBallastAndTrim ) assign in sequence BallastAndTrim:InsertDepth = Depth # The B&T insert will never actually invoke the B&T behavior here. # This is a little bit of a hack to avoid duplicating the ApproachDepth etc. # logic the B&T insert uses. assign in sequence BTSettleTime = SinkDuration call refId="BallastAndTrim" } } aggregate FloatUp { """ Float to surface instead of driving. """ run in sequence break if ( Universal:depth < SurfaceThreshold ) behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyDefault } behavior Guidance:Wait { run in sequence set duration = WaitToFloat } } call id="EndingMission" refId="NeedComms" } 2025-04-22T16:41:01.487Z,1745340061.487 [CommandExec](IMPORTANT): Loaded ./Missions/Engineering/sink.tl id=sink 2025-04-22T16:41:15.512Z,1745340075.512 [CommandExec](IMPORTANT): got command set sink.SinkDuration 0.6 hour 2025-04-22T16:41:15.512Z,1745340075.512 [CommandExec](IMPORTANT): got command set sink.TargetDepthTimeout 12 hour 2025-04-22T16:41:15.513Z,1745340075.513 [CommandExec](IMPORTANT): got command set sink.MinAltitude 0.00001 meter 2025-04-22T16:41:15.514Z,1745340075.514 [CommandExec](IMPORTANT): got command set sink.MinOffshore 0.0001 kilometer 2025-04-22T16:41:15.514Z,1745340075.514 [CommandExec](IMPORTANT): got command run 2025-04-22T16:41:15.519Z,1745340075.519 [CommandExec](IMPORTANT): Running 2025-04-22T16:41:15.783Z,1745340075.783 [Default] Stopped 2025-04-22T16:41:15.784Z,1745340075.784 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-22T16:41:15.784Z,1745340075.784 [Default:B.GoToSurface] Stopped 2025-04-22T16:41:15.784Z,1745340075.784 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-22T16:41:15.784Z,1745340075.784 [Default:CheckIn] Stopped 2025-04-22T16:41:15.784Z,1745340075.784 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:41:15.784Z,1745340075.784 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:41:15.784Z,1745340075.784 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:41:15.784Z,1745340075.784 [MissionManager](IMPORTANT): Started mission sink 2025-04-22T16:41:15.785Z,1745340075.785 [sink] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink](DEBUG): Aggregate::initialize sink 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science](DEBUG): Aggregate::initialize sink:Science 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:A] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:B] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:C] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:D] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:E] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:F] Running Loop=1 2025-04-22T16:41:15.785Z,1745340075.785 [sink:Science:Read_Oil] Running Loop=1 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:PeakDetectChl] Running Loop=1 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:PeakDetectChl](DEBUG): Aggregate::initialize sink:Science:PeakDetectChl 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:PeakDetectChl:A] Running Loop=1 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:EdgeDetectChl] Running Loop=1 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:EdgeDetectChl](DEBUG): Aggregate::initialize sink:Science:EdgeDetectChl 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:EdgeDetectChl:A] Running Loop=1 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:HighestChlPeakReport] Running Loop=1 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestChlPeakReport 2025-04-22T16:41:15.786Z,1745340075.786 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:PeakDetectNO3] Running Loop=1 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sink:Science:PeakDetectNO3 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:PeakDetectNO3:A] Running Loop=1 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:PeakDetectOil] Running Loop=1 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:PeakDetectOil](DEBUG): Aggregate::initialize sink:Science:PeakDetectOil 2025-04-22T16:41:15.787Z,1745340075.787 [sink:Science:PeakDetectOil:A] Running Loop=1 2025-04-22T16:41:15.788Z,1745340075.788 [sink:Science:HighestOilPeakReport] Running Loop=1 2025-04-22T16:41:15.788Z,1745340075.788 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestOilPeakReport 2025-04-22T16:41:15.788Z,1745340075.788 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-04-22T16:41:15.788Z,1745340075.788 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-04-22T16:41:15.788Z,1745340075.788 [sink:Science:PeakDetectFDOM] Running Loop=1 2025-04-22T16:41:15.788Z,1745340075.788 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sink:Science:PeakDetectFDOM 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:PeakDetectFDOM:A] Running Loop=1 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:PeakDetectSalinity] Running Loop=1 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sink:Science:PeakDetectSalinity 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:PeakDetectSalinity:A] Running Loop=1 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:HighestSaltPeakReport] Running Loop=1 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sink:Science:HighestSaltPeakReport 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-04-22T16:41:15.789Z,1745340075.789 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:PeakDetectPlanktivoreLMavgROI] Running Loop=1 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize sink:Science:PeakDetectPlanktivoreLMavgROI 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:PeakDetectPlanktivoreLMavgROI:A] Running Loop=1 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:EdgeDetectPlanktivoreLMavgROI] Running Loop=1 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize sink:Science:EdgeDetectPlanktivoreLMavgROI 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:EdgeDetectPlanktivoreLMavgROI:A] Running Loop=1 2025-04-22T16:41:15.790Z,1745340075.790 [sink:Science:PeakDetectPlanktivoreHMavgROI] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize sink:Science:PeakDetectPlanktivoreHMavgROI 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:PeakDetectPlanktivoreHMavgROI:A] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:EdgeDetectPlanktivoreHMavgROI] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize sink:Science:EdgeDetectPlanktivoreHMavgROI 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:EdgeDetectPlanktivoreHMavgROI:A] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:PeakDetectPlanktivoreDiatoms] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize sink:Science:PeakDetectPlanktivoreDiatoms 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:PeakDetectPlanktivoreDiatoms:A] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:EdgeDetectPlanktivoreDiatoms] Running Loop=1 2025-04-22T16:41:15.791Z,1745340075.791 [sink:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize sink:Science:EdgeDetectPlanktivoreDiatoms 2025-04-22T16:41:15.792Z,1745340075.792 [sink:Science:EdgeDetectPlanktivoreDiatoms:A] Running Loop=1 2025-04-22T16:41:15.792Z,1745340075.792 [sink:Science:PeakDetectPlanktivoreDinoflagellates] Running Loop=1 2025-04-22T16:41:15.792Z,1745340075.792 [sink:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize sink:Science:PeakDetectPlanktivoreDinoflagellates 2025-04-22T16:41:15.792Z,1745340075.792 [sink:Science:PeakDetectPlanktivoreDinoflagellates:A] Running Loop=1 2025-04-22T16:41:15.792Z,1745340075.792 [sink:Science:EdgeDetectPlanktivoreDinoflagellates] Running Loop=1 2025-04-22T16:41:15.792Z,1745340075.792 [sink:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize sink:Science:EdgeDetectPlanktivoreDinoflagellates 2025-04-22T16:41:15.793Z,1745340075.793 [sink:Science:EdgeDetectPlanktivoreDinoflagellates:A] Running Loop=1 2025-04-22T16:41:15.793Z,1745340075.793 [sink:Science:ChlThreshTrigger] Running Loop=1 2025-04-22T16:41:15.793Z,1745340075.793 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize sink:Science:ChlThreshTrigger 2025-04-22T16:41:15.793Z,1745340075.793 [sink:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2025-04-22T16:41:15.793Z,1745340075.793 [sink:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2025-04-22T16:41:15.794Z,1745340075.794 [sink:Science:OceanCurrent] Running Loop=1 2025-04-22T16:41:15.794Z,1745340075.794 [sink:Science:OceanCurrent](DEBUG): Aggregate::initialize sink:Science:OceanCurrent 2025-04-22T16:41:15.794Z,1745340075.794 [sink:Science:OceanCurrent:A.] Running Loop=1 2025-04-22T16:41:15.794Z,1745340075.794 [sink:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2025-04-22T16:41:15.794Z,1745340075.794 [sink:StandardEnvelopes] Running Loop=1 2025-04-22T16:41:15.794Z,1745340075.794 [sink:StandardEnvelopes](DEBUG): Aggregate::initialize sink:StandardEnvelopes 2025-04-22T16:41:15.794Z,1745340075.794 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-22T16:41:15.794Z,1745340075.794 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-04-22T16:41:15.794Z,1745340075.794 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-22T16:41:15.794Z,1745340075.794 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-04-22T16:41:15.795Z,1745340075.795 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-04-22T16:41:15.795Z,1745340075.795 [sink:BackseatDriver] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:BackseatDriver](DEBUG): Aggregate::initialize sink:BackseatDriver 2025-04-22T16:41:15.795Z,1745340075.795 [sink:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:PowerOnly] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:PowerOnly](DEBUG): Aggregate::initialize sink:PowerOnly 2025-04-22T16:41:15.795Z,1745340075.795 [sink:PowerOnly:A] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:PowerOnly:B] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:PowerOnly:C] Running Loop=1 2025-04-22T16:41:15.795Z,1745340075.795 [sink:PowerOnly:D] Running Loop=1 2025-04-22T16:41:15.796Z,1745340075.796 [sink:PowerOnly:E.Wait] Running Loop=1 2025-04-22T16:41:15.796Z,1745340075.796 [sink:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:41:15.796Z,1745340075.796 [sink:A] Running Loop=1 2025-04-22T16:41:15.797Z,1745340075.797 [sink:PowerOnly] Running Loop=1 2025-04-22T16:41:15.797Z,1745340075.797 [sink:PowerOnly:D] Stopped 2025-04-22T16:41:15.797Z,1745340075.797 [sink:PowerOnly:C] Stopped 2025-04-22T16:41:15.797Z,1745340075.797 [sink:PowerOnly:B] Stopped 2025-04-22T16:41:15.797Z,1745340075.797 [sink:PowerOnly:A] Stopped 2025-04-22T16:41:15.797Z,1745340075.797 [sink:BackseatDriver] Running Loop=1 2025-04-22T16:41:15.798Z,1745340075.798 [sink:StandardEnvelopes] Running Loop=1 2025-04-22T16:41:15.798Z,1745340075.798 [sink:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-22T16:41:15.798Z,1745340075.798 [sink:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-22T16:41:15.798Z,1745340075.798 [sink:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-22T16:41:15.798Z,1745340075.798 [sink:Science] Running Loop=1 2025-04-22T16:41:15.800Z,1745340075.800 [sink:Science:ChlThreshTrigger] Stopped 2025-04-22T16:41:15.800Z,1745340075.800 [sink:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize sink:Science:ChlThreshTrigger 2025-04-22T16:41:15.800Z,1745340075.800 [sink:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2025-04-22T16:41:15.800Z,1745340075.800 [sink:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2025-04-22T16:41:15.800Z,1745340075.800 [sink:Science:EdgeDetectPlanktivoreDinoflagellates] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize sink:Science:EdgeDetectPlanktivoreDinoflagellates 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:EdgeDetectPlanktivoreDinoflagellates:A] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:PeakDetectPlanktivoreDinoflagellates] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectPlanktivoreDinoflagellates 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:PeakDetectPlanktivoreDinoflagellates:A] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:EdgeDetectPlanktivoreDiatoms] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize sink:Science:EdgeDetectPlanktivoreDiatoms 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:EdgeDetectPlanktivoreDiatoms:A] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:PeakDetectPlanktivoreDiatoms] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectPlanktivoreDiatoms 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:PeakDetectPlanktivoreDiatoms:A] Stopped 2025-04-22T16:41:15.801Z,1745340075.801 [sink:Science:EdgeDetectPlanktivoreHMavgROI] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize sink:Science:EdgeDetectPlanktivoreHMavgROI 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:EdgeDetectPlanktivoreHMavgROI:A] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:PeakDetectPlanktivoreHMavgROI] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectPlanktivoreHMavgROI 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:PeakDetectPlanktivoreHMavgROI:A] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:EdgeDetectPlanktivoreLMavgROI] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize sink:Science:EdgeDetectPlanktivoreLMavgROI 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:EdgeDetectPlanktivoreLMavgROI:A] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:PeakDetectPlanktivoreLMavgROI] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectPlanktivoreLMavgROI 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:PeakDetectPlanktivoreLMavgROI:A] Stopped 2025-04-22T16:41:15.802Z,1745340075.802 [sink:Science:HighestSaltPeakReport] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestSaltPeakReport 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectSalinity] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectSalinity 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectSalinity:A] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectFDOM] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectFDOM 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectFDOM:A] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestOilPeakReport] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestOilPeakReport 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-04-22T16:41:15.803Z,1745340075.803 [sink:Science:PeakDetectOil] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectOil 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:PeakDetectOil:A] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:PeakDetectNO3] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectNO3 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:PeakDetectNO3:A] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:HighestChlPeakReport] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sink:Science:HighestChlPeakReport 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:EdgeDetectChl] Stopped 2025-04-22T16:41:15.804Z,1745340075.804 [sink:Science:EdgeDetectChl](DEBUG): Aggregate::uninitialize sink:Science:EdgeDetectChl 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:EdgeDetectChl:A] Stopped 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:PeakDetectChl] Stopped 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sink:Science:PeakDetectChl 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:PeakDetectChl:A] Stopped 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:OceanCurrent:A.] Running Loop=1 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:Read_Oil] Stopped 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:F] Stopped 2025-04-22T16:41:15.806Z,1745340075.806 [sink:Science:E] Running Loop=1 2025-04-22T16:41:15.807Z,1745340075.807 [sink:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2025-04-22T16:41:15.807Z,1745340075.807 [sink:Science:D] Stopped 2025-04-22T16:41:15.807Z,1745340075.807 [sink:Science:C] Running Loop=1 2025-04-22T16:41:15.807Z,1745340075.807 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2025-04-22T16:41:15.808Z,1745340075.808 [sink:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2025-04-22T16:41:15.808Z,1745340075.808 [sink:Science:B] Running Loop=1 2025-04-22T16:41:15.808Z,1745340075.808 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2025-04-22T16:41:15.809Z,1745340075.809 [sink:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2025-04-22T16:41:15.809Z,1745340075.809 [sink:Science:A] Stopped 2025-04-22T16:41:15.809Z,1745340075.809 [sink:A] Stopped 2025-04-22T16:41:15.810Z,1745340075.810 [sink:D] Running Loop=1 2025-04-22T16:41:16.058Z,1745340076.058 [WetLabsBB2FL](INFO): Powering up 2025-04-22T16:41:16.173Z,1745340076.173 [sink:D] Stopped 2025-04-22T16:41:16.173Z,1745340076.173 [sink:F] Running Loop=1 2025-04-22T16:41:16.585Z,1745340076.585 [sink:F] Stopped 2025-04-22T16:41:16.585Z,1745340076.585 [sink:G] Running Loop=1 2025-04-22T16:41:16.983Z,1745340076.983 [sink:G] Stopped 2025-04-22T16:41:16.983Z,1745340076.983 [sink:H] Running Loop=1 2025-04-22T16:41:17.391Z,1745340077.391 [sink:H] Stopped 2025-04-22T16:41:17.391Z,1745340077.391 [sink:startComms] Running Loop=1 2025-04-22T16:41:17.392Z,1745340077.392 [sink:startComms](DEBUG): Aggregate::initialize sink:startComms 2025-04-22T16:41:17.392Z,1745340077.392 [sink:startComms:StartingMission] Running Loop=1 2025-04-22T16:41:17.392Z,1745340077.392 [sink:startComms:StartingMission](DEBUG): Aggregate::initialize sink:startComms:StartingMission 2025-04-22T16:41:17.792Z,1745340077.792 [sink:NeedComms] Running Loop=1 2025-04-22T16:41:17.801Z,1745340077.801 [sink:NeedComms](DEBUG): Aggregate::initialize sink:NeedComms 2025-04-22T16:41:17.801Z,1745340077.801 [sink:NeedComms:B.GoToSurface] Running Loop=1 2025-04-22T16:41:17.801Z,1745340077.801 [sink:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-22T16:41:17.801Z,1745340077.801 [sink:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-22T16:41:17.802Z,1745340077.802 [sink:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-22T16:41:17.802Z,1745340077.802 [sink:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-22T16:41:17.802Z,1745340077.802 [sink:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-22T16:41:17.803Z,1745340077.803 [sink:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-22T16:41:17.803Z,1745340077.803 [sink:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-22T16:41:17.803Z,1745340077.803 [sink:NeedComms:A] Running Loop=1 2025-04-22T16:41:17.805Z,1745340077.805 [sink:NeedComms:A](INFO): last time_fix was: 1745337615.000000 second since 1970/01/01T00:00:00Z 2025-04-22T16:41:17.805Z,1745340077.805 [sink:NeedComms:A] Stopped 2025-04-22T16:41:18.201Z,1745340078.201 [sink:NeedComms:C] Running Loop=1 2025-04-22T16:41:18.601Z,1745340078.601 [sink:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-22T16:41:19.466Z,1745340079.466 [CTD_Seabird](ERROR): Failed to parse device response: 2025-04-22T16:41:20.206Z,1745340080.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164118.00,A,3648.16943,N,12147.28305,W,0.156,32.49,220425,,,D*42 2025-04-22T16:41:20.208Z,1745340080.208 [NAL9602](INFO): GPS fix at 20250422T164118: (36.802824, -121.788051) 2025-04-22T16:41:20.220Z,1745340080.220 [sink:NeedComms:C] Stopped 2025-04-22T16:41:20.220Z,1745340080.220 [sink:NeedComms:D] Running Loop=1 2025-04-22T16:41:20.678Z,1745340080.678 [sink:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-22T16:41:24.639Z,1745340084.639 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250422T163301/Courier0006.lzma 2025-04-22T16:41:25.342Z,1745340085.342 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Courier0006.lzma.bak 2025-04-22T16:41:25.343Z,1745340085.343 [DataOverHttps](INFO): SBD MOMSN=24710348 2025-04-22T16:41:44.816Z,1745340104.816 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T163301/Courier0009.lzma 2025-04-22T16:41:45.819Z,1745340105.819 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Courier0009.lzma.bak 2025-04-22T16:41:45.819Z,1745340105.819 [DataOverHttps](INFO): SBD MOMSN=24710351 2025-04-22T16:41:55.958Z,1745340115.958 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool 2025-04-22T16:42:08.542Z,1745340128.542 [DataOverHttps](INFO): Sending 695 bytes from file Logs/20250422T163301/Express0007.lzma 2025-04-22T16:42:09.543Z,1745340129.543 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0007.lzma.bak 2025-04-22T16:42:09.543Z,1745340129.543 [DataOverHttps](INFO): SBD MOMSN=24710353 2025-04-22T16:42:20.636Z,1745340140.636 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1745340129 second 2025-04-22T16:42:25.829Z,1745340145.829 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250422T163301/Express0010.lzma 2025-04-22T16:42:26.831Z,1745340146.831 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0010.lzma.bak 2025-04-22T16:42:26.831Z,1745340146.831 [DataOverHttps](INFO): SBD MOMSN=24710372 2025-04-22T16:42:28.238Z,1745340148.238 [sink:NeedComms:D] Stopped 2025-04-22T16:42:28.238Z,1745340148.238 [sink:NeedComms:E] Running Loop=1 2025-04-22T16:42:28.656Z,1745340148.656 [sink:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-22T16:43:16.187Z,1745340196.187 [CommandExec](IMPORTANT): got command set sink.SinkDuration 0.6 hour 2025-04-22T16:43:16.188Z,1745340196.188 [CommandExec](IMPORTANT): got command set sink.TargetDepthTimeout 12 hour 2025-04-22T16:43:16.188Z,1745340196.188 [CommandExec](IMPORTANT): got command set sink.MinAltitude 0.00001 meter 2025-04-22T16:43:16.189Z,1745340196.189 [CommandExec](IMPORTANT): got command set sink.MinOffshore 0.0001 kilometer 2025-04-22T16:43:16.190Z,1745340196.190 [CommandExec](IMPORTANT): got command run 2025-04-22T16:43:16.192Z,1745340196.192 [CommandExec](IMPORTANT): Running 2025-04-22T16:43:16.193Z,1745340196.193 [CommandExec](IMPORTANT): got command failComponent 2025-04-22T16:43:16.193Z,1745340196.193 [CommandExec](IMPORTANT): Failed components: 2025-04-22T16:43:16.193Z,1745340196.193 [CommandExec](IMPORTANT): No failed Components. 2025-04-22T16:44:41.942Z,1745340281.942 [CommandExec](IMPORTANT): got command show stack 2025-04-22T16:44:41.942Z,1745340281.942 [CommandExec](IMPORTANT): Behavior Stack: 2025-04-22T16:44:41.944Z,1745340281.944 [sink:Science](IMPORTANT): Priority 0: sink:Science:B 2025-04-22T16:44:41.945Z,1745340281.945 [sink:Science](IMPORTANT): Priority 1: sink:Science:C 2025-04-22T16:44:41.945Z,1745340281.945 [sink:Science](IMPORTANT): Priority 2: sink:Science:E 2025-04-22T16:44:41.945Z,1745340281.945 [sink:Science:OceanCurrent](IMPORTANT): Priority 3: sink:Science:OceanCurrent:A. 2025-04-22T16:44:41.945Z,1745340281.945 [sink:NeedComms](IMPORTANT): Priority 4: sink:NeedComms:B.GoToSurface 2025-04-22T16:44:41.946Z,1745340281.946 [sink:NeedComms](IMPORTANT): Priority 5: sink:NeedComms:E 2025-04-22T16:44:41.946Z,1745340281.946 [sink:StandardEnvelopes](IMPORTANT): Priority 6: sink:StandardEnvelopes:A.AltitudeEnvelope 2025-04-22T16:44:41.946Z,1745340281.946 [sink:StandardEnvelopes](IMPORTANT): Priority 7: sink:StandardEnvelopes:B.DepthEnvelope 2025-04-22T16:44:41.946Z,1745340281.946 [sink:StandardEnvelopes](IMPORTANT): Priority 8: sink:StandardEnvelopes:C.OffshoreEnvelope 2025-04-22T16:44:41.947Z,1745340281.947 [sink:BackseatDriver](IMPORTANT): Priority 9: sink:BackseatDriver:A.BackseatDriver 2025-04-22T16:44:41.947Z,1745340281.947 [sink:PowerOnly](IMPORTANT): Priority 10: sink:PowerOnly:E.Wait 2025-04-22T16:45:09.727Z,1745340309.727 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool 2025-04-22T16:45:27.778Z,1745340327.778 [CommandExec](IMPORTANT): got command get PowerOnly.loadAtStartup 2025-04-22T16:45:27.779Z,1745340327.779 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup 0 bool 2025-04-22T16:45:46.128Z,1745340346.128 [CommandExec](IMPORTANT): got command get SpeedControl.speedCmd 2025-04-22T16:45:46.129Z,1745340346.129 [CommandExec](IMPORTANT): SpeedControl.speedCmd 0.000000 m/s 2025-04-22T16:46:22.149Z,1745340382.149 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:46:27.006Z,1745340387.006 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164626.00,A,3648.16475,N,12147.28371,W,0.214,141.47,220425,,,D*7D 2025-04-22T16:46:27.008Z,1745340387.008 [NAL9602](INFO): GPS fix at 20250422T164626: (36.802746, -121.788062) 2025-04-22T16:46:27.062Z,1745340387.062 [sink:NeedComms:E] Stopped 2025-04-22T16:46:27.064Z,1745340387.064 [sink:NeedComms](INFO): Completed sink:NeedComms 2025-04-22T16:46:27.064Z,1745340387.064 [sink:NeedComms] Stopped 2025-04-22T16:46:27.064Z,1745340387.064 [sink:NeedComms](DEBUG): Aggregate::uninitialize sink:NeedComms 2025-04-22T16:46:27.064Z,1745340387.064 [sink:NeedComms:B.GoToSurface] Stopped 2025-04-22T16:46:27.064Z,1745340387.064 [sink:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-22T16:46:27.433Z,1745340387.433 [sink:startComms:StartingMission](INFO): Completed sink:startComms:StartingMission 2025-04-22T16:46:27.433Z,1745340387.433 [sink:startComms:StartingMission] Stopped 2025-04-22T16:46:27.433Z,1745340387.433 [sink:startComms:StartingMission](DEBUG): Aggregate::uninitialize sink:startComms:StartingMission 2025-04-22T16:46:27.433Z,1745340387.433 [sink:startComms](INFO): Completed sink:startComms 2025-04-22T16:46:27.433Z,1745340387.433 [sink:startComms] Stopped 2025-04-22T16:46:27.433Z,1745340387.433 [sink:startComms](DEBUG): Aggregate::uninitialize sink:startComms 2025-04-22T16:46:27.433Z,1745340387.433 [sink:RunSink] Running Loop=1 2025-04-22T16:46:27.433Z,1745340387.433 [sink:RunSink](DEBUG): Aggregate::initialize sink:RunSink 2025-04-22T16:46:27.434Z,1745340387.434 [sink:RunSink:RunBallastAndTrim] Running Loop=1 2025-04-22T16:46:27.434Z,1745340387.434 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:RunBallastAndTrim 2025-04-22T16:46:27.434Z,1745340387.434 [sink:RunSink:RunBallastAndTrim:A] Running Loop=1 2025-04-22T16:46:27.836Z,1745340387.836 [sink:RunSink:RunBallastAndTrim] Stopped 2025-04-22T16:46:27.836Z,1745340387.836 [sink:RunSink:RunBallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:RunBallastAndTrim 2025-04-22T16:46:27.836Z,1745340387.836 [sink:RunSink:RunBallastAndTrim:A] Stopped 2025-04-22T16:46:27.836Z,1745340387.836 [sink:RunSink:JustSink] Running Loop=1 2025-04-22T16:46:27.836Z,1745340387.836 [sink:RunSink:JustSink](DEBUG): Aggregate::initialize sink:RunSink:JustSink 2025-04-22T16:46:27.836Z,1745340387.836 [sink:RunSink:JustSink:A] Running Loop=1 2025-04-22T16:46:28.258Z,1745340388.258 [sink:RunSink:JustSink:A] Stopped 2025-04-22T16:46:28.258Z,1745340388.258 [sink:RunSink:JustSink:B] Running Loop=1 2025-04-22T16:46:28.644Z,1745340388.644 [sink:RunSink:JustSink:B] Stopped 2025-04-22T16:46:28.644Z,1745340388.644 [sink:RunSink:JustSink:C] Running Loop=1 2025-04-22T16:46:28.644Z,1745340388.644 [sink:RunSink:JustSink:C](DEBUG): Aggregate::initialize sink:RunSink:JustSink:C 2025-04-22T16:46:29.049Z,1745340389.049 [sink:RunSink:BallastAndTrim] Running Loop=1 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2025-04-22T16:46:29.050Z,1745340389.050 [sink:RunSink:BallastAndTrim:F] Running Loop=1 2025-04-22T16:46:29.052Z,1745340389.052 [sink:RunSink:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 2025-04-22T16:46:29.052Z,1745340389.052 [sink:RunSink:BallastAndTrim:F] Stopped 2025-04-22T16:46:29.052Z,1745340389.052 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Running Loop=1 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize. 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1 2025-04-22T16:46:29.057Z,1745340389.057 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize. 2025-04-22T16:46:29.058Z,1745340389.058 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1 2025-04-22T16:46:29.058Z,1745340389.058 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2025-04-22T16:46:29.058Z,1745340389.058 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1 2025-04-22T16:46:29.058Z,1745340389.058 [sink:RunSink:BallastAndTrim:E] Running Loop=1 2025-04-22T16:46:29.058Z,1745340389.058 [sink:RunSink:BallastAndTrim:D] Running Loop=1 2025-04-22T16:46:29.059Z,1745340389.059 [sink:RunSink:BallastAndTrim:C] Running Loop=1 2025-04-22T16:46:29.059Z,1745340389.059 [sink:RunSink:BallastAndTrim:B] Running Loop=1 2025-04-22T16:46:29.059Z,1745340389.059 [sink:RunSink:BallastAndTrim:A] Running Loop=1 2025-04-22T16:46:29.454Z,1745340389.454 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral. 2025-04-22T16:46:29.454Z,1745340389.454 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped 2025-04-22T16:46:29.454Z,1745340389.454 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped 2025-04-22T16:46:29.455Z,1745340389.455 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Running Loop=1 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting. 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:WaitPreDive 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2025-04-22T16:46:29.843Z,1745340389.843 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize. 2025-04-22T16:46:29.844Z,1745340389.844 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1 2025-04-22T16:46:30.262Z,1745340390.262 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1 2025-04-22T16:46:30.263Z,1745340390.263 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 20.000000 m 2025-04-22T16:46:30.263Z,1745340390.263 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped 2025-04-22T16:46:30.263Z,1745340390.263 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1 2025-04-22T16:46:30.263Z,1745340390.263 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize. 2025-04-22T16:46:36.191Z,1745340396.191 [Reporter](INFO): platform_buoyancy_position 998.896179 cc 2025-04-22T16:46:36.491Z,1745340396.491 [Reporter](INFO): platform_buoyancy_position 998.761841 cc 2025-04-22T16:46:36.798Z,1745340396.798 [Reporter](INFO): platform_buoyancy_position 996.881775 cc 2025-04-22T16:46:37.299Z,1745340397.299 [Reporter](INFO): platform_buoyancy_position 986.272034 cc 2025-04-22T16:46:37.572Z,1745340397.572 [Reporter](INFO): platform_buoyancy_position 986.003418 cc 2025-04-22T16:46:37.987Z,1745340397.987 [Reporter](INFO): platform_buoyancy_position 981.705872 cc 2025-04-22T16:46:38.423Z,1745340398.423 [Reporter](INFO): platform_buoyancy_position 977.408264 cc 2025-04-22T16:46:38.812Z,1745340398.812 [Reporter](INFO): platform_buoyancy_position 973.110596 cc 2025-04-22T16:46:39.223Z,1745340399.223 [Reporter](INFO): platform_buoyancy_position 968.812988 cc 2025-04-22T16:46:39.619Z,1745340399.619 [Reporter](INFO): platform_buoyancy_position 963.843872 cc 2025-04-22T16:46:40.044Z,1745340400.044 [Reporter](INFO): platform_buoyancy_position 958.068970 cc 2025-04-22T16:46:40.456Z,1745340400.456 [Reporter](INFO): platform_buoyancy_position 951.622559 cc 2025-04-22T16:46:40.884Z,1745340400.884 [Reporter](INFO): platform_buoyancy_position 947.324951 cc 2025-04-22T16:46:41.251Z,1745340401.251 [Reporter](INFO): platform_buoyancy_position 942.893066 cc 2025-04-22T16:46:41.675Z,1745340401.675 [Reporter](INFO): platform_buoyancy_position 936.715332 cc 2025-04-22T16:46:42.072Z,1745340402.072 [Reporter](INFO): platform_buoyancy_position 932.283325 cc 2025-04-22T16:46:42.472Z,1745340402.472 [Reporter](INFO): platform_buoyancy_position 925.971313 cc 2025-04-22T16:46:42.992Z,1745340402.992 [Reporter](INFO): platform_buoyancy_position 919.390625 cc 2025-04-22T16:46:43.284Z,1745340403.284 [Reporter](INFO): platform_buoyancy_position 915.227295 cc 2025-04-22T16:46:43.667Z,1745340403.667 [Reporter](INFO): platform_buoyancy_position 910.795410 cc 2025-04-22T16:46:44.095Z,1745340404.095 [Reporter](INFO): platform_buoyancy_position 904.348999 cc 2025-04-22T16:46:44.503Z,1745340404.503 [Reporter](INFO): platform_buoyancy_position 898.574097 cc 2025-04-22T16:46:44.917Z,1745340404.917 [Reporter](INFO): platform_buoyancy_position 893.739258 cc 2025-04-22T16:46:45.287Z,1745340405.287 [Reporter](INFO): platform_buoyancy_position 889.307373 cc 2025-04-22T16:46:45.702Z,1745340405.702 [Reporter](INFO): platform_buoyancy_position 884.338257 cc 2025-04-22T16:46:46.105Z,1745340406.105 [Reporter](INFO): platform_buoyancy_position 878.563354 cc 2025-04-22T16:46:46.266Z,1745340406.266 [CommandExec](IMPORTANT): got command set sink:NeedComms.GPSTimeout 1 minute 2025-04-22T16:46:46.496Z,1745340406.496 [Reporter](INFO): platform_buoyancy_position 873.862915 cc 2025-04-22T16:46:46.906Z,1745340406.906 [Reporter](INFO): platform_buoyancy_position 867.953735 cc 2025-04-22T16:46:47.270Z,1745340407.270 [Reporter](INFO): platform_buoyancy_position 863.656128 cc 2025-04-22T16:46:47.784Z,1745340407.784 [Reporter](INFO): platform_buoyancy_position 857.209717 cc 2025-04-22T16:46:48.207Z,1745340408.207 [Reporter](INFO): platform_buoyancy_position 852.912109 cc 2025-04-22T16:46:48.507Z,1745340408.507 [Reporter](INFO): platform_buoyancy_position 848.480225 cc 2025-04-22T16:46:49.007Z,1745340409.007 [Reporter](INFO): platform_buoyancy_position 842.033813 cc 2025-04-22T16:46:49.271Z,1745340409.271 [Reporter](INFO): platform_buoyancy_position 837.198975 cc 2025-04-22T16:46:49.676Z,1745340409.676 [Reporter](INFO): platform_buoyancy_position 831.289795 cc 2025-04-22T16:46:50.092Z,1745340410.092 [Reporter](INFO): platform_buoyancy_position 826.992188 cc 2025-04-22T16:46:50.510Z,1745340410.510 [Reporter](INFO): platform_buoyancy_position 820.680054 cc 2025-04-22T16:46:50.908Z,1745340410.908 [Reporter](INFO): platform_buoyancy_position 816.248169 cc 2025-04-22T16:46:51.290Z,1745340411.290 [Reporter](INFO): platform_buoyancy_position 809.936096 cc 2025-04-22T16:46:51.708Z,1745340411.708 [Reporter](INFO): platform_buoyancy_position 805.504150 cc 2025-04-22T16:46:52.112Z,1745340412.112 [Reporter](INFO): platform_buoyancy_position 799.192078 cc 2025-04-22T16:46:52.524Z,1745340412.524 [Reporter](INFO): platform_buoyancy_position 794.760193 cc 2025-04-22T16:46:52.957Z,1745340412.957 [Reporter](INFO): platform_buoyancy_position 788.313782 cc 2025-04-22T16:46:53.320Z,1745340413.320 [Reporter](INFO): platform_buoyancy_position 784.150452 cc 2025-04-22T16:46:53.732Z,1745340413.732 [Reporter](INFO): platform_buoyancy_position 778.778442 cc 2025-04-22T16:46:54.144Z,1745340414.144 [Reporter](INFO): platform_buoyancy_position 773.272156 cc 2025-04-22T16:46:54.516Z,1745340414.516 [Reporter](INFO): platform_buoyancy_position 768.974548 cc 2025-04-22T16:46:54.933Z,1745340414.933 [Reporter](INFO): platform_buoyancy_position 762.662476 cc 2025-04-22T16:46:55.348Z,1745340415.348 [Reporter](INFO): platform_buoyancy_position 758.230591 cc 2025-04-22T16:46:55.752Z,1745340415.752 [Reporter](INFO): platform_buoyancy_position 751.784180 cc 2025-04-22T16:46:56.136Z,1745340416.136 [Reporter](INFO): platform_buoyancy_position 747.486572 cc 2025-04-22T16:46:56.552Z,1745340416.552 [Reporter](INFO): platform_buoyancy_position 741.174500 cc 2025-04-22T16:46:56.964Z,1745340416.964 [Reporter](INFO): platform_buoyancy_position 736.742554 cc 2025-04-22T16:46:57.372Z,1745340417.372 [Reporter](INFO): platform_buoyancy_position 730.296143 cc 2025-04-22T16:46:57.763Z,1745340417.763 [Reporter](INFO): platform_buoyancy_position 725.998596 cc 2025-04-22T16:46:58.180Z,1745340418.180 [Reporter](INFO): platform_buoyancy_position 719.686462 cc 2025-04-22T16:46:58.584Z,1745340418.584 [Reporter](INFO): platform_buoyancy_position 715.388855 cc 2025-04-22T16:46:58.984Z,1745340418.984 [Reporter](INFO): platform_buoyancy_position 708.808167 cc 2025-04-22T16:46:59.316Z,1745340419.316 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:46:59.380Z,1745340419.380 [Reporter](INFO): platform_buoyancy_position 704.644836 cc 2025-04-22T16:46:59.800Z,1745340419.800 [Reporter](INFO): platform_buoyancy_position 698.064148 cc 2025-04-22T16:47:00.211Z,1745340420.211 [Reporter](INFO): platform_buoyancy_position 693.766541 cc 2025-04-22T16:47:00.610Z,1745340420.610 [Reporter](INFO): platform_buoyancy_position 687.588745 cc 2025-04-22T16:47:01.003Z,1745340421.003 [Reporter](INFO): platform_buoyancy_position 683.156860 cc 2025-04-22T16:47:01.476Z,1745340421.476 [Reporter](INFO): platform_buoyancy_position 676.576172 cc 2025-04-22T16:47:01.848Z,1745340421.848 [Reporter](INFO): platform_buoyancy_position 672.278564 cc 2025-04-22T16:47:02.220Z,1745340422.220 [Reporter](INFO): platform_buoyancy_position 667.980957 cc 2025-04-22T16:47:02.632Z,1745340422.632 [Reporter](INFO): platform_buoyancy_position 661.668884 cc 2025-04-22T16:47:03.048Z,1745340423.048 [Reporter](INFO): platform_buoyancy_position 655.222473 cc 2025-04-22T16:47:03.456Z,1745340423.456 [Reporter](INFO): platform_buoyancy_position 650.790588 cc 2025-04-22T16:47:03.835Z,1745340423.835 [Reporter](INFO): platform_buoyancy_position 646.492981 cc 2025-04-22T16:47:04.257Z,1745340424.257 [Reporter](INFO): platform_buoyancy_position 640.046570 cc 2025-04-22T16:47:04.650Z,1745340424.650 [Reporter](INFO): platform_buoyancy_position 635.748962 cc 2025-04-22T16:47:05.040Z,1745340425.040 [Reporter](INFO): platform_buoyancy_position 631.048462 cc 2025-04-22T16:47:05.557Z,1745340425.557 [Reporter](INFO): platform_buoyancy_position 625.139282 cc 2025-04-22T16:47:05.983Z,1745340425.983 [Reporter](INFO): platform_buoyancy_position 619.498657 cc 2025-04-22T16:47:06.271Z,1745340426.271 [Reporter](INFO): platform_buoyancy_position 614.395264 cc 2025-04-22T16:47:06.671Z,1745340426.671 [Reporter](INFO): platform_buoyancy_position 609.694763 cc 2025-04-22T16:47:07.183Z,1745340427.183 [Reporter](INFO): platform_buoyancy_position 603.651245 cc 2025-04-22T16:47:07.479Z,1745340427.479 [Reporter](INFO): platform_buoyancy_position 598.682129 cc 2025-04-22T16:47:07.860Z,1745340427.860 [Reporter](INFO): platform_buoyancy_position 592.907288 cc 2025-04-22T16:47:08.284Z,1745340428.284 [Reporter](INFO): platform_buoyancy_position 588.475342 cc 2025-04-22T16:47:08.693Z,1745340428.693 [Reporter](INFO): platform_buoyancy_position 582.028931 cc 2025-04-22T16:47:09.078Z,1745340429.078 [Reporter](INFO): platform_buoyancy_position 577.731384 cc 2025-04-22T16:47:09.499Z,1745340429.499 [Reporter](INFO): platform_buoyancy_position 571.284973 cc 2025-04-22T16:47:09.923Z,1745340429.923 [Reporter](INFO): platform_buoyancy_position 567.121643 cc 2025-04-22T16:47:10.300Z,1745340430.300 [Reporter](INFO): platform_buoyancy_position 560.675232 cc 2025-04-22T16:47:10.711Z,1745340430.711 [Reporter](INFO): platform_buoyancy_position 556.243347 cc 2025-04-22T16:47:11.124Z,1745340431.124 [Reporter](INFO): platform_buoyancy_position 549.931274 cc 2025-04-22T16:47:11.492Z,1745340431.492 [Reporter](INFO): platform_buoyancy_position 545.633667 cc 2025-04-22T16:47:11.918Z,1745340431.918 [Reporter](INFO): platform_buoyancy_position 540.127380 cc 2025-04-22T16:47:12.328Z,1745340432.328 [Reporter](INFO): platform_buoyancy_position 534.755371 cc 2025-04-22T16:47:12.723Z,1745340432.723 [Reporter](INFO): platform_buoyancy_position 528.443237 cc 2025-04-22T16:47:13.112Z,1745340433.112 [Reporter](INFO): platform_buoyancy_position 524.145630 cc 2025-04-22T16:47:13.534Z,1745340433.534 [Reporter](INFO): platform_buoyancy_position 519.445129 cc 2025-04-22T16:47:13.949Z,1745340433.949 [Reporter](INFO): platform_buoyancy_position 513.401672 cc 2025-04-22T16:47:14.316Z,1745340434.316 [Reporter](INFO): platform_buoyancy_position 508.969696 cc 2025-04-22T16:47:14.725Z,1745340434.725 [Reporter](INFO): platform_buoyancy_position 502.523376 cc 2025-04-22T16:47:15.141Z,1745340435.141 [Reporter](INFO): platform_buoyancy_position 498.225800 cc 2025-04-22T16:47:15.579Z,1745340435.579 [Reporter](INFO): platform_buoyancy_position 491.913635 cc 2025-04-22T16:47:15.988Z,1745340435.988 [Reporter](INFO): platform_buoyancy_position 487.616028 cc 2025-04-22T16:47:16.368Z,1745340436.368 [Reporter](INFO): platform_buoyancy_position 481.169678 cc 2025-04-22T16:47:16.768Z,1745340436.768 [Reporter](INFO): platform_buoyancy_position 476.737732 cc 2025-04-22T16:47:17.197Z,1745340437.197 [Reporter](INFO): platform_buoyancy_position 470.291321 cc 2025-04-22T16:47:17.594Z,1745340437.594 [Reporter](INFO): platform_buoyancy_position 465.993774 cc 2025-04-22T16:47:18.044Z,1745340438.044 [Reporter](INFO): platform_buoyancy_position 459.547363 cc 2025-04-22T16:47:18.396Z,1745340438.396 [Reporter](INFO): platform_buoyancy_position 455.249756 cc 2025-04-22T16:47:18.808Z,1745340438.808 [Reporter](INFO): platform_buoyancy_position 448.937622 cc 2025-04-22T16:47:19.188Z,1745340439.188 [Reporter](INFO): platform_buoyancy_position 444.640076 cc 2025-04-22T16:47:19.602Z,1745340439.602 [Reporter](INFO): platform_buoyancy_position 440.208130 cc 2025-04-22T16:47:20.004Z,1745340440.004 [Reporter](INFO): platform_buoyancy_position 433.761719 cc 2025-04-22T16:47:20.384Z,1745340440.384 [Reporter](INFO): platform_buoyancy_position 429.598450 cc 2025-04-22T16:47:20.807Z,1745340440.807 [Reporter](INFO): platform_buoyancy_position 423.152039 cc 2025-04-22T16:47:21.219Z,1745340441.219 [Reporter](INFO): platform_buoyancy_position 418.585815 cc 2025-04-22T16:47:21.592Z,1745340441.592 [Reporter](INFO): platform_buoyancy_position 412.542358 cc 2025-04-22T16:47:22.004Z,1745340442.004 [Reporter](INFO): platform_buoyancy_position 407.976135 cc 2025-04-22T16:47:22.420Z,1745340442.420 [Reporter](INFO): platform_buoyancy_position 401.798340 cc 2025-04-22T16:47:22.800Z,1745340442.800 [Reporter](INFO): platform_buoyancy_position 397.232117 cc 2025-04-22T16:47:23.215Z,1745340443.215 [Reporter](INFO): platform_buoyancy_position 391.322937 cc 2025-04-22T16:47:23.620Z,1745340443.620 [Reporter](INFO): platform_buoyancy_position 386.622437 cc 2025-04-22T16:47:24.040Z,1745340444.040 [Reporter](INFO): platform_buoyancy_position 380.310364 cc 2025-04-22T16:47:24.440Z,1745340444.440 [Reporter](INFO): platform_buoyancy_position 375.878418 cc 2025-04-22T16:47:24.830Z,1745340444.830 [Reporter](INFO): platform_buoyancy_position 371.177917 cc 2025-04-22T16:47:25.243Z,1745340445.243 [Reporter](INFO): platform_buoyancy_position 365.134460 cc 2025-04-22T16:47:25.656Z,1745340445.656 [Reporter](INFO): platform_buoyancy_position 360.702515 cc 2025-04-22T16:47:26.162Z,1745340446.162 [Reporter](INFO): platform_buoyancy_position 354.256165 cc 2025-04-22T16:47:26.599Z,1745340446.599 [Reporter](INFO): platform_buoyancy_position 349.958557 cc 2025-04-22T16:47:26.902Z,1745340446.902 [Reporter](INFO): platform_buoyancy_position 343.646423 cc 2025-04-22T16:47:27.392Z,1745340447.392 [Reporter](INFO): platform_buoyancy_position 339.214539 cc 2025-04-22T16:47:27.676Z,1745340447.676 [Reporter](INFO): platform_buoyancy_position 332.902466 cc 2025-04-22T16:47:28.060Z,1745340448.060 [Reporter](INFO): platform_buoyancy_position 328.470520 cc 2025-04-22T16:47:28.478Z,1745340448.478 [Reporter](INFO): platform_buoyancy_position 322.024109 cc 2025-04-22T16:47:28.887Z,1745340448.887 [Reporter](INFO): platform_buoyancy_position 317.726562 cc 2025-04-22T16:47:29.271Z,1745340449.271 [Reporter](INFO): platform_buoyancy_position 312.623108 cc 2025-04-22T16:47:29.684Z,1745340449.684 [Reporter](INFO): platform_buoyancy_position 306.982544 cc 2025-04-22T16:47:30.200Z,1745340450.200 [Reporter](INFO): platform_buoyancy_position 300.670441 cc 2025-04-22T16:47:30.663Z,1745340450.663 [Reporter](INFO): platform_buoyancy_position 296.372833 cc 2025-04-22T16:47:30.908Z,1745340450.908 [Reporter](INFO): platform_buoyancy_position 291.538025 cc 2025-04-22T16:47:31.428Z,1745340451.428 [Reporter](INFO): platform_buoyancy_position 285.628845 cc 2025-04-22T16:47:31.731Z,1745340451.731 [Reporter](INFO): platform_buoyancy_position 281.062622 cc 2025-04-22T16:47:32.231Z,1745340452.231 [Reporter](INFO): platform_buoyancy_position 275.287720 cc 2025-04-22T16:47:32.503Z,1745340452.503 [Reporter](INFO): platform_buoyancy_position 270.452942 cc 2025-04-22T16:47:32.938Z,1745340452.938 [Reporter](INFO): platform_buoyancy_position 264.275146 cc 2025-04-22T16:47:33.319Z,1745340453.319 [Reporter](INFO): platform_buoyancy_position 259.708923 cc 2025-04-22T16:47:33.735Z,1745340453.735 [Reporter](INFO): platform_buoyancy_position 253.396805 cc 2025-04-22T16:47:34.112Z,1745340454.112 [Reporter](INFO): platform_buoyancy_position 249.099243 cc 2025-04-22T16:47:34.524Z,1745340454.524 [Reporter](INFO): platform_buoyancy_position 242.652832 cc 2025-04-22T16:47:34.994Z,1745340454.994 [Reporter](INFO): platform_buoyancy_position 237.952332 cc 2025-04-22T16:47:35.363Z,1745340455.363 [Reporter](INFO): platform_buoyancy_position 232.177429 cc 2025-04-22T16:47:35.760Z,1745340455.760 [Reporter](INFO): platform_buoyancy_position 227.476929 cc 2025-04-22T16:47:36.144Z,1745340456.144 [Reporter](INFO): platform_buoyancy_position 221.299133 cc 2025-04-22T16:47:36.683Z,1745340456.683 [Reporter](INFO): platform_buoyancy_position 216.732941 cc 2025-04-22T16:47:37.107Z,1745340457.107 [Reporter](INFO): platform_buoyancy_position 212.435333 cc 2025-04-22T16:47:37.387Z,1745340457.387 [Reporter](INFO): platform_buoyancy_position 206.123230 cc 2025-04-22T16:47:37.794Z,1745340457.794 [Reporter](INFO): platform_buoyancy_position 201.691315 cc 2025-04-22T16:47:38.295Z,1745340458.295 [Reporter](INFO): platform_buoyancy_position 195.379242 cc 2025-04-22T16:47:38.580Z,1745340458.580 [Reporter](INFO): platform_buoyancy_position 190.947327 cc 2025-04-22T16:47:38.992Z,1745340458.992 [Reporter](INFO): platform_buoyancy_position 184.635223 cc 2025-04-22T16:47:39.388Z,1745340459.388 [Reporter](INFO): platform_buoyancy_position 180.203339 cc 2025-04-22T16:47:39.788Z,1745340459.788 [Reporter](INFO): platform_buoyancy_position 173.891235 cc 2025-04-22T16:47:40.203Z,1745340460.203 [Reporter](INFO): platform_buoyancy_position 169.459320 cc 2025-04-22T16:47:40.580Z,1745340460.580 [Reporter](INFO): platform_buoyancy_position 163.012939 cc 2025-04-22T16:47:40.992Z,1745340460.992 [Reporter](INFO): platform_buoyancy_position 158.849640 cc 2025-04-22T16:47:41.405Z,1745340461.405 [Reporter](INFO): platform_buoyancy_position 152.403229 cc 2025-04-22T16:47:41.805Z,1745340461.805 [Reporter](INFO): platform_buoyancy_position 148.105621 cc 2025-04-22T16:47:42.221Z,1745340462.221 [Reporter](INFO): platform_buoyancy_position 143.539429 cc 2025-04-22T16:47:42.608Z,1745340462.608 [Reporter](INFO): platform_buoyancy_position 137.361633 cc 2025-04-22T16:47:43.021Z,1745340463.021 [Reporter](INFO): platform_buoyancy_position 132.929718 cc 2025-04-22T16:47:43.434Z,1745340463.434 [Reporter](INFO): platform_buoyancy_position 126.483337 cc 2025-04-22T16:47:43.816Z,1745340463.816 [Reporter](INFO): platform_buoyancy_position 122.185722 cc 2025-04-22T16:47:44.228Z,1745340464.228 [Reporter](INFO): platform_buoyancy_position 116.410828 cc 2025-04-22T16:47:44.651Z,1745340464.651 [Reporter](INFO): platform_buoyancy_position 111.576019 cc 2025-04-22T16:47:45.020Z,1745340465.020 [Reporter](INFO): platform_buoyancy_position 107.278412 cc 2025-04-22T16:47:45.445Z,1745340465.445 [Reporter](INFO): platform_buoyancy_position 100.832031 cc 2025-04-22T16:47:45.857Z,1745340465.857 [Reporter](INFO): platform_buoyancy_position 95.728607 cc 2025-04-22T16:47:46.369Z,1745340466.369 [Reporter](INFO): platform_buoyancy_position 89.953735 cc 2025-04-22T16:48:19.257Z,1745340499.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T16:48:21.265Z,1745340501.265 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239277 2025-04-22T16:48:54.422Z,1745340534.422 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T16:48:58.438Z,1745340538.438 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-22T16:48:58.709Z,1745340538.709 [Radio_Surface](INFO): Powering down 2025-04-22T16:52:21.251Z,1745340741.251 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:52:21.252Z,1745340741.252 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:52:21.252Z,1745340741.252 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:52:21.488Z,1745340741.488 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:52:21.685Z,1745340741.685 [Waterlinked](INFO): Powering down 2025-04-22T16:52:22.376Z,1745340742.376 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:52:22.376Z,1745340742.376 [Waterlinked] No Fault, FailCount= 1 2025-04-22T16:53:21.860Z,1745340801.860 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:53:21.860Z,1745340801.860 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:53:21.860Z,1745340801.860 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:53:22.006Z,1745340802.006 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:53:22.333Z,1745340802.333 [Waterlinked](INFO): Powering down 2025-04-22T16:53:23.005Z,1745340803.005 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:53:23.005Z,1745340803.005 [Waterlinked] No Fault, FailCount= 1 2025-04-22T16:54:10.534Z,1745340850.534 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:54:10.535Z,1745340850.535 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:54:10.535Z,1745340850.535 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:54:10.725Z,1745340850.725 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:54:10.985Z,1745340850.985 [Waterlinked](INFO): Powering down 2025-04-22T16:54:11.708Z,1745340851.708 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:54:11.708Z,1745340851.708 [Waterlinked] No Fault, FailCount= 1 2025-04-22T16:55:56.197Z,1745340956.197 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:55:56.197Z,1745340956.197 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:55:56.198Z,1745340956.198 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:55:56.553Z,1745340956.553 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:55:56.777Z,1745340956.777 [Waterlinked](INFO): Powering down 2025-04-22T16:55:57.466Z,1745340957.466 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:55:57.466Z,1745340957.466 [Waterlinked] No Fault, FailCount= 1 2025-04-22T16:56:35.868Z,1745340995.868 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:56:35.869Z,1745340995.869 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:56:35.869Z,1745340995.869 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:56:36.075Z,1745340996.075 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:56:36.325Z,1745340996.325 [Waterlinked](INFO): Powering down 2025-04-22T16:56:37.047Z,1745340997.047 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:56:37.047Z,1745340997.047 [Waterlinked] No Fault, FailCount= 1 2025-04-22T16:57:23.587Z,1745341043.587 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:57:23.588Z,1745341043.588 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:57:23.588Z,1745341043.588 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:57:23.755Z,1745341043.755 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:57:24.005Z,1745341044.005 [Waterlinked](INFO): Powering down 2025-04-22T16:57:24.734Z,1745341044.734 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:57:24.734Z,1745341044.734 [Waterlinked] No Fault, FailCount= 1 2025-04-22T16:58:13.295Z,1745341093.295 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T16:58:13.295Z,1745341093.295 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T16:58:13.295Z,1745341093.295 [Waterlinked](ERROR): Communications Fault 2025-04-22T16:58:13.513Z,1745341093.513 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T16:58:13.801Z,1745341093.801 [Waterlinked](INFO): Powering down 2025-04-22T16:58:14.451Z,1745341094.451 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T16:58:14.451Z,1745341094.451 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:00:00.036Z,1745341200.036 [CommandExec](IMPORTANT): got command gfisolate 2025-04-22T17:00:00.402Z,1745341200.402 [GFScanner](INFO): Ground fault isolation requested 2025-04-22T17:00:00.402Z,1745341200.402 [GFScanner](IMPORTANT): Starting ground fault isolation process 2025-04-22T17:00:00.403Z,1745341200.403 [GFScanner](IMPORTANT): No ground fault detected in recent scan results, will request new scan 2025-04-22T17:00:00.754Z,1745341200.754 [GFScanner](INFO): Requesting scan for ground fault isolation 2025-04-22T17:00:00.754Z,1745341200.754 [GFScanner](INFO): Beginning ground fault scan 2025-04-22T17:00:01.106Z,1745341201.106 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2025-04-22T17:00:01.111Z,1745341201.111 [CTD_Seabird](INFO): some bad data, not updating bins 2025-04-22T17:00:02.109Z,1745341202.109 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2025-04-22T17:00:02.112Z,1745341202.112 [CTD_Seabird](INFO): some bad data, not updating bins 2025-04-22T17:00:03.115Z,1745341203.115 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2025-04-22T17:00:03.118Z,1745341203.118 [CTD_Seabird](INFO): some bad data, not updating bins 2025-04-22T17:00:04.117Z,1745341204.117 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2025-04-22T17:00:04.120Z,1745341204.120 [CTD_Seabird](INFO): some bad data, not updating bins 2025-04-22T17:00:05.122Z,1745341205.122 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2025-04-22T17:00:05.125Z,1745341205.125 [CTD_Seabird](INFO): some bad data, not updating bins 2025-04-22T17:00:05.578Z,1745341205.578 [GFScanner](INFO): No ground fault detected mA: CHAN A0 (Batt): -0.006412 CHAN A1 (24V): -0.003896 CHAN A2 (12V): -0.005205 CHAN A3 (5V): -0.002875 CHAN B0 (3.3V): -0.000424 CHAN B1 (3.15aV): -0.001067 CHAN B2 (3.15bV): -0.001138 CHAN B3 (GND): -0.001218 OPEN: 0.006033 Full Scale: +/- 1 mA 2025-04-22T17:00:06.054Z,1745341206.054 [GFScanner](IMPORTANT): No ground fault detected in initial scan, completing isolation process 2025-04-22T17:00:06.386Z,1745341206.386 [GFScanner](IMPORTANT): Ground fault isolation process complete 2025-04-22T17:00:17.903Z,1745341217.903 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:00:17.903Z,1745341217.903 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:00:17.903Z,1745341217.903 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:00:18.050Z,1745341218.050 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:00:18.349Z,1745341218.349 [Waterlinked](INFO): Powering down 2025-04-22T17:00:19.062Z,1745341219.062 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:00:19.062Z,1745341219.062 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:01:43.718Z,1745341303.718 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:01:43.718Z,1745341303.718 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:01:43.718Z,1745341303.718 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:01:43.925Z,1745341303.925 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:01:44.149Z,1745341304.149 [Waterlinked](INFO): Powering down 2025-04-22T17:01:44.833Z,1745341304.833 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:01:44.833Z,1745341304.833 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:03:18.498Z,1745341398.498 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:03:18.498Z,1745341398.498 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:03:18.498Z,1745341398.498 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:03:18.738Z,1745341398.738 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:03:18.985Z,1745341398.985 [Waterlinked](INFO): Powering down 2025-04-22T17:03:19.691Z,1745341399.691 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:03:19.691Z,1745341399.691 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:04:16.871Z,1745341456.871 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:04:16.871Z,1745341456.871 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:04:16.871Z,1745341456.871 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:04:17.028Z,1745341457.028 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:04:17.357Z,1745341457.357 [Waterlinked](INFO): Powering down 2025-04-22T17:04:18.069Z,1745341458.069 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:04:18.069Z,1745341458.069 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:05:30.448Z,1745341530.448 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:05:30.449Z,1745341530.449 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:05:30.449Z,1745341530.449 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:05:30.638Z,1745341530.638 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:05:30.909Z,1745341530.909 [Waterlinked](INFO): Powering down 2025-04-22T17:05:31.625Z,1745341531.625 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:05:31.625Z,1745341531.625 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:07:40.203Z,1745341660.203 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:07:40.204Z,1745341660.204 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:07:40.204Z,1745341660.204 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:07:40.374Z,1745341660.374 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:07:40.661Z,1745341660.661 [Waterlinked](INFO): Powering down 2025-04-22T17:07:41.373Z,1745341661.373 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:07:41.373Z,1745341661.373 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:08:27.539Z,1745341707.539 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:08:27.540Z,1745341707.540 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:08:27.540Z,1745341707.540 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:08:27.697Z,1745341707.697 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:08:27.989Z,1745341707.989 [Waterlinked](INFO): Powering down 2025-04-22T17:08:28.694Z,1745341708.694 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:08:28.694Z,1745341708.694 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:09:35.995Z,1745341775.995 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:09:35.995Z,1745341775.995 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:09:35.996Z,1745341775.996 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:09:36.141Z,1745341776.141 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:09:36.413Z,1745341776.413 [Waterlinked](INFO): Powering down 2025-04-22T17:09:37.095Z,1745341777.095 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:09:37.095Z,1745341777.095 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:10:16.434Z,1745341816.434 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:10:16.434Z,1745341816.434 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:10:16.434Z,1745341816.434 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:10:16.596Z,1745341816.596 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:10:16.865Z,1745341816.865 [Waterlinked](INFO): Powering down 2025-04-22T17:10:17.556Z,1745341817.556 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:10:17.557Z,1745341817.557 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:11:12.641Z,1745341872.641 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:11:12.641Z,1745341872.641 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:11:12.642Z,1745341872.642 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:11:12.802Z,1745341872.802 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:11:13.105Z,1745341873.105 [Waterlinked](INFO): Powering down 2025-04-22T17:11:13.810Z,1745341873.810 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:11:13.810Z,1745341873.810 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:12:08.490Z,1745341928.490 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:12:08.490Z,1745341928.490 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:12:08.490Z,1745341928.490 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:12:08.651Z,1745341928.651 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:12:08.933Z,1745341928.933 [Waterlinked](INFO): Powering down 2025-04-22T17:12:09.666Z,1745341929.666 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:12:09.667Z,1745341929.667 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:13:24.541Z,1745342004.541 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:13:24.870Z,1745342004.870 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:13:31.452Z,1745342011.452 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:13:40.057Z,1745342020.057 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:13:46.855Z,1745342026.855 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:13:55.062Z,1745342035.062 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:13:55.062Z,1745342035.062 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:13:55.062Z,1745342035.062 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:13:55.232Z,1745342035.232 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:13:55.356Z,1745342035.356 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:13:55.497Z,1745342035.497 [Waterlinked](INFO): Powering down 2025-04-22T17:13:56.157Z,1745342036.157 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:13:56.157Z,1745342036.157 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:14:02.169Z,1745342042.169 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:05.024Z,1745342045.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-04-22T17:14:05.024Z,1745342045.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-22T17:14:05.042Z,1745342045.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-22T17:14:05.425Z,1745342045.425 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-22T17:14:05.426Z,1745342045.426 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-04-22T17:14:09.846Z,1745342049.846 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:17.926Z,1745342057.926 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:25.377Z,1745342065.377 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:33.146Z,1745342073.146 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:38.074Z,1745342078.074 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:14:38.075Z,1745342078.075 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:14:38.075Z,1745342078.075 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:14:38.228Z,1745342078.228 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:14:38.502Z,1745342078.502 [Waterlinked](INFO): Powering down 2025-04-22T17:14:39.214Z,1745342079.214 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:14:39.214Z,1745342079.214 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:14:41.188Z,1745342081.188 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:48.460Z,1745342088.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:14:56.139Z,1745342096.139 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:03.410Z,1745342103.410 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:11.276Z,1745342111.276 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:18.930Z,1745342118.930 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:26.210Z,1745342126.210 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:34.139Z,1745342134.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:41.431Z,1745342141.431 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-04-22T17:15:48.074Z,1745342148.074 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:15:48.074Z,1745342148.074 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:15:48.074Z,1745342148.074 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:15:48.262Z,1745342148.262 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:15:48.414Z,1745342148.414 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2025-04-22T17:15:48.420Z,1745342148.420 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T17:15:48.533Z,1745342148.533 [Waterlinked](INFO): Powering down 2025-04-22T17:15:49.150Z,1745342149.150 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:15:49.150Z,1745342149.150 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:16:34.048Z,1745342194.048 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:16:34.050Z,1745342194.050 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:16:34.050Z,1745342194.050 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:16:34.190Z,1745342194.190 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:16:34.537Z,1745342194.537 [Waterlinked](INFO): Powering down 2025-04-22T17:16:35.190Z,1745342195.190 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:16:35.190Z,1745342195.190 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:17:18.915Z,1745342238.915 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:17:18.916Z,1745342238.916 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:17:18.916Z,1745342238.916 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:17:19.047Z,1745342239.047 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:17:19.329Z,1745342239.329 [Waterlinked](INFO): Powering down 2025-04-22T17:17:20.045Z,1745342240.045 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:17:20.045Z,1745342240.045 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:18:41.126Z,1745342321.126 [Waterlinked](ERROR): No DVL communication! Re-initializing 2025-04-22T17:18:41.126Z,1745342321.126 [Waterlinked] Communications Fault, FailCount= 1 2025-04-22T17:18:41.126Z,1745342321.126 [Waterlinked](ERROR): Communications Fault 2025-04-22T17:18:41.419Z,1745342321.419 [CBIT](ERROR): Communications Fault in component: Waterlinked 2025-04-22T17:18:41.585Z,1745342321.585 [Waterlinked](INFO): Powering down 2025-04-22T17:18:42.285Z,1745342322.285 [CBIT](INFO): Clearing failed state for component Waterlinked 2025-04-22T17:18:42.285Z,1745342322.285 [Waterlinked] No Fault, FailCount= 1 2025-04-22T17:20:01.377Z,1745342401.377 [Radio_Surface](INFO): Powering up 2025-04-22T17:20:07.205Z,1745342407.205 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-22T17:20:37.350Z,1745342437.350 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T17:21:12.493Z,1745342472.493 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T17:21:47.638Z,1745342507.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T17:22:22.783Z,1745342542.783 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T17:22:27.884Z,1745342547.884 [sink:RunSink](INFO): Timed out from 2025-04-22T16:46:27.4Z 2025-04-22T17:22:27.884Z,1745342547.884 [sink:RunSink] Stopped 2025-04-22T17:22:27.884Z,1745342547.884 [sink:RunSink](DEBUG): Aggregate::uninitialize sink:RunSink 2025-04-22T17:22:27.884Z,1745342547.884 [sink:RunSink:BallastAndTrim] Stopped 2025-04-22T17:22:27.884Z,1745342547.884 [sink:RunSink:BallastAndTrim](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim 2025-04-22T17:22:27.884Z,1745342547.884 [sink:RunSink:BallastAndTrim:A] Stopped 2025-04-22T17:22:27.885Z,1745342547.885 [sink:RunSink:BallastAndTrim:B] Stopped 2025-04-22T17:22:27.885Z,1745342547.885 [sink:RunSink:BallastAndTrim:C] Stopped 2025-04-22T17:22:27.885Z,1745342547.885 [sink:RunSink:BallastAndTrim:D] Stopped 2025-04-22T17:22:27.885Z,1745342547.885 [sink:RunSink:BallastAndTrim:E] Stopped 2025-04-22T17:22:27.885Z,1745342547.885 [sink:RunSink:BallastAndTrim:GoToTargetDepth] Stopped 2025-04-22T17:22:27.885Z,1745342547.885 [sink:RunSink:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:BallastAndTrim:GoToTargetDepth:A] Stopped 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize. 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:JustSink] Stopped 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:JustSink](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:JustSink:C] Stopped 2025-04-22T17:22:27.886Z,1745342547.886 [sink:RunSink:JustSink:C](DEBUG): Aggregate::uninitialize sink:RunSink:JustSink:C 2025-04-22T17:22:27.887Z,1745342547.887 [sink:FloatUp] Running Loop=1 2025-04-22T17:22:27.887Z,1745342547.887 [sink:FloatUp](DEBUG): Aggregate::initialize sink:FloatUp 2025-04-22T17:22:27.887Z,1745342547.887 [sink:FloatUp:A.Buoyancy] Running Loop=1 2025-04-22T17:22:27.887Z,1745342547.887 [sink:FloatUp:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-22T17:22:27.887Z,1745342547.887 [sink:FloatUp:B.Wait] Running Loop=1 2025-04-22T17:22:27.887Z,1745342547.887 [sink:FloatUp:B.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:22:28.242Z,1745342548.242 [sink:FloatUp] Stopped 2025-04-22T17:22:28.242Z,1745342548.242 [sink:FloatUp](DEBUG): Aggregate::uninitialize sink:FloatUp 2025-04-22T17:22:28.242Z,1745342548.242 [sink:FloatUp:A.Buoyancy] Stopped 2025-04-22T17:22:28.242Z,1745342548.242 [sink:FloatUp:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-22T17:22:28.242Z,1745342548.242 [sink:FloatUp:B.Wait] Stopped 2025-04-22T17:22:28.242Z,1745342548.242 [sink:FloatUp:B.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:22:28.242Z,1745342548.242 [sink:EndingMission] Running Loop=1 2025-04-22T17:22:28.242Z,1745342548.242 [sink:EndingMission](DEBUG): Aggregate::initialize sink:EndingMission 2025-04-22T17:22:28.662Z,1745342548.662 [sink:NeedComms] Running Loop=1 2025-04-22T17:22:28.663Z,1745342548.663 [sink:NeedComms](DEBUG): Aggregate::initialize sink:NeedComms 2025-04-22T17:22:28.663Z,1745342548.663 [sink:NeedComms:B.GoToSurface] Running Loop=1 2025-04-22T17:22:28.663Z,1745342548.663 [sink:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-22T17:22:28.663Z,1745342548.663 [sink:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-22T17:22:28.663Z,1745342548.663 [sink:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-22T17:22:28.664Z,1745342548.664 [sink:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-22T17:22:28.664Z,1745342548.664 [sink:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-22T17:22:28.665Z,1745342548.665 [sink:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-22T17:22:28.665Z,1745342548.665 [sink:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-22T17:22:28.665Z,1745342548.665 [sink:NeedComms:A] Running Loop=1 2025-04-22T17:22:28.667Z,1745342548.667 [sink:NeedComms:A](INFO): last time_fix was: 1745340386.000000 second since 1970/01/01T00:00:00Z 2025-04-22T17:22:28.667Z,1745342548.667 [sink:NeedComms:A] Stopped 2025-04-22T17:22:29.059Z,1745342549.059 [sink:NeedComms:C] Running Loop=1 2025-04-22T17:22:29.732Z,1745342549.732 [Reporter](INFO): platform_buoyancy_position 81.492828 cc 2025-04-22T17:22:30.074Z,1745342550.074 [Reporter](INFO): platform_buoyancy_position 83.238708 cc 2025-04-22T17:22:30.330Z,1745342550.330 [Reporter](INFO): platform_buoyancy_position 83.507324 cc 2025-04-22T17:22:30.747Z,1745342550.747 [Reporter](INFO): platform_buoyancy_position 85.656128 cc 2025-04-22T17:22:30.981Z,1745342550.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172230.00,A,3648.16692,N,12147.28432,W,0.058,0.00,220425,,,A*7A 2025-04-22T17:22:30.983Z,1745342550.983 [NAL9602](INFO): GPS fix at 20250422T172230: (36.802782, -121.788072) 2025-04-22T17:22:31.058Z,1745342551.058 [sink:NeedComms:C] Stopped 2025-04-22T17:22:31.059Z,1745342551.059 [sink:NeedComms:D] Running Loop=1 2025-04-22T17:22:31.297Z,1745342551.297 [Reporter](INFO): platform_buoyancy_position 90.490936 cc 2025-04-22T17:22:31.615Z,1745342551.615 [Reporter](INFO): platform_buoyancy_position 94.519928 cc 2025-04-22T17:22:32.102Z,1745342552.102 [Reporter](INFO): platform_buoyancy_position 102.846527 cc 2025-04-22T17:22:32.583Z,1745342552.583 [Reporter](INFO): platform_buoyancy_position 109.292908 cc 2025-04-22T17:22:32.976Z,1745342552.976 [Reporter](INFO): platform_buoyancy_position 113.590515 cc 2025-04-22T17:22:33.367Z,1745342553.367 [Reporter](INFO): platform_buoyancy_position 118.156738 cc 2025-04-22T17:22:33.763Z,1745342553.763 [Reporter](INFO): platform_buoyancy_position 124.334534 cc 2025-04-22T17:22:34.190Z,1745342554.190 [Reporter](INFO): platform_buoyancy_position 128.766418 cc 2025-04-22T17:22:34.589Z,1745342554.589 [Reporter](INFO): platform_buoyancy_position 135.078522 cc 2025-04-22T17:22:35.141Z,1745342555.141 [Reporter](INFO): platform_buoyancy_position 141.793518 cc 2025-04-22T17:22:35.343Z,1745342555.343 [Reporter](INFO): platform_buoyancy_position 145.822540 cc 2025-04-22T17:22:35.739Z,1745342555.739 [Reporter](INFO): platform_buoyancy_position 150.254425 cc 2025-04-22T17:22:36.152Z,1745342556.152 [Reporter](INFO): platform_buoyancy_position 154.686340 cc 2025-04-22T17:22:36.536Z,1745342556.536 [Reporter](INFO): platform_buoyancy_position 160.864136 cc 2025-04-22T17:22:36.975Z,1745342556.975 [Reporter](INFO): platform_buoyancy_position 167.310516 cc 2025-04-22T17:22:37.423Z,1745342557.423 [Reporter](INFO): platform_buoyancy_position 171.876740 cc 2025-04-22T17:22:37.724Z,1745342557.724 [Reporter](INFO): platform_buoyancy_position 176.040039 cc 2025-04-22T17:22:38.249Z,1745342558.249 [Reporter](INFO): platform_buoyancy_position 182.352142 cc 2025-04-22T17:22:38.629Z,1745342558.629 [Reporter](INFO): platform_buoyancy_position 188.798523 cc 2025-04-22T17:22:39.064Z,1745342559.064 [Reporter](INFO): platform_buoyancy_position 193.364716 cc 2025-04-22T17:22:39.436Z,1745342559.436 [Reporter](INFO): platform_buoyancy_position 198.199524 cc 2025-04-22T17:22:40.016Z,1745342560.016 [Reporter](INFO): platform_buoyancy_position 204.914520 cc 2025-04-22T17:22:40.229Z,1745342560.229 [Reporter](INFO): platform_buoyancy_position 208.943542 cc 2025-04-22T17:22:40.578Z,1745342560.578 [Reporter](INFO): platform_buoyancy_position 214.718445 cc 2025-04-22T17:22:41.020Z,1745342561.020 [Reporter](INFO): platform_buoyancy_position 219.284637 cc 2025-04-22T17:22:41.347Z,1745342561.347 [Reporter](INFO): platform_buoyancy_position 224.253723 cc 2025-04-22T17:22:41.727Z,1745342561.727 [Reporter](INFO): platform_buoyancy_position 229.625732 cc 2025-04-22T17:22:42.216Z,1745342562.216 [Reporter](INFO): platform_buoyancy_position 236.072144 cc 2025-04-22T17:22:42.644Z,1745342562.644 [Reporter](INFO): platform_buoyancy_position 241.847046 cc 2025-04-22T17:22:43.064Z,1745342563.064 [Reporter](INFO): platform_buoyancy_position 246.816147 cc 2025-04-22T17:22:43.431Z,1745342563.431 [Reporter](INFO): platform_buoyancy_position 251.248047 cc 2025-04-22T17:22:43.887Z,1745342563.887 [Reporter](INFO): platform_buoyancy_position 257.560120 cc 2025-04-22T17:22:44.230Z,1745342564.230 [Reporter](INFO): platform_buoyancy_position 262.126343 cc 2025-04-22T17:22:44.628Z,1745342564.628 [Reporter](INFO): platform_buoyancy_position 268.304138 cc 2025-04-22T17:22:45.032Z,1745342565.032 [Reporter](INFO): platform_buoyancy_position 272.870331 cc 2025-04-22T17:22:45.424Z,1745342565.424 [Reporter](INFO): platform_buoyancy_position 277.033630 cc 2025-04-22T17:22:45.855Z,1745342565.855 [Reporter](INFO): platform_buoyancy_position 283.614349 cc 2025-04-22T17:22:46.291Z,1745342566.291 [Reporter](INFO): platform_buoyancy_position 289.926422 cc 2025-04-22T17:22:46.591Z,1745342566.591 [Reporter](INFO): platform_buoyancy_position 292.343842 cc 2025-04-22T17:22:47.094Z,1745342567.094 [Reporter](INFO): platform_buoyancy_position 299.596039 cc 2025-04-22T17:22:47.487Z,1745342567.487 [Reporter](INFO): platform_buoyancy_position 305.102325 cc 2025-04-22T17:22:47.876Z,1745342567.876 [Reporter](INFO): platform_buoyancy_position 309.399933 cc 2025-04-22T17:22:48.252Z,1745342568.252 [Reporter](INFO): platform_buoyancy_position 315.577759 cc 2025-04-22T17:22:48.676Z,1745342568.676 [Reporter](INFO): platform_buoyancy_position 320.009644 cc 2025-04-22T17:22:49.111Z,1745342569.111 [Reporter](INFO): platform_buoyancy_position 326.321716 cc 2025-04-22T17:22:49.495Z,1745342569.495 [Reporter](INFO): platform_buoyancy_position 330.753662 cc 2025-04-22T17:22:49.887Z,1745342569.887 [Reporter](INFO): platform_buoyancy_position 335.722717 cc 2025-04-22T17:22:50.284Z,1745342570.284 [Reporter](INFO): platform_buoyancy_position 341.497620 cc 2025-04-22T17:22:50.688Z,1745342570.688 [Reporter](INFO): platform_buoyancy_position 345.929565 cc 2025-04-22T17:22:51.094Z,1745342571.094 [Reporter](INFO): platform_buoyancy_position 352.107361 cc 2025-04-22T17:22:51.487Z,1745342571.487 [Reporter](INFO): platform_buoyancy_position 356.404968 cc 2025-04-22T17:22:52.117Z,1745342572.117 [Reporter](INFO): platform_buoyancy_position 365.134460 cc 2025-04-22T17:22:52.384Z,1745342572.384 [Reporter](INFO): platform_buoyancy_position 369.297729 cc 2025-04-22T17:22:53.075Z,1745342573.075 [Reporter](INFO): platform_buoyancy_position 378.027222 cc 2025-04-22T17:22:53.196Z,1745342573.196 [Reporter](INFO): platform_buoyancy_position 379.907471 cc 2025-04-22T17:22:53.681Z,1745342573.681 [Reporter](INFO): platform_buoyancy_position 386.488159 cc 2025-04-22T17:22:54.062Z,1745342574.062 [Reporter](INFO): platform_buoyancy_position 390.920044 cc 2025-04-22T17:22:54.436Z,1745342574.436 [Reporter](INFO): platform_buoyancy_position 397.232117 cc 2025-04-22T17:22:54.975Z,1745342574.975 [Reporter](INFO): platform_buoyancy_position 401.664062 cc 2025-04-22T17:22:55.420Z,1745342575.420 [Reporter](INFO): platform_buoyancy_position 408.110474 cc 2025-04-22T17:22:55.646Z,1745342575.646 [Reporter](INFO): platform_buoyancy_position 412.408020 cc 2025-04-22T17:22:56.112Z,1745342576.112 [Reporter](INFO): platform_buoyancy_position 416.705627 cc 2025-04-22T17:22:56.466Z,1745342576.466 [Reporter](INFO): platform_buoyancy_position 423.017761 cc 2025-04-22T17:22:56.869Z,1745342576.869 [Reporter](INFO): platform_buoyancy_position 427.449646 cc 2025-04-22T17:22:57.260Z,1745342577.260 [Reporter](INFO): platform_buoyancy_position 432.015869 cc 2025-04-22T17:22:57.693Z,1745342577.693 [Reporter](INFO): platform_buoyancy_position 438.193665 cc 2025-04-22T17:22:57.921Z,1745342577.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-22T17:22:58.124Z,1745342578.124 [Reporter](INFO): platform_buoyancy_position 444.505737 cc 2025-04-22T17:22:58.531Z,1745342578.531 [Reporter](INFO): platform_buoyancy_position 448.937622 cc 2025-04-22T17:22:58.936Z,1745342578.936 [Reporter](INFO): platform_buoyancy_position 455.249756 cc 2025-04-22T17:22:59.316Z,1745342579.316 [Reporter](INFO): platform_buoyancy_position 459.547363 cc 2025-04-22T17:22:59.766Z,1745342579.766 [Reporter](INFO): platform_buoyancy_position 465.053650 cc 2025-04-22T17:23:00.122Z,1745342580.122 [Reporter](INFO): platform_buoyancy_position 470.425659 cc 2025-04-22T17:23:00.534Z,1745342580.534 [Reporter](INFO): platform_buoyancy_position 474.857544 cc 2025-04-22T17:23:00.943Z,1745342580.943 [Reporter](INFO): platform_buoyancy_position 481.035339 cc 2025-04-22T17:23:01.287Z,1745342581.287 [Reporter](INFO): platform_buoyancy_position 485.467224 cc 2025-04-22T17:23:01.582Z,1745342581.582 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2025-04-22T17:23:01.665Z,1745342581.665 [NAL9602](ERROR): received: +CSQ:0 OK74, 2, 0, 0, 0 OK 2025-04-22T17:23:01.987Z,1745342581.987 [Reporter](INFO): platform_buoyancy_position 494.062439 cc 2025-04-22T17:23:02.259Z,1745342582.259 [Reporter](INFO): platform_buoyancy_position 498.225800 cc 2025-04-22T17:23:02.602Z,1745342582.602 [Reporter](INFO): platform_buoyancy_position 502.791962 cc 2025-04-22T17:23:03.235Z,1745342583.235 [Reporter](INFO): platform_buoyancy_position 511.252838 cc 2025-04-22T17:23:03.451Z,1745342583.451 [Reporter](INFO): platform_buoyancy_position 513.401672 cc 2025-04-22T17:23:04.116Z,1745342584.116 [Reporter](INFO): platform_buoyancy_position 524.011353 cc 2025-04-22T17:23:04.251Z,1745342584.251 [Reporter](INFO): platform_buoyancy_position 524.279968 cc 2025-04-22T17:23:04.702Z,1745342584.702 [Reporter](INFO): platform_buoyancy_position 530.592041 cc 2025-04-22T17:23:05.103Z,1745342585.103 [Reporter](INFO): platform_buoyancy_position 536.904175 cc 2025-04-22T17:23:05.472Z,1745342585.472 [Reporter](INFO): platform_buoyancy_position 541.201782 cc 2025-04-22T17:23:05.973Z,1745342585.973 [Reporter](INFO): platform_buoyancy_position 546.976685 cc 2025-04-22T17:23:06.332Z,1745342586.332 [Reporter](INFO): platform_buoyancy_position 551.945740 cc 2025-04-22T17:23:06.719Z,1745342586.719 [Reporter](INFO): platform_buoyancy_position 556.377686 cc 2025-04-22T17:23:07.114Z,1745342587.114 [Reporter](INFO): platform_buoyancy_position 562.689758 cc 2025-04-22T17:23:07.583Z,1745342587.583 [Reporter](INFO): platform_buoyancy_position 569.270447 cc 2025-04-22T17:23:07.996Z,1745342587.996 [Reporter](INFO): platform_buoyancy_position 573.568054 cc 2025-04-22T17:23:08.348Z,1745342588.348 [Reporter](INFO): platform_buoyancy_position 577.865662 cc 2025-04-22T17:23:08.763Z,1745342588.763 [Reporter](INFO): platform_buoyancy_position 584.177734 cc 2025-04-22T17:23:09.191Z,1745342589.191 [Reporter](INFO): platform_buoyancy_position 588.609680 cc 2025-04-22T17:23:09.570Z,1745342589.570 [Reporter](INFO): platform_buoyancy_position 594.921753 cc 2025-04-22T17:23:09.988Z,1745342589.988 [Reporter](INFO): platform_buoyancy_position 599.353638 cc 2025-04-22T17:23:10.359Z,1745342590.359 [Reporter](INFO): platform_buoyancy_position 605.665771 cc 2025-04-22T17:23:10.768Z,1745342590.768 [Reporter](INFO): platform_buoyancy_position 610.097656 cc 2025-04-22T17:23:11.172Z,1745342591.172 [Reporter](INFO): platform_buoyancy_position 616.409790 cc 2025-04-22T17:23:11.556Z,1745342591.556 [Reporter](INFO): platform_buoyancy_position 620.707336 cc 2025-04-22T17:23:11.909Z,1745342591.909 [Reporter](INFO): platform_buoyancy_position 625.004944 cc 2025-04-22T17:23:12.412Z,1745342592.412 [Reporter](INFO): platform_buoyancy_position 631.451355 cc 2025-04-22T17:23:12.780Z,1745342592.780 [Reporter](INFO): platform_buoyancy_position 635.883240 cc 2025-04-22T17:23:13.132Z,1745342593.132 [Reporter](INFO): platform_buoyancy_position 640.180847 cc 2025-04-22T17:23:13.566Z,1745342593.566 [Reporter](INFO): platform_buoyancy_position 646.627258 cc 2025-04-22T17:23:14.039Z,1745342594.039 [Reporter](INFO): platform_buoyancy_position 653.073669 cc 2025-04-22T17:23:14.431Z,1745342594.431 [Reporter](INFO): platform_buoyancy_position 657.774170 cc 2025-04-22T17:23:14.785Z,1745342594.785 [Reporter](INFO): platform_buoyancy_position 661.937439 cc 2025-04-22T17:23:15.223Z,1745342595.223 [Reporter](INFO): platform_buoyancy_position 668.115234 cc 2025-04-22T17:23:15.627Z,1745342595.627 [Reporter](INFO): platform_buoyancy_position 674.427368 cc 2025-04-22T17:23:16.006Z,1745342596.006 [Reporter](INFO): platform_buoyancy_position 678.724976 cc 2025-04-22T17:23:16.424Z,1745342596.424 [Reporter](INFO): platform_buoyancy_position 685.305664 cc 2025-04-22T17:23:16.796Z,1745342596.796 [Reporter](INFO): platform_buoyancy_position 689.468994 cc 2025-04-22T17:23:17.206Z,1745342597.206 [Reporter](INFO): platform_buoyancy_position 694.438049 cc 2025-04-22T17:23:17.611Z,1745342597.611 [Reporter](INFO): platform_buoyancy_position 700.212952 cc 2025-04-22T17:23:18.035Z,1745342598.035 [Reporter](INFO): platform_buoyancy_position 704.779175 cc 2025-04-22T17:23:18.492Z,1745342598.492 [Reporter](INFO): platform_buoyancy_position 711.359863 cc 2025-04-22T17:23:18.804Z,1745342598.804 [Reporter](INFO): platform_buoyancy_position 715.388855 cc 2025-04-22T17:23:19.240Z,1745342599.240 [Reporter](INFO): platform_buoyancy_position 721.835266 cc 2025-04-22T17:23:19.721Z,1745342599.721 [Reporter](INFO): platform_buoyancy_position 728.147339 cc 2025-04-22T17:23:20.065Z,1745342600.065 [Reporter](INFO): platform_buoyancy_position 732.579285 cc 2025-04-22T17:23:20.475Z,1745342600.475 [Reporter](INFO): platform_buoyancy_position 737.279785 cc 2025-04-22T17:23:20.878Z,1745342600.878 [Reporter](INFO): platform_buoyancy_position 743.323242 cc 2025-04-22T17:23:21.248Z,1745342601.248 [Reporter](INFO): platform_buoyancy_position 747.620850 cc 2025-04-22T17:23:21.672Z,1745342601.672 [Reporter](INFO): platform_buoyancy_position 753.932983 cc 2025-04-22T17:23:21.972Z,1745342601.972 [Reporter](INFO): platform_buoyancy_position 757.961975 cc 2025-04-22T17:23:22.435Z,1745342602.435 [Reporter](INFO): platform_buoyancy_position 762.796753 cc 2025-04-22T17:23:22.823Z,1745342602.823 [Reporter](INFO): platform_buoyancy_position 768.974548 cc 2025-04-22T17:23:23.256Z,1745342603.256 [Reporter](INFO): platform_buoyancy_position 773.272156 cc 2025-04-22T17:23:23.665Z,1745342603.665 [Reporter](INFO): platform_buoyancy_position 779.449951 cc 2025-04-22T17:23:24.143Z,1745342604.143 [Reporter](INFO): platform_buoyancy_position 786.164978 cc 2025-04-22T17:23:24.584Z,1745342604.584 [Reporter](INFO): platform_buoyancy_position 790.731201 cc 2025-04-22T17:23:24.839Z,1745342604.839 [Reporter](INFO): platform_buoyancy_position 794.760193 cc 2025-04-22T17:23:25.439Z,1745342605.439 [Reporter](INFO): platform_buoyancy_position 801.340881 cc 2025-04-22T17:23:25.767Z,1745342605.767 [Reporter](INFO): platform_buoyancy_position 807.652954 cc 2025-04-22T17:23:26.247Z,1745342606.247 [Reporter](INFO): platform_buoyancy_position 814.099365 cc 2025-04-22T17:23:26.763Z,1745342606.763 [Reporter](INFO): platform_buoyancy_position 820.545776 cc 2025-04-22T17:23:27.056Z,1745342607.056 [Reporter](INFO): platform_buoyancy_position 824.977661 cc 2025-04-22T17:23:27.472Z,1745342607.472 [Reporter](INFO): platform_buoyancy_position 829.275269 cc 2025-04-22T17:23:27.789Z,1745342607.789 [Reporter](INFO): platform_buoyancy_position 833.572876 cc 2025-04-22T17:23:28.232Z,1745342608.232 [Reporter](INFO): platform_buoyancy_position 839.885010 cc 2025-04-22T17:23:28.633Z,1745342608.633 [Reporter](INFO): platform_buoyancy_position 844.316895 cc 2025-04-22T17:23:28.000Z,1745342609.000 [Reporter](INFO): platform_buoyancy_position 848.883057 cc 2025-04-22T17:23:29.436Z,1745342609.436 [Reporter](INFO): platform_buoyancy_position 855.060913 cc 2025-04-22T17:23:29.860Z,1745342609.860 [Reporter](INFO): platform_buoyancy_position 861.372925 cc 2025-04-22T17:23:30.288Z,1745342610.288 [Reporter](INFO): platform_buoyancy_position 865.670532 cc 2025-04-22T17:23:30.804Z,1745342610.804 [Reporter](INFO): platform_buoyancy_position 872.385620 cc 2025-04-22T17:23:31.015Z,1745342611.015 [Reporter](INFO): platform_buoyancy_position 876.548828 cc 2025-04-22T17:23:31.392Z,1745342611.392 [Reporter](INFO): platform_buoyancy_position 880.846436 cc 2025-04-22T17:23:31.916Z,1745342611.916 [Reporter](INFO): platform_buoyancy_position 887.158569 cc 2025-04-22T17:23:32.166Z,1745342612.166 [Reporter](INFO): platform_buoyancy_position 891.590454 cc 2025-04-22T17:23:32.748Z,1745342612.748 [Reporter](INFO): platform_buoyancy_position 898.036865 cc 2025-04-22T17:23:33.061Z,1745342613.061 [Reporter](INFO): platform_buoyancy_position 902.200195 cc 2025-04-22T17:23:33.406Z,1745342613.406 [Reporter](INFO): platform_buoyancy_position 906.766357 cc 2025-04-22T17:23:33.903Z,1745342613.903 [Reporter](INFO): platform_buoyancy_position 913.078491 cc 2025-04-22T17:23:34.348Z,1745342614.348 [Reporter](INFO): platform_buoyancy_position 919.524902 cc 2025-04-22T17:23:34.627Z,1745342614.627 [Reporter](INFO): platform_buoyancy_position 923.688232 cc 2025-04-22T17:23:35.239Z,1745342615.239 [Reporter](INFO): platform_buoyancy_position 932.283325 cc 2025-04-22T17:23:35.495Z,1745342615.495 [Reporter](INFO): platform_buoyancy_position 934.566528 cc 2025-04-22T17:23:35.923Z,1745342615.923 [Reporter](INFO): platform_buoyancy_position 938.998413 cc 2025-04-22T17:23:36.376Z,1745342616.376 [Reporter](INFO): platform_buoyancy_position 946.519165 cc 2025-04-22T17:23:36.819Z,1745342616.819 [Reporter](INFO): platform_buoyancy_position 951.622559 cc 2025-04-22T17:23:37.133Z,1745342617.133 [Reporter](INFO): platform_buoyancy_position 955.920166 cc 2025-04-22T17:23:37.568Z,1745342617.568 [Reporter](INFO): platform_buoyancy_position 962.500854 cc 2025-04-22T17:23:37.988Z,1745342617.988 [Reporter](INFO): platform_buoyancy_position 966.798462 cc 2025-04-22T17:23:38.351Z,1745342618.351 [Reporter](INFO): platform_buoyancy_position 971.096069 cc 2025-04-22T17:23:38.748Z,1745342618.748 [Reporter](INFO): platform_buoyancy_position 977.408264 cc 2025-04-22T17:23:39.143Z,1745342619.143 [Reporter](INFO): platform_buoyancy_position 981.840088 cc 2025-04-22T17:23:39.653Z,1745342619.653 [Reporter](INFO): platform_buoyancy_position 986.137634 cc 2025-04-22T17:23:48.685Z,1745342628.685 [DataOverHttps](IMPORTANT): SBD MTMSN=20250422T172348 2025-04-22T17:23:48.710Z,1745342628.710 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.025817 2025-04-22T17:23:58.813Z,1745342638.813 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250422T163301/Courier0012.lzma 2025-04-22T17:23:58.816Z,1745342638.816 [DataOverHttps](INFO): Received command: stop 2025-04-22T17:23:58.822Z,1745342638.822 [CommandExec](IMPORTANT): got command stop 2025-04-22T17:23:58.822Z,1745342638.822 [CommandExec](IMPORTANT): Scheduling is paused 2025-04-22T17:23:58.822Z,1745342638.822 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-04-22T17:23:59.197Z,1745342639.197 [MissionManager](INFO): MissionManager is completed. 2025-04-22T17:23:59.198Z,1745342639.198 [MissionManager](INFO): Uninitializing Mission sink 2025-04-22T17:23:59.198Z,1745342639.198 [sink] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink](DEBUG): Aggregate::uninitialize sink 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science](DEBUG): Aggregate::uninitialize sink:Science 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science:B] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science:C] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science:E] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science:OceanCurrent] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sink:Science:OceanCurrent 2025-04-22T17:23:59.198Z,1745342639.198 [sink:Science:OceanCurrent:A.] Stopped 2025-04-22T17:23:59.198Z,1745342639.198 [sink:NeedComms] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:NeedComms](DEBUG): Aggregate::uninitialize sink:NeedComms 2025-04-22T17:23:59.199Z,1745342639.199 [sink:NeedComms:B.GoToSurface] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-22T17:23:59.199Z,1745342639.199 [sink:NeedComms:D] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes](DEBUG): Aggregate::uninitialize sink:StandardEnvelopes 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-04-22T17:23:59.199Z,1745342639.199 [sink:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-04-22T17:23:59.199Z,1745342639.199 [sink:BackseatDriver] Stopped 2025-04-22T17:23:59.200Z,1745342639.200 [sink:BackseatDriver](DEBUG): Aggregate::uninitialize sink:BackseatDriver 2025-04-22T17:23:59.200Z,1745342639.200 [sink:BackseatDriver:A.BackseatDriver] Stopped 2025-04-22T17:23:59.200Z,1745342639.200 [sink:PowerOnly] Stopped 2025-04-22T17:23:59.200Z,1745342639.200 [sink:PowerOnly](DEBUG): Aggregate::uninitialize sink:PowerOnly 2025-04-22T17:23:59.200Z,1745342639.200 [sink:PowerOnly:E.Wait] Stopped 2025-04-22T17:23:59.200Z,1745342639.200 [sink:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:23:59.200Z,1745342639.200 [sink:EndingMission] Stopped 2025-04-22T17:23:59.200Z,1745342639.200 [sink:EndingMission](DEBUG): Aggregate::uninitialize sink:EndingMission 2025-04-22T17:23:59.622Z,1745342639.622 [MissionManager](IMPORTANT): Started mission Default 2025-04-22T17:23:59.622Z,1745342639.622 [Default] Running Loop=1 2025-04-22T17:23:59.622Z,1745342639.622 [Default](DEBUG): Aggregate::initialize Default 2025-04-22T17:23:59.623Z,1745342639.623 [Default:B.GoToSurface] Running Loop=1 2025-04-22T17:23:59.623Z,1745342639.623 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-22T17:23:59.623Z,1745342639.623 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-22T17:23:59.623Z,1745342639.623 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-22T17:23:59.623Z,1745342639.623 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-22T17:23:59.624Z,1745342639.624 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-22T17:23:59.624Z,1745342639.624 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-22T17:23:59.629Z,1745342639.629 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-22T17:23:59.629Z,1745342639.629 [Default:A.Wait] Running Loop=1 2025-04-22T17:23:59.629Z,1745342639.629 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:23:59.785Z,1745342639.785 [WetLabsBB2FL](INFO): Powering down 2025-04-22T17:23:59.815Z,1745342639.815 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Courier0012.lzma.bak 2025-04-22T17:23:59.815Z,1745342639.815 [DataOverHttps](INFO): SBD MOMSN=24710598 2025-04-22T17:24:00.193Z,1745342640.193 [WetLabsBB2FL](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-04-22T17:24:00.193Z,1745342640.193 [WetLabsBB2FL] Hardware Fault, FailCount= 1 2025-04-22T17:24:00.193Z,1745342640.193 [WetLabsBB2FL](ERROR): Hardware Fault 2025-04-22T17:24:00.398Z,1745342640.398 [CBIT](ERROR): Hardware Fault in component: WetLabsBB2FL 2025-04-22T17:24:00.789Z,1745342640.789 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2025-04-22T17:24:00.789Z,1745342640.789 [WetLabsBB2FL] No Fault, FailCount= 1 2025-04-22T17:24:01.002Z,1745342641.002 [WetLabsBB2FL](INFO): Powering up 2025-04-22T17:24:03.618Z,1745342643.618 [WetLabsBB2FL](INFO): Powering down 2025-04-22T17:24:12.931Z,1745342652.931 [Default:A.Wait](INFO): Done Waiting. 2025-04-22T17:24:12.932Z,1745342652.932 [Default:A.Wait] Stopped 2025-04-22T17:24:12.932Z,1745342652.932 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:24:13.334Z,1745342653.334 [Default:CheckIn] Running Loop=1 2025-04-22T17:24:13.334Z,1745342653.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:24:13.335Z,1745342653.335 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:24:15.297Z,1745342655.297 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172414.00,A,3648.16762,N,12147.28311,W,0.078,0.00,220425,,,A*70 2025-04-22T17:24:15.299Z,1745342655.299 [NAL9602](INFO): GPS fix at 20250422T172414: (36.802794, -121.788052) 2025-04-22T17:24:15.352Z,1745342655.352 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:24:15.352Z,1745342655.352 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:24:23.956Z,1745342663.956 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250422T163301/Courier0015.lzma 2025-04-22T17:24:24.959Z,1745342664.959 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Courier0015.lzma.bak 2025-04-22T17:24:24.959Z,1745342664.959 [DataOverHttps](INFO): SBD MOMSN=24710602 2025-04-22T17:24:45.243Z,1745342685.243 [DataOverHttps](INFO): Sending 1428 bytes from file Logs/20250422T163301/Express0013.lzma 2025-04-22T17:24:46.235Z,1745342686.235 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0013.lzma.bak 2025-04-22T17:24:46.235Z,1745342686.235 [DataOverHttps](INFO): SBD MOMSN=24710605 2025-04-22T17:25:08.181Z,1745342708.181 [DataOverHttps](INFO): Sending 335 bytes from file Logs/20250422T163301/Express0016.lzma 2025-04-22T17:25:09.184Z,1745342709.184 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0016.lzma.bak 2025-04-22T17:25:09.184Z,1745342709.184 [DataOverHttps](INFO): SBD MOMSN=24710652 2025-04-22T17:25:28.220Z,1745342728.220 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20250422T163301/Express0019.lzma 2025-04-22T17:25:29.219Z,1745342729.219 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0019.lzma.bak 2025-04-22T17:25:29.219Z,1745342729.219 [DataOverHttps](INFO): SBD MOMSN=24710659 2025-04-22T17:25:30.468Z,1745342730.468 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:25:30.469Z,1745342730.469 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:25:30.469Z,1745342730.469 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:25:41.881Z,1745342741.881 [CommandExec](IMPORTANT): got command failComponent 2025-04-22T17:25:41.882Z,1745342741.882 [CommandExec](IMPORTANT): Failed components: 2025-04-22T17:25:41.882Z,1745342741.882 [CommandExec](IMPORTANT): No failed Components. 2025-04-22T17:25:44.379Z,1745342744.379 [CommandExec](IMPORTANT): got command gfscan 2025-04-22T17:25:44.478Z,1745342744.478 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-22T17:25:48.915Z,1745342748.915 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006156 CHAN A1 (24V): -0.004287 CHAN A2 (12V): -0.005157 CHAN A3 (5V): -0.003967 CHAN B0 (3.3V): -0.001632 CHAN B1 (3.15aV): -0.000657 CHAN B2 (3.15bV): -0.001156 CHAN B3 (GND): -0.001101 OPEN: 0.005614 Full Scale: +/- 1 mA 2025-04-22T17:26:03.122Z,1745342763.122 [CommandExec](IMPORTANT): got command schedule 2025-04-22T17:26:03.122Z,1745342763.122 [CommandExec](FAULT): Scheduling was paused by a command at 2025-04-22T17:23:58Z 2025-04-22T17:26:07.019Z,1745342767.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=8274, MT Status=2, MTMSN=0 2025-04-22T17:26:07.020Z,1745342767.020 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T17:26:11.267Z,1745342771.267 [CommandExec](IMPORTANT): got command schedule clear 2025-04-22T17:26:11.267Z,1745342771.267 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2025-04-22T17:26:11.268Z,1745342771.268 [CommandExec](IMPORTANT): got command schedule resume 2025-04-22T17:26:11.268Z,1745342771.268 [CommandExec](IMPORTANT): Scheduling is resumed 2025-04-22T17:26:27.619Z,1745342787.619 [NAL9602](INFO): SBD MO Status=2, MOMSN=8274, MT Status=2, MTMSN=0 2025-04-22T17:26:27.619Z,1745342787.619 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T17:26:56.302Z,1745342816.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=8274, MT Status=2, MTMSN=0 2025-04-22T17:26:56.302Z,1745342816.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T17:28:23.987Z,1745342903.987 [NAL9602](INFO): SBD MO Status=2, MOMSN=8274, MT Status=2, MTMSN=0 2025-04-22T17:28:23.988Z,1745342903.988 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T17:29:17.228Z,1745342957.228 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T17:29:47.919Z,1745342987.919 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T17:30:30.790Z,1745343030.790 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:30:30.790Z,1745343030.790 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:30:30.791Z,1745343030.791 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:30:30.791Z,1745343030.791 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:30:31.193Z,1745343031.193 [Default:CheckIn:D] Stopped 2025-04-22T17:30:31.194Z,1745343031.194 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:30:31.615Z,1745343031.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.526184 min 2025-04-22T17:30:31.615Z,1745343031.615 [Default:CheckIn:E] Stopped 2025-04-22T17:30:31.615Z,1745343031.615 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:30:31.615Z,1745343031.615 [Default:CheckIn] Stopped 2025-04-22T17:30:31.615Z,1745343031.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:30:31.616Z,1745343031.616 [Default:CheckIn](INFO): Running loop #2 2025-04-22T17:30:31.616Z,1745343031.616 [Default:CheckIn] Running Loop=2 2025-04-22T17:30:31.616Z,1745343031.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:30:31.616Z,1745343031.616 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:30:33.603Z,1745343033.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173032.00,A,3648.16963,N,12147.28012,W,0.019,0.00,220425,,,A*79 2025-04-22T17:30:33.624Z,1745343033.624 [NAL9602](INFO): GPS fix at 20250422T173032: (36.802827, -121.788002) 2025-04-22T17:30:33.654Z,1745343033.654 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:30:33.655Z,1745343033.655 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:30:42.864Z,1745343042.864 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250422T163301/Courier0021.lzma 2025-04-22T17:30:43.864Z,1745343043.864 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Courier0021.lzma.bak 2025-04-22T17:30:43.865Z,1745343043.865 [DataOverHttps](INFO): SBD MOMSN=24710676 2025-04-22T17:30:50.389Z,1745343050.389 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,24052713140498, 0.0 0.0,1475.0, 0 2025-04-22T17:31:03.546Z,1745343063.546 [DataOverHttps](INFO): Sending 597 bytes from file Logs/20250422T163301/Express0022.lzma 2025-04-22T17:31:04.135Z,1745343064.135 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T17:31:04.217Z,1745343064.217 [NAL9602](ERROR): received: +CSQ:0 OK74, 2, 0, 0, 0 OK 2025-04-22T17:31:04.547Z,1745343064.547 [DataOverHttps](INFO): Moved sent file to Logs/20250422T163301/Express0022.lzma.bak 2025-04-22T17:31:04.547Z,1745343064.547 [DataOverHttps](INFO): SBD MOMSN=24710686 2025-04-22T17:31:05.859Z,1745343065.859 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:31:05.859Z,1745343065.859 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:31:05.859Z,1745343065.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:31:07.983Z,1745343067.983 [CommandExec](IMPORTANT): got command schedule resume