2026-01-21T01:45:46.575Z,1768959946.575 [CommandExec](IMPORTANT): got command set sci2.SinkOffSurface 1 bool 2026-01-21T01:45:46.577Z,1768959946.577 [CommandExec](IMPORTANT): got command set sci2.SinkDepthTimeout 2 minute 2026-01-21T01:45:46.577Z,1768959946.577 [CommandExec](IMPORTANT): got command set sci2.MaxDepth 80 meter 2026-01-21T01:45:46.578Z,1768959946.578 [CommandExec](IMPORTANT): got command run 2026-01-21T01:45:46.582Z,1768959946.582 [CommandExec](IMPORTANT): Running 2026-01-21T01:45:46.585Z,1768959946.585 [CommandExec](IMPORTANT): got command restart logs 2026-01-21T01:45:46.952Z,1768959946.952 [Default] Stopped 2026-01-21T01:45:46.952Z,1768959946.952 [Default](DEBUG): Aggregate::uninitialize Default 2026-01-21T01:45:46.952Z,1768959946.952 [Default:B.GoToSurface] Stopped 2026-01-21T01:45:46.952Z,1768959946.952 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T01:45:46.953Z,1768959946.953 [MissionManager](IMPORTANT): Started mission sci2 2026-01-21T01:45:46.953Z,1768959946.953 [sci2] Running Loop=1 2026-01-21T01:45:46.953Z,1768959946.953 [sci2](DEBUG): Aggregate::initialize sci2 2026-01-21T01:45:46.953Z,1768959946.953 [sci2:Science] Running Loop=1 2026-01-21T01:45:46.953Z,1768959946.953 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science 2026-01-21T01:45:46.953Z,1768959946.953 [sci2:Science:A] Running Loop=1 2026-01-21T01:45:46.953Z,1768959946.953 [sci2:Science:B] Running Loop=1 2026-01-21T01:45:46.953Z,1768959946.953 [sci2:Science:C] Running Loop=1 2026-01-21T01:45:46.953Z,1768959946.953 [sci2:Science:D] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:E] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:F] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:Read_Oil] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:PeakDetectChl] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:PeakDetectChl:A] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:EdgeDetectChl] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:EdgeDetectChl](DEBUG): Aggregate::initialize sci2:Science:EdgeDetectChl 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:EdgeDetectChl:A] Running Loop=1 2026-01-21T01:45:46.954Z,1768959946.954 [sci2:Science:HighestChlPeakReport] Running Loop=1 2026-01-21T01:45:46.955Z,1768959946.955 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport 2026-01-21T01:45:46.955Z,1768959946.955 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2026-01-21T01:45:46.955Z,1768959946.955 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2026-01-21T01:45:46.955Z,1768959946.955 [sci2:Science:PeakDepthAvgProfilesChl] Running Loop=1 2026-01-21T01:45:46.955Z,1768959946.955 [sci2:Science:PeakDepthAvgProfilesChl](DEBUG): Aggregate::initialize sci2:Science:PeakDepthAvgProfilesChl 2026-01-21T01:45:46.960Z,1768959946.960 [sci2:Science:PeakDepthAvgProfilesChl:A] Running Loop=1 2026-01-21T01:45:46.960Z,1768959946.960 [sci2:Science:PeakDetectNO3] Running Loop=1 2026-01-21T01:45:46.960Z,1768959946.960 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:PeakDetectNO3:A] Running Loop=1 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:PeakDetectOil] Running Loop=1 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:PeakDetectOil:A] Running Loop=1 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:HighestOilPeakReport] Running Loop=1 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestOilPeakReport 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2026-01-21T01:45:46.961Z,1768959946.961 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:PeakDetectFDOM] Running Loop=1 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2:Science:PeakDetectFDOM 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:PeakDetectFDOM:A] Running Loop=1 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:PeakDetectSalinity] Running Loop=1 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize sci2:Science:PeakDetectSalinity 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:PeakDetectSalinity:A] Running Loop=1 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:HighestSaltPeakReport] Running Loop=1 2026-01-21T01:45:46.962Z,1768959946.962 [sci2:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestSaltPeakReport 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:PeakDetectPlanktivoreLMavgROI] Running Loop=1 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize sci2:Science:PeakDetectPlanktivoreLMavgROI 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:PeakDetectPlanktivoreLMavgROI:A] Running Loop=1 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:EdgeDetectPlanktivoreLMavgROI] Running Loop=1 2026-01-21T01:45:46.963Z,1768959946.963 [sci2:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize sci2:Science:EdgeDetectPlanktivoreLMavgROI 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:EdgeDetectPlanktivoreLMavgROI:A] Running Loop=1 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI] Running Loop=1 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI](DEBUG): Aggregate::initialize sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI:A] Running Loop=1 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:PeakDetectPlanktivoreHMavgROI] Running Loop=1 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize sci2:Science:PeakDetectPlanktivoreHMavgROI 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:PeakDetectPlanktivoreHMavgROI:A] Running Loop=1 2026-01-21T01:45:46.968Z,1768959946.968 [sci2:Science:EdgeDetectPlanktivoreHMavgROI] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize sci2:Science:EdgeDetectPlanktivoreHMavgROI 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:EdgeDetectPlanktivoreHMavgROI:A] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:PeakDetectPlanktivoreDiatoms] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize sci2:Science:PeakDetectPlanktivoreDiatoms 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:PeakDetectPlanktivoreDiatoms:A] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:EdgeDetectPlanktivoreDiatoms] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize sci2:Science:EdgeDetectPlanktivoreDiatoms 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:EdgeDetectPlanktivoreDiatoms:A] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:PeakDetectPlanktivoreDinoflagellates] Running Loop=1 2026-01-21T01:45:46.969Z,1768959946.969 [sci2:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize sci2:Science:PeakDetectPlanktivoreDinoflagellates 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:PeakDetectPlanktivoreDinoflagellates:A] Running Loop=1 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:EdgeDetectPlanktivoreDinoflagellates] Running Loop=1 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize sci2:Science:EdgeDetectPlanktivoreDinoflagellates 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:EdgeDetectPlanktivoreDinoflagellates:A] Running Loop=1 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:ChlThreshTrigger] Running Loop=1 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize sci2:Science:ChlThreshTrigger 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2026-01-21T01:45:46.970Z,1768959946.970 [sci2:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:Science:OceanCurrent] Running Loop=1 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:Science:OceanCurrent](DEBUG): Aggregate::initialize sci2:Science:OceanCurrent 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:Science:OceanCurrent:A.] Running Loop=1 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:StandardEnvelopes] Running Loop=1 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T01:45:46.971Z,1768959946.971 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:BackseatDriver] Running Loop=1 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:BackseatDriver](DEBUG): Aggregate::initialize sci2:BackseatDriver 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:BackseatDriver:A.BackseatDriver] Running Loop=1 2026-01-21T01:45:46.976Z,1768959946.976 [sci2:PowerOnly] Running Loop=1 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly](DEBUG): Aggregate::initialize sci2:PowerOnly 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly:A] Running Loop=1 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly:B] Running Loop=1 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly:C] Running Loop=1 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly:D] Running Loop=1 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly:E.Wait] Running Loop=1 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2026-01-21T01:45:46.977Z,1768959946.977 [sci2:C] Running Loop=1 2026-01-21T01:45:46.978Z,1768959946.978 [sci2:PowerOnly] Running Loop=1 2026-01-21T01:45:46.978Z,1768959946.978 [sci2:PowerOnly:D] Stopped 2026-01-21T01:45:46.978Z,1768959946.978 [sci2:PowerOnly:C] Stopped 2026-01-21T01:45:46.978Z,1768959946.978 [sci2:PowerOnly:B] Stopped 2026-01-21T01:45:46.979Z,1768959946.979 [sci2:PowerOnly:A] Stopped 2026-01-21T01:45:46.979Z,1768959946.979 [sci2:BackseatDriver] Running Loop=1 2026-01-21T01:45:46.979Z,1768959946.979 [sci2:StandardEnvelopes] Running Loop=1 2026-01-21T01:45:46.979Z,1768959946.979 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2026-01-21T01:45:46.979Z,1768959946.979 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2026-01-21T01:45:46.979Z,1768959946.979 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2026-01-21T01:45:46.984Z,1768959946.984 [sci2:C] Stopped 2026-01-21T01:45:46.984Z,1768959946.984 [sci2:D] Running Loop=1 2026-01-21T01:45:46.984Z,1768959946.984 [sci2:Science] Running Loop=1 2026-01-21T01:45:46.987Z,1768959946.987 [sci2:Science:ChlThreshTrigger] Stopped 2026-01-21T01:45:46.987Z,1768959946.987 [sci2:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize sci2:Science:ChlThreshTrigger 2026-01-21T01:45:46.987Z,1768959946.987 [sci2:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2026-01-21T01:45:46.987Z,1768959946.987 [sci2:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2026-01-21T01:45:46.987Z,1768959946.987 [sci2:Science:EdgeDetectPlanktivoreDinoflagellates] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize sci2:Science:EdgeDetectPlanktivoreDinoflagellates 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:EdgeDetectPlanktivoreDinoflagellates:A] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:PeakDetectPlanktivoreDinoflagellates] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectPlanktivoreDinoflagellates 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:PeakDetectPlanktivoreDinoflagellates:A] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:EdgeDetectPlanktivoreDiatoms] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize sci2:Science:EdgeDetectPlanktivoreDiatoms 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:EdgeDetectPlanktivoreDiatoms:A] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:PeakDetectPlanktivoreDiatoms] Stopped 2026-01-21T01:45:46.992Z,1768959946.992 [sci2:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectPlanktivoreDiatoms 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDetectPlanktivoreDiatoms:A] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:EdgeDetectPlanktivoreHMavgROI] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize sci2:Science:EdgeDetectPlanktivoreHMavgROI 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:EdgeDetectPlanktivoreHMavgROI:A] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDetectPlanktivoreHMavgROI] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectPlanktivoreHMavgROI 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDetectPlanktivoreHMavgROI:A] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:PeakDepthAvgProfilesPlanktivoreLMavgROI:A] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:EdgeDetectPlanktivoreLMavgROI] Stopped 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize sci2:Science:EdgeDetectPlanktivoreLMavgROI 2026-01-21T01:45:46.993Z,1768959946.993 [sci2:Science:EdgeDetectPlanktivoreLMavgROI:A] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectPlanktivoreLMavgROI] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectPlanktivoreLMavgROI 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectPlanktivoreLMavgROI:A] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:HighestSaltPeakReport] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestSaltPeakReport 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectSalinity] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectSalinity 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectSalinity:A] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectFDOM] Stopped 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectFDOM 2026-01-21T01:45:46.994Z,1768959946.994 [sci2:Science:PeakDetectFDOM:A] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:HighestOilPeakReport] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestOilPeakReport 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDetectOil] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDetectOil:A] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDetectNO3] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDetectNO3:A] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDepthAvgProfilesChl] Stopped 2026-01-21T01:45:46.995Z,1768959946.995 [sci2:Science:PeakDepthAvgProfilesChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDepthAvgProfilesChl 2026-01-21T01:45:47.000Z,1768959947.000 [sci2:Science:PeakDepthAvgProfilesChl:A] Stopped 2026-01-21T01:45:47.000Z,1768959947.000 [sci2:Science:HighestChlPeakReport] Stopped 2026-01-21T01:45:47.000Z,1768959947.000 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport 2026-01-21T01:45:47.000Z,1768959947.000 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2026-01-21T01:45:47.000Z,1768959947.000 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:EdgeDetectChl] Stopped 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:EdgeDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:EdgeDetectChl 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:EdgeDetectChl:A] Stopped 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:PeakDetectChl] Stopped 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:PeakDetectChl:A] Stopped 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:OceanCurrent:A.] Running Loop=1 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:Read_Oil] Stopped 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:F] Stopped 2026-01-21T01:45:47.001Z,1768959947.001 [sci2:Science:E] Running Loop=1 2026-01-21T01:45:47.002Z,1768959947.002 [sci2:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2026-01-21T01:45:47.002Z,1768959947.002 [sci2:Science:D] Stopped 2026-01-21T01:45:47.002Z,1768959947.002 [sci2:Science:C] Running Loop=1 2026-01-21T01:45:47.003Z,1768959947.003 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2026-01-21T01:45:47.008Z,1768959947.008 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2026-01-21T01:45:47.008Z,1768959947.008 [sci2:Science:B] Running Loop=1 2026-01-21T01:45:47.009Z,1768959947.009 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2026-01-21T01:45:47.010Z,1768959947.010 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2026-01-21T01:45:47.010Z,1768959947.010 [sci2:Science:A] Stopped 2026-01-21T01:45:47.533Z,1768959947.533 [sci2:D] Stopped 2026-01-21T01:45:47.533Z,1768959947.533 [sci2:setRestoreMode] Running Loop=1 2026-01-21T01:45:47.533Z,1768959947.533 [sci2:setRestoreMode](DEBUG): Aggregate::initialize sci2:setRestoreMode 2026-01-21T01:45:47.533Z,1768959947.533 [sci2:setRestoreMode:A] Running Loop=1 2026-01-21T01:45:47.694Z,1768959947.694 [WetLabsBB2FL](INFO): Powering up 2026-01-21T01:45:47.915Z,1768959947.915 [ElevatorOffsetCalculator](IMPORTANT): Completed estimation for commanded vars: speed 0.90 m/s, pitch -20.00 deg, mass-position 30.65 mm. Average elevator angle=3.849919 +/- 0.095469 deg (conf. level 99.90%, sigma: 0.314866 deg). 2026-01-21T01:45:47.927Z,1768959947.927 [sci2:setRestoreMode] Stopped 2026-01-21T01:45:47.927Z,1768959947.927 [sci2:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:setRestoreMode 2026-01-21T01:45:47.927Z,1768959947.927 [sci2:setRestoreMode:A] Stopped 2026-01-21T01:45:47.927Z,1768959947.927 [sci2:setSinkRestore] Running Loop=1 2026-01-21T01:45:47.932Z,1768959947.932 [sci2:setSinkRestore](DEBUG): Aggregate::initialize sci2:setSinkRestore 2026-01-21T01:45:47.932Z,1768959947.932 [sci2:setSinkRestore:A] Running Loop=1 2026-01-21T01:45:48.336Z,1768959948.336 [sci2:setSinkRestore:A] Stopped 2026-01-21T01:45:48.336Z,1768959948.336 [sci2:setSinkRestore](INFO): Completed sci2:setSinkRestore 2026-01-21T01:45:48.336Z,1768959948.336 [sci2:setSinkRestore] Stopped 2026-01-21T01:45:48.336Z,1768959948.336 [sci2:setSinkRestore](DEBUG): Aggregate::uninitialize sci2:setSinkRestore 2026-01-21T01:45:48.336Z,1768959948.336 [sci2:H] Running Loop=1 2026-01-21T01:45:48.744Z,1768959948.744 [sci2:H] Stopped 2026-01-21T01:45:48.744Z,1768959948.744 [sci2:I] Running Loop=1 2026-01-21T01:45:48.745Z,1768959948.745 [sci2:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2026-01-21T01:45:49.139Z,1768959949.139 [sci2:I] Stopped 2026-01-21T01:45:49.139Z,1768959949.139 [sci2:J] Running Loop=1 2026-01-21T01:45:49.543Z,1768959949.543 [sci2:J] Stopped 2026-01-21T01:45:49.543Z,1768959949.543 [sci2:StartingMission] Running Loop=1 2026-01-21T01:45:49.543Z,1768959949.543 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission 2026-01-21T01:45:49.947Z,1768959949.947 [sci2:NeedComms] Running Loop=1 2026-01-21T01:45:49.947Z,1768959949.947 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T01:45:49.947Z,1768959949.947 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T01:45:49.947Z,1768959949.947 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T01:45:49.952Z,1768959949.952 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T01:45:49.952Z,1768959949.952 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T01:45:49.953Z,1768959949.953 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T01:45:49.953Z,1768959949.953 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T01:45:49.953Z,1768959949.953 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T01:45:49.954Z,1768959949.954 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T01:45:49.954Z,1768959949.954 [sci2:NeedComms:A] Running Loop=1 2026-01-21T01:45:49.955Z,1768959949.955 [sci2:NeedComms:A](INFO): last time_fix was: 1768957387.000000 second since 1970/01/01T00:00:00Z 2026-01-21T01:45:49.955Z,1768959949.955 [sci2:NeedComms:A] Stopped 2026-01-21T01:45:49.955Z,1768959949.955 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T01:45:49.955Z,1768959949.955 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T01:45:49.960Z,1768959949.960 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T01:45:50.356Z,1768959950.356 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T01:45:50.356Z,1768959950.356 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T01:45:50.593Z,1768959950.593 [CTD_Seabird](ERROR): Failed to parse device response: 2026-01-21T01:45:50.763Z,1768959950.763 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T01:45:50.768Z,1768959950.768 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T01:45:50.768Z,1768959950.768 [sci2:NeedComms:setActive] Stopped 2026-01-21T01:45:50.768Z,1768959950.768 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T01:45:51.158Z,1768959951.158 [sci2:manageDiveMode] Running Loop=1 2026-01-21T01:45:51.158Z,1768959951.158 [sci2:manageDiveMode](DEBUG): Aggregate::initialize sci2:manageDiveMode 2026-01-21T01:45:51.158Z,1768959951.158 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T01:45:51.158Z,1768959951.158 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T01:45:51.159Z,1768959951.159 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T01:45:51.159Z,1768959951.159 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T01:45:51.159Z,1768959951.159 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T01:45:51.165Z,1768959951.165 [sci2:NeedComms:D] Running Loop=1 2026-01-21T01:45:51.597Z,1768959951.597 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-01-21T01:45:52.266Z,1768959952.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:46:23.388Z,1768959983.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:46:23.864Z,1768959983.864 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T01:46:23.944Z,1768959983.944 [NAL9602](ERROR): received: +CSQ:0 OK349, 2, 0, 0, 0 OK 2026-01-21T01:46:54.512Z,1768960014.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:47:06.695Z,1768960026.695 [NAL9602](INFO): SBD MO Status=2, MOMSN=25349, MT Status=2, MTMSN=0 2026-01-21T01:47:06.695Z,1768960026.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T01:47:25.638Z,1768960045.638 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:47:28.517Z,1768960048.517 [NAL9602](INFO): SBD MO Status=2, MOMSN=25349, MT Status=2, MTMSN=0 2026-01-21T01:47:28.517Z,1768960048.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T01:47:36.421Z,1768960056.421 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003259 2026-01-21T01:47:37.798Z,1768960057.798 [NAL9602](INFO): SBD MO Status=0, MOMSN=25349, MT Status=0, MTMSN=0 2026-01-21T01:47:37.798Z,1768960057.798 [NAL9602](INFO): No messages in MT queue 2026-01-21T01:47:39.010Z,1768960059.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014736.00,A,3657.83929,N,12218.85823,W,0.855,252.52,210126,,,D*70 2026-01-21T01:47:39.012Z,1768960059.012 [NAL9602](INFO): GPS fix at 20260121T014736: (36.963988, -122.314304) 2026-01-21T01:47:39.049Z,1768960059.049 [UniversalFixResidualReporter](INFO): Fix residual: 19.9 %DT, over the last 1650.6 m. Residual distance 329.0 m at bearing -43.6 degrees. Fix at (36.9640, -122.3143) with 1906.8 m made good. 2026-01-21T01:47:39.051Z,1768960059.051 [sci2:NeedComms:D] Stopped 2026-01-21T01:47:39.051Z,1768960059.051 [sci2:NeedComms:E] Running Loop=1 2026-01-21T01:47:39.453Z,1768960059.453 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-01-21T01:47:47.239Z,1768960067.239 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20260120T212604/Courier0028.lzma 2026-01-21T01:47:48.242Z,1768960068.242 [DataOverHttps](INFO): Moved sent file to Logs/20260120T212604/Courier0028.lzma.bak 2026-01-21T01:47:48.242Z,1768960068.242 [DataOverHttps](INFO): SBD MOMSN=26583279 2026-01-21T01:48:06.285Z,1768960086.285 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20260121T014546/Courier0000.lzma 2026-01-21T01:48:07.286Z,1768960087.286 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0000.lzma.bak 2026-01-21T01:48:07.286Z,1768960087.286 [DataOverHttps](INFO): SBD MOMSN=26583286 2026-01-21T01:48:11.363Z,1768960091.363 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T01:48:26.833Z,1768960106.833 [DataOverHttps](INFO): Sending 967 bytes from file Logs/20260120T212604/Express0029.lzma 2026-01-21T01:48:27.834Z,1768960107.834 [DataOverHttps](INFO): Moved sent file to Logs/20260120T212604/Express0029.lzma.bak 2026-01-21T01:48:27.834Z,1768960107.834 [DataOverHttps](INFO): SBD MOMSN=26583291 2026-01-21T01:48:45.417Z,1768960125.417 [DataOverHttps](INFO): Sending 966 bytes from file Logs/20260121T014546/Express0001.lzma 2026-01-21T01:48:47.422Z,1768960127.422 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0001.lzma.bak 2026-01-21T01:48:47.422Z,1768960127.422 [DataOverHttps](INFO): SBD MOMSN=26583307 2026-01-21T01:48:48.952Z,1768960128.952 [sci2:NeedComms:E] Stopped 2026-01-21T01:48:48.953Z,1768960128.953 [sci2:NeedComms:F] Running Loop=1 2026-01-21T01:48:49.370Z,1768960129.370 [sci2:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-01-21T01:48:50.959Z,1768960130.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014848.00,A,3657.83475,N,12218.85172,W,0.447,127.76,210126,,,D*77 2026-01-21T01:48:50.961Z,1768960130.961 [NAL9602](INFO): GPS fix at 20260121T014848: (36.963912, -122.314195) 2026-01-21T01:48:50.998Z,1768960130.998 [sci2:NeedComms:F] Stopped 2026-01-21T01:48:50.998Z,1768960130.998 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T01:48:50.998Z,1768960130.998 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T01:48:50.998Z,1768960130.998 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T01:48:51.430Z,1768960131.430 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T01:48:51.430Z,1768960131.430 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T01:48:51.430Z,1768960131.430 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T01:48:51.430Z,1768960131.430 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T01:48:51.430Z,1768960131.430 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T01:48:51.430Z,1768960131.430 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T01:48:51.431Z,1768960131.431 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T01:48:51.825Z,1768960131.825 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T01:48:51.825Z,1768960131.825 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T01:48:51.825Z,1768960131.825 [sci2:NeedComms:setInactive] Stopped 2026-01-21T01:48:51.825Z,1768960131.825 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T01:48:51.826Z,1768960131.826 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T01:48:51.826Z,1768960131.826 [sci2:NeedComms] Stopped 2026-01-21T01:48:51.826Z,1768960131.826 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T01:48:51.826Z,1768960131.826 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T01:48:51.826Z,1768960131.826 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T01:48:52.200Z,1768960132.200 [sci2:StartingMission](INFO): Completed sci2:StartingMission 2026-01-21T01:48:52.200Z,1768960132.200 [sci2:StartingMission] Stopped 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap] Running Loop=1 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap:Dive] Running Loop=1 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T01:48:52.201Z,1768960132.201 [sci2:Lap:Wpt1] Running Loop=1 2026-01-21T01:48:52.202Z,1768960132.202 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1 2026-01-21T01:48:52.202Z,1768960132.202 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1 2026-01-21T01:48:52.202Z,1768960132.202 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent. 2026-01-21T01:48:52.203Z,1768960132.203 [sci2:Lap:Wpt1:A.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T01:48:52.203Z,1768960132.203 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T01:48:52.203Z,1768960132.203 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T01:48:52.203Z,1768960132.203 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T01:48:52.609Z,1768960132.609 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T01:48:52.610Z,1768960132.610 [sci2:Lap:Dive] Running Loop=1 2026-01-21T01:48:52.610Z,1768960132.610 [sci2:DiveCmd] Running Loop=1 2026-01-21T01:48:52.610Z,1768960132.610 [sci2:DiveCmd](DEBUG): Aggregate::initialize sci2:DiveCmd 2026-01-21T01:48:52.610Z,1768960132.610 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T01:48:52.610Z,1768960132.610 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T01:48:52.610Z,1768960132.610 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T01:48:52.611Z,1768960132.611 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T01:48:52.616Z,1768960132.616 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T01:48:52.617Z,1768960132.617 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T01:48:52.618Z,1768960132.618 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T01:48:52.618Z,1768960132.618 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T01:48:52.618Z,1768960132.618 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T01:48:52.618Z,1768960132.618 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T01:48:52.618Z,1768960132.618 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T01:48:52.619Z,1768960132.619 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T01:48:52.624Z,1768960132.624 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T01:48:52.625Z,1768960132.625 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T01:48:52.626Z,1768960132.626 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T01:48:52.626Z,1768960132.626 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T01:48:52.626Z,1768960132.626 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T01:48:52.626Z,1768960132.626 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T01:49:31.832Z,1768960171.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=25350, MT Status=2, MTMSN=0 2026-01-21T01:49:31.832Z,1768960171.832 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T01:50:20.896Z,1768960220.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:50:52.020Z,1768960252.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:50:52.883Z,1768960252.883 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T01:48:52.6Z 2026-01-21T01:50:52.883Z,1768960252.883 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T01:50:52.883Z,1768960252.883 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T01:50:52.884Z,1768960252.884 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T01:50:52.885Z,1768960252.885 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 5.286073 m 2026-01-21T01:50:52.885Z,1768960252.885 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T01:50:52.885Z,1768960252.885 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T01:50:52.885Z,1768960252.885 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T01:50:52.886Z,1768960252.886 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T01:50:53.282Z,1768960253.282 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T01:50:53.282Z,1768960253.282 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T01:50:53.282Z,1768960253.282 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T01:50:53.282Z,1768960253.282 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T01:50:53.282Z,1768960253.282 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T01:50:53.282Z,1768960253.282 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T01:50:53.283Z,1768960253.283 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T01:50:53.283Z,1768960253.283 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T01:50:53.283Z,1768960253.283 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T01:50:53.689Z,1768960253.689 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T01:50:53.689Z,1768960253.689 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T01:50:53.689Z,1768960253.689 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T01:50:53.690Z,1768960253.690 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T01:50:53.691Z,1768960253.691 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T01:50:53.692Z,1768960253.692 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T01:50:53.692Z,1768960253.692 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T01:50:53.692Z,1768960253.692 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T01:50:53.692Z,1768960253.692 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T01:50:53.692Z,1768960253.692 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T01:50:53.693Z,1768960253.693 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T01:50:53.693Z,1768960253.693 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T01:50:53.693Z,1768960253.693 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T01:50:53.694Z,1768960253.694 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T01:50:53.695Z,1768960253.695 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T01:50:53.695Z,1768960253.695 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T01:50:53.695Z,1768960253.695 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T01:50:53.695Z,1768960253.695 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T01:50:54.062Z,1768960254.062 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.70 m/s, pitch 30.00 deg, mass-position 30.65 mm (3 active estimators). 2026-01-21T01:50:54.472Z,1768960254.472 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.70 m/s, pitch -20.00 deg, mass-position 30.65 mm (4 active estimators). 2026-01-21T01:50:55.276Z,1768960255.276 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2026-01-21T01:50:57.976Z,1768960257.976 [Radio_Surface](INFO): Powering down 2026-01-21T01:51:23.164Z,1768960283.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T01:51:24.168Z,1768960284.168 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T01:51:57.316Z,1768960317.316 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759277 2026-01-21T01:53:53.515Z,1768960433.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T01:54:24.220Z,1768960464.220 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T02:11:38.718Z,1768961498.718 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T02:12:10.129Z,1768961530.129 [CTD_Seabird](ERROR): Failed to parse device response: ,14.2203,4.02267,4064.16 2026-01-21T02:30:21.801Z,1768962621.801 [BPC1](ERROR): Battery stick #14 (s/n: 0496) reported OVER_TEMP_ALARM. Status code: 0x1717. 2026-01-21T02:30:21.802Z,1768962621.802 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 5. 2026-01-21T02:30:21.804Z,1768962621.804 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2026-01-21T03:18:51.225Z,1768965531.225 [sci2:NeedComms] Running Loop=1 2026-01-21T03:18:51.225Z,1768965531.225 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T03:18:51.225Z,1768965531.225 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T03:18:51.225Z,1768965531.225 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T03:18:51.225Z,1768965531.225 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T03:18:51.226Z,1768965531.226 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T03:18:51.226Z,1768965531.226 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T03:18:51.227Z,1768965531.227 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T03:18:51.227Z,1768965531.227 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T03:18:51.227Z,1768965531.227 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T03:18:51.228Z,1768965531.228 [sci2:NeedComms:A] Running Loop=1 2026-01-21T03:18:51.229Z,1768965531.229 [sci2:NeedComms:A](INFO): last time_fix was: 1768960128.000000 second since 1970/01/01T00:00:00Z 2026-01-21T03:18:51.230Z,1768965531.230 [sci2:NeedComms:A] Stopped 2026-01-21T03:18:51.230Z,1768965531.230 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T03:18:51.230Z,1768965531.230 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T03:18:51.230Z,1768965531.230 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T03:18:51.640Z,1768965531.640 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T03:18:51.640Z,1768965531.640 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T03:18:52.047Z,1768965532.047 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T03:18:52.052Z,1768965532.052 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T03:18:52.052Z,1768965532.052 [sci2:NeedComms:setActive] Stopped 2026-01-21T03:18:52.052Z,1768965532.052 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T03:18:52.433Z,1768965532.433 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T03:18:52.433Z,1768965532.433 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T03:18:52.433Z,1768965532.433 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T03:18:52.836Z,1768965532.836 [sci2:Lap:Dive] Stopped 2026-01-21T03:18:52.836Z,1768965532.836 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T03:18:52.836Z,1768965532.836 [sci2:Lap:Dive:A] Stopped 2026-01-21T03:18:52.836Z,1768965532.836 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T03:18:52.837Z,1768965532.837 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T03:18:52.838Z,1768965532.838 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T03:21:35.642Z,1768965695.642 [sci2:NeedComms:D] Running Loop=1 2026-01-21T03:21:36.240Z,1768965696.240 [Radio_Surface](INFO): Powering up 2026-01-21T03:21:41.660Z,1768965701.660 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T03:22:08.749Z,1768965728.749 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T03:22:08.832Z,1768965728.832 [NAL9602](ERROR): received: +CSQ:0 OK350, 2, 0, 0, 0 OK 2026-01-21T03:22:11.780Z,1768965731.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:22:42.904Z,1768965762.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:23:14.032Z,1768965794.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:23:18.641Z,1768965798.641 [NAL9602](INFO): SBD MO Status=2, MOMSN=25350, MT Status=2, MTMSN=0 2026-01-21T03:23:18.641Z,1768965798.641 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T03:23:44.908Z,1768965824.908 [NAL9602](INFO): SBD MO Status=2, MOMSN=25350, MT Status=2, MTMSN=0 2026-01-21T03:23:44.908Z,1768965824.908 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T03:23:45.156Z,1768965825.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:23:54.932Z,1768965834.932 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003266 2026-01-21T03:26:17.380Z,1768965977.380 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-01-21T03:26:17.425Z,1768965977.425 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T03:26:17.427Z,1768965977.427 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T03:26:18.430Z,1768965978.430 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T03:26:18.432Z,1768965978.432 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T03:26:19.433Z,1768965979.433 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T03:26:19.435Z,1768965979.435 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T03:26:20.438Z,1768965980.438 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T03:26:20.439Z,1768965980.439 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T03:26:21.443Z,1768965981.443 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T03:26:21.444Z,1768965981.444 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T03:26:21.746Z,1768965981.746 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.001434 CHAN A1 (24V): 0.003774 CHAN A2 (12V): -0.008751 CHAN A3 (5V): -0.007340 CHAN B0 (3.3V): -0.002706 CHAN B1 (3.15aV): -0.001764 CHAN B2 (3.15bV): -0.003036 CHAN B3 (GND): -0.002223 OPEN: 0.011997 Full Scale: +/- 1 mA 2026-01-21T03:26:40.243Z,1768966000.243 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T03:26:47.117Z,1768966007.117 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032644.00,A,3657.02866,N,12217.00966,W,1.477,120.63,210126,,,D*78 2026-01-21T03:26:47.119Z,1768966007.119 [NAL9602](INFO): GPS fix at 20260121T032644: (36.950478, -122.283494) 2026-01-21T03:26:47.131Z,1768966007.131 [UniversalFixResidualReporter](INFO): Fix residual: 11.0 %DT, over the last 2880.8 m. Residual distance 316.0 m at bearing -41.5 degrees. Fix at (36.9505, -122.2835) with 3110.2 m made good. 2026-01-21T03:26:47.133Z,1768966007.133 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T03:26:47.135Z,1768966007.135 [sci2:NeedComms:D] Stopped 2026-01-21T03:26:47.136Z,1768966007.136 [sci2:NeedComms:E] Running Loop=1 2026-01-21T03:26:55.308Z,1768966015.308 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20260121T014546/Courier0003.lzma 2026-01-21T03:26:56.310Z,1768966016.310 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0003.lzma.bak 2026-01-21T03:26:56.310Z,1768966016.310 [DataOverHttps](INFO): SBD MOMSN=26583570 2026-01-21T03:27:13.593Z,1768966033.593 [DataOverHttps](INFO): Sending 1258 bytes from file Logs/20260121T014546/Express0004.lzma 2026-01-21T03:27:14.594Z,1768966034.594 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0004.lzma.bak 2026-01-21T03:27:14.594Z,1768966034.594 [DataOverHttps](INFO): SBD MOMSN=26583573 2026-01-21T03:27:15.898Z,1768966035.898 [sci2:NeedComms:E] Stopped 2026-01-21T03:27:15.898Z,1768966035.898 [sci2:NeedComms:F] Running Loop=1 2026-01-21T03:27:17.901Z,1768966037.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032715.00,A,3657.02695,N,12217.00776,W,0.175,157.44,210126,,,D*73 2026-01-21T03:27:17.903Z,1768966037.903 [NAL9602](INFO): GPS fix at 20260121T032715: (36.950449, -122.283463) 2026-01-21T03:27:17.925Z,1768966037.925 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T03:27:17.933Z,1768966037.933 [sci2:NeedComms:F] Stopped 2026-01-21T03:27:17.933Z,1768966037.933 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T03:27:17.934Z,1768966037.934 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T03:27:17.934Z,1768966037.934 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T03:27:18.346Z,1768966038.346 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T03:27:18.346Z,1768966038.346 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T03:27:18.346Z,1768966038.346 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T03:27:18.346Z,1768966038.346 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T03:27:18.346Z,1768966038.346 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T03:27:18.346Z,1768966038.346 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T03:27:18.347Z,1768966038.347 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T03:27:18.746Z,1768966038.746 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T03:27:18.747Z,1768966038.747 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T03:27:18.747Z,1768966038.747 [sci2:NeedComms:setInactive] Stopped 2026-01-21T03:27:18.747Z,1768966038.747 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T03:27:18.748Z,1768966038.748 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T03:27:18.760Z,1768966038.760 [sci2:NeedComms] Stopped 2026-01-21T03:27:18.760Z,1768966038.760 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T03:27:18.760Z,1768966038.760 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T03:27:18.760Z,1768966038.760 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T03:27:19.134Z,1768966039.134 [sci2:Lap:Dive] Running Loop=1 2026-01-21T03:27:19.135Z,1768966039.135 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T03:27:19.135Z,1768966039.135 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T03:27:19.140Z,1768966039.140 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T03:27:19.140Z,1768966039.140 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T03:27:19.140Z,1768966039.140 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T03:27:19.140Z,1768966039.140 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T03:27:19.141Z,1768966039.141 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T03:27:19.142Z,1768966039.142 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T03:27:19.142Z,1768966039.142 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T03:27:19.142Z,1768966039.142 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T03:27:19.142Z,1768966039.142 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T03:27:19.143Z,1768966039.143 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T03:27:19.143Z,1768966039.143 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T03:27:50.653Z,1768966070.653 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T03:28:46.796Z,1768966126.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:29:17.936Z,1768966157.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:29:19.143Z,1768966159.143 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T03:27:19.1Z 2026-01-21T03:29:19.143Z,1768966159.143 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T03:29:19.143Z,1768966159.143 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T03:29:19.143Z,1768966159.143 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T03:29:19.160Z,1768966159.160 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 5.229986 m 2026-01-21T03:29:19.160Z,1768966159.160 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T03:29:19.160Z,1768966159.160 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T03:29:19.160Z,1768966159.160 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T03:29:19.160Z,1768966159.160 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T03:29:19.547Z,1768966159.547 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T03:29:19.547Z,1768966159.547 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T03:29:19.547Z,1768966159.547 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T03:29:19.547Z,1768966159.547 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T03:29:19.547Z,1768966159.547 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T03:29:19.547Z,1768966159.547 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T03:29:19.548Z,1768966159.548 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T03:29:19.548Z,1768966159.548 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T03:29:19.548Z,1768966159.548 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T03:29:19.954Z,1768966159.954 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T03:29:19.954Z,1768966159.954 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T03:29:19.954Z,1768966159.954 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T03:29:19.955Z,1768966159.955 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T03:29:19.956Z,1768966159.956 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T03:29:19.957Z,1768966159.957 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T03:29:19.957Z,1768966159.957 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T03:29:19.957Z,1768966159.957 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T03:29:19.957Z,1768966159.957 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T03:29:19.957Z,1768966159.957 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T03:29:19.957Z,1768966159.957 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T03:29:19.959Z,1768966159.959 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T03:29:19.959Z,1768966159.959 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T03:29:19.959Z,1768966159.959 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T03:29:19.959Z,1768966159.959 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T03:29:19.959Z,1768966159.959 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T03:29:19.959Z,1768966159.959 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T03:29:23.304Z,1768966163.304 [Radio_Surface](INFO): Powering down 2026-01-21T03:29:49.076Z,1768966189.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T03:29:50.080Z,1768966190.080 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T03:30:55.351Z,1768966255.351 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.796844 2026-01-21T03:37:21.982Z,1768966641.982 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2026-01-21T03:42:40.360Z,1768966960.360 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T03:50:22.361Z,1768967422.361 [CTD_Seabird](ERROR): Failed to parse device response: 17,4056.91 2026-01-21T03:58:53.812Z,1768967933.812 [RudderServo](ERROR): getPosition uart error serial timeout 2026-01-21T03:58:53.812Z,1768967933.812 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2026-01-21T03:58:53.812Z,1768967933.812 [RudderServo] Communications Fault, FailCount= 1 2026-01-21T03:58:53.812Z,1768967933.812 [RudderServo](ERROR): Communications Fault 2026-01-21T03:58:53.879Z,1768967933.879 [CBIT](ERROR): Communications Fault in component: RudderServo 2026-01-21T03:58:53.933Z,1768967933.933 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2026-01-21T03:58:53.933Z,1768967933.933 [RudderServo](INFO): Powering down 2026-01-21T03:58:54.753Z,1768967934.753 [RudderServo](DEBUG): Initializing EZServoServo. 2026-01-21T03:58:54.873Z,1768967934.873 [RudderServo](DEBUG): Initializing RudderServo. 2026-01-21T03:58:54.891Z,1768967934.891 [CBIT](INFO): Clearing failed state for component RudderServo 2026-01-21T03:58:54.891Z,1768967934.891 [RudderServo] No Fault, FailCount= 1 2026-01-21T04:01:22.975Z,1768968082.975 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T04:01:22.979Z,1768968082.979 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T04:21:49.853Z,1768969309.853 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T04:23:38.931Z,1768969418.931 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T04:42:24.759Z,1768970544.759 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T04:42:24.763Z,1768970544.763 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T04:57:18.135Z,1768971438.135 [sci2:NeedComms] Running Loop=1 2026-01-21T04:57:18.135Z,1768971438.135 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T04:57:18.135Z,1768971438.135 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T04:57:18.136Z,1768971438.136 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T04:57:18.136Z,1768971438.136 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T04:57:18.137Z,1768971438.137 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T04:57:18.137Z,1768971438.137 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T04:57:18.137Z,1768971438.137 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T04:57:18.138Z,1768971438.138 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T04:57:18.138Z,1768971438.138 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T04:57:18.138Z,1768971438.138 [sci2:NeedComms:A] Running Loop=1 2026-01-21T04:57:18.156Z,1768971438.156 [sci2:NeedComms:A](INFO): last time_fix was: 1768966035.000000 second since 1970/01/01T00:00:00Z 2026-01-21T04:57:18.156Z,1768971438.156 [sci2:NeedComms:A] Stopped 2026-01-21T04:57:18.157Z,1768971438.157 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T04:57:18.157Z,1768971438.157 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T04:57:18.157Z,1768971438.157 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T04:57:18.542Z,1768971438.542 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T04:57:18.542Z,1768971438.542 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T04:57:18.951Z,1768971438.951 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T04:57:18.951Z,1768971438.951 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T04:57:18.951Z,1768971438.951 [sci2:NeedComms:setActive] Stopped 2026-01-21T04:57:18.951Z,1768971438.951 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T04:57:19.366Z,1768971439.366 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T04:57:19.366Z,1768971439.366 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T04:57:19.367Z,1768971439.367 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T04:57:19.812Z,1768971439.812 [sci2:Lap:Dive] Stopped 2026-01-21T04:57:19.812Z,1768971439.812 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T04:57:19.812Z,1768971439.812 [sci2:Lap:Dive:A] Stopped 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T04:57:19.813Z,1768971439.813 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T04:57:19.814Z,1768971439.814 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T04:59:05.802Z,1768971545.802 [sci2:NeedComms:D] Running Loop=1 2026-01-21T04:59:06.500Z,1768971546.500 [Radio_Surface](INFO): Powering up 2026-01-21T04:59:12.388Z,1768971552.388 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T04:59:38.749Z,1768971578.749 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T04:59:38.832Z,1768971578.832 [NAL9602](ERROR): received: +CSQ:0 OK350, 2, 0, 0, 0 OK 2026-01-21T04:59:42.518Z,1768971582.518 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:00:13.640Z,1768971613.640 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:00:44.772Z,1768971644.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:01:15.900Z,1768971675.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:01:24.804Z,1768971684.804 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002922 2026-01-21T05:04:10.232Z,1768971850.232 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T05:04:19.121Z,1768971859.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050416.00,A,3656.22114,N,12215.23750,W,0.175,161.23,210126,,,D*79 2026-01-21T05:04:19.124Z,1768971859.124 [NAL9602](INFO): GPS fix at 20260121T050416: (36.937019, -122.253958) 2026-01-21T05:04:19.136Z,1768971859.136 [UniversalFixResidualReporter](INFO): Fix residual: 10.9 %DT, over the last 2837.8 m. Residual distance 309.6 m at bearing -24.1 degrees. Fix at (36.9370, -122.2540) with 3017.5 m made good. 2026-01-21T05:04:19.138Z,1768971859.138 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T05:04:19.140Z,1768971859.140 [sci2:NeedComms:D] Stopped 2026-01-21T05:04:19.141Z,1768971859.141 [sci2:NeedComms:E] Running Loop=1 2026-01-21T05:04:27.423Z,1768971867.423 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20260121T014546/Courier0006.lzma 2026-01-21T05:04:28.426Z,1768971868.426 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0006.lzma.bak 2026-01-21T05:04:28.427Z,1768971868.427 [DataOverHttps](INFO): SBD MOMSN=26583682 2026-01-21T05:04:40.326Z,1768971880.326 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T05:04:45.449Z,1768971885.449 [DataOverHttps](INFO): Sending 1106 bytes from file Logs/20260121T014546/Express0007.lzma 2026-01-21T05:04:46.446Z,1768971886.446 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0007.lzma.bak 2026-01-21T05:04:46.446Z,1768971886.446 [DataOverHttps](INFO): SBD MOMSN=26583685 2026-01-21T05:04:47.982Z,1768971887.982 [sci2:NeedComms:E] Stopped 2026-01-21T05:04:47.983Z,1768971887.983 [sci2:NeedComms:F] Running Loop=1 2026-01-21T05:04:49.975Z,1768971889.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050447.00,A,3656.21910,N,12215.23676,W,0.739,179.96,210126,,,D*7E 2026-01-21T05:04:49.977Z,1768971889.977 [NAL9602](INFO): GPS fix at 20260121T050447: (36.936985, -122.253946) 2026-01-21T05:04:50.001Z,1768971890.001 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T05:04:50.003Z,1768971890.003 [sci2:NeedComms:F] Stopped 2026-01-21T05:04:50.003Z,1768971890.003 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T05:04:50.008Z,1768971890.008 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T05:04:50.008Z,1768971890.008 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T05:04:50.419Z,1768971890.419 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T05:04:50.419Z,1768971890.419 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T05:04:50.419Z,1768971890.419 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T05:04:50.419Z,1768971890.419 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T05:04:50.419Z,1768971890.419 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T05:04:50.419Z,1768971890.419 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T05:04:50.420Z,1768971890.420 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T05:04:50.795Z,1768971890.795 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T05:04:50.795Z,1768971890.795 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T05:04:50.795Z,1768971890.795 [sci2:NeedComms:setInactive] Stopped 2026-01-21T05:04:50.795Z,1768971890.795 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T05:04:50.816Z,1768971890.816 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T05:04:50.816Z,1768971890.816 [sci2:NeedComms] Stopped 2026-01-21T05:04:50.816Z,1768971890.816 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T05:04:50.816Z,1768971890.816 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T05:04:50.816Z,1768971890.816 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:Lap:Dive] Running Loop=1 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T05:04:51.217Z,1768971891.217 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T05:04:51.218Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T05:04:51.218Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T05:04:51.218Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T05:04:51.218Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T05:04:51.218Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T05:04:51.218Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T05:04:51.219Z,1768971891.218 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T05:04:51.219Z,1768971891.219 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T05:04:51.219Z,1768971891.219 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T05:04:51.219Z,1768971891.219 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T05:04:51.219Z,1768971891.219 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T05:04:51.224Z,1768971891.224 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T05:04:51.224Z,1768971891.224 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T05:05:22.693Z,1768971922.693 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T05:06:18.704Z,1768971978.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:06:49.840Z,1768972009.840 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:06:51.596Z,1768972011.596 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T05:04:51.2Z 2026-01-21T05:06:51.596Z,1768972011.596 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T05:06:51.596Z,1768972011.596 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T05:06:51.596Z,1768972011.596 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T05:06:51.597Z,1768972011.597 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 5.211594 m 2026-01-21T05:06:51.597Z,1768972011.597 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T05:06:51.597Z,1768972011.597 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T05:06:51.597Z,1768972011.597 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T05:06:51.597Z,1768972011.597 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T05:06:51.988Z,1768972011.988 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T05:06:51.988Z,1768972011.988 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T05:06:51.989Z,1768972011.989 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T05:06:52.396Z,1768972012.396 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T05:06:52.396Z,1768972012.396 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T05:06:52.396Z,1768972012.396 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T05:06:52.397Z,1768972012.397 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T05:06:52.398Z,1768972012.398 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T05:06:52.399Z,1768972012.399 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T05:06:52.399Z,1768972012.399 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T05:06:52.399Z,1768972012.399 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T05:06:52.399Z,1768972012.399 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T05:06:52.401Z,1768972012.401 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T05:06:52.401Z,1768972012.401 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T05:06:52.401Z,1768972012.401 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T05:06:52.401Z,1768972012.401 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T05:06:52.401Z,1768972012.401 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T05:06:52.401Z,1768972012.401 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T05:06:55.331Z,1768972015.331 [Radio_Surface](INFO): Powering down 2026-01-21T05:07:20.968Z,1768972040.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T05:07:21.972Z,1768972041.972 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T05:08:29.242Z,1768972109.242 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.736481 2026-01-21T05:10:11.590Z,1768972211.590 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T05:14:17.766Z,1768972457.766 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T05:16:31.060Z,1768972591.060 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2026-01-21T05:19:31.657Z,1768972771.657 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2026-01-21T05:23:26.797Z,1768973006.797 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T05:23:26.802Z,1768973006.802 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T05:44:09.043Z,1768974249.043 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T05:45:41.996Z,1768974341.996 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T06:00:58.281Z,1768975258.281 [ThrusterHE](FAULT): Could not get status:serial timeout 2026-01-21T06:04:28.379Z,1768975468.379 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T06:04:28.382Z,1768975468.382 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T06:07:33.245Z,1768975653.245 [CTD_Seabird](ERROR): Failed to parse device response: .04 2026-01-21T06:34:50.214Z,1768977290.214 [sci2:NeedComms] Running Loop=1 2026-01-21T06:34:50.214Z,1768977290.214 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T06:34:50.214Z,1768977290.214 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T06:34:50.214Z,1768977290.214 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T06:34:50.215Z,1768977290.215 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T06:34:50.215Z,1768977290.215 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T06:34:50.216Z,1768977290.216 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T06:34:50.216Z,1768977290.216 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T06:34:50.216Z,1768977290.216 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T06:34:50.217Z,1768977290.217 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T06:34:50.217Z,1768977290.217 [sci2:NeedComms:A] Running Loop=1 2026-01-21T06:34:50.219Z,1768977290.219 [sci2:NeedComms:A](INFO): last time_fix was: 1768971887.000000 second since 1970/01/01T00:00:00Z 2026-01-21T06:34:50.219Z,1768977290.219 [sci2:NeedComms:A] Stopped 2026-01-21T06:34:50.219Z,1768977290.219 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T06:34:50.219Z,1768977290.219 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T06:34:50.219Z,1768977290.219 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T06:34:50.601Z,1768977290.601 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T06:34:50.601Z,1768977290.601 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T06:34:51.001Z,1768977291.001 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T06:34:51.001Z,1768977291.001 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T06:34:51.001Z,1768977291.001 [sci2:NeedComms:setActive] Stopped 2026-01-21T06:34:51.002Z,1768977291.002 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T06:34:51.408Z,1768977291.408 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T06:34:51.408Z,1768977291.408 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T06:34:51.408Z,1768977291.408 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T06:34:51.829Z,1768977291.829 [sci2:Lap:Dive] Stopped 2026-01-21T06:34:51.830Z,1768977291.830 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T06:34:51.830Z,1768977291.830 [sci2:Lap:Dive:A] Stopped 2026-01-21T06:34:51.830Z,1768977291.830 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T06:34:51.830Z,1768977291.830 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T06:34:51.830Z,1768977291.830 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T06:34:51.830Z,1768977291.830 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T06:34:51.831Z,1768977291.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T06:34:51.836Z,1768977291.836 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T06:34:51.836Z,1768977291.836 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T06:34:51.836Z,1768977291.836 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T06:34:51.836Z,1768977291.836 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T06:34:51.836Z,1768977291.836 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T06:34:51.836Z,1768977291.836 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T06:34:51.837Z,1768977291.837 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T06:36:19.718Z,1768977379.718 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T06:36:24.148Z,1768977384.148 [sci2:NeedComms:D] Running Loop=1 2026-01-21T06:36:24.872Z,1768977384.872 [Radio_Surface](INFO): Powering up 2026-01-21T06:36:30.944Z,1768977390.944 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T06:36:56.853Z,1768977416.853 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T06:36:56.936Z,1768977416.936 [NAL9602](ERROR): received: +CSQ:1 OK350, 2, 0, 0, 0 OK 2026-01-21T06:37:01.064Z,1768977421.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:37:32.192Z,1768977452.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:38:03.333Z,1768977483.333 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:38:34.456Z,1768977514.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:38:43.286Z,1768977523.286 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002870 2026-01-21T06:41:28.324Z,1768977688.324 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T06:41:38.041Z,1768977698.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064135.00,A,3655.22743,N,12213.47019,W,0.253,226.45,210126,,,D*74 2026-01-21T06:41:38.043Z,1768977698.043 [NAL9602](INFO): GPS fix at 20260121T064135: (36.920457, -122.224503) 2026-01-21T06:41:38.055Z,1768977698.055 [UniversalFixResidualReporter](INFO): Fix residual: 23.8 %DT, over the last 2823.7 m. Residual distance 672.3 m at bearing -9.5 degrees. Fix at (36.9205, -122.2245) with 3197.9 m made good. 2026-01-21T06:41:38.057Z,1768977698.057 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T06:41:38.059Z,1768977698.059 [sci2:NeedComms:D] Stopped 2026-01-21T06:41:38.060Z,1768977698.060 [sci2:NeedComms:E] Running Loop=1 2026-01-21T06:41:45.803Z,1768977705.803 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20260121T014546/Courier0009.lzma 2026-01-21T06:41:46.806Z,1768977706.806 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0009.lzma.bak 2026-01-21T06:41:46.806Z,1768977706.806 [DataOverHttps](INFO): SBD MOMSN=26583771 2026-01-21T06:42:03.793Z,1768977723.793 [DataOverHttps](INFO): Sending 1101 bytes from file Logs/20260121T014546/Express0010.lzma 2026-01-21T06:42:04.794Z,1768977724.794 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0010.lzma.bak 2026-01-21T06:42:04.794Z,1768977724.794 [DataOverHttps](INFO): SBD MOMSN=26583774 2026-01-21T06:42:06.318Z,1768977726.318 [sci2:NeedComms:E] Stopped 2026-01-21T06:42:06.319Z,1768977726.319 [sci2:NeedComms:F] Running Loop=1 2026-01-21T06:42:08.338Z,1768977728.338 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064205.00,A,3655.22431,N,12213.47155,W,1.011,220.51,210126,,,D*7D 2026-01-21T06:42:08.352Z,1768977728.352 [NAL9602](INFO): GPS fix at 20260121T064205: (36.920405, -122.224526) 2026-01-21T06:42:08.404Z,1768977728.404 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T06:42:08.407Z,1768977728.407 [sci2:NeedComms:F] Stopped 2026-01-21T06:42:08.407Z,1768977728.407 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T06:42:08.407Z,1768977728.407 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T06:42:08.407Z,1768977728.407 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T06:42:08.882Z,1768977728.882 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T06:42:08.882Z,1768977728.882 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T06:42:08.882Z,1768977728.882 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T06:42:08.882Z,1768977728.882 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T06:42:08.883Z,1768977728.883 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T06:42:08.883Z,1768977728.883 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T06:42:08.883Z,1768977728.883 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T06:42:09.206Z,1768977729.206 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T06:42:09.207Z,1768977729.207 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T06:42:09.207Z,1768977729.207 [sci2:NeedComms:setInactive] Stopped 2026-01-21T06:42:09.207Z,1768977729.207 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T06:42:09.232Z,1768977729.232 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T06:42:09.232Z,1768977729.232 [sci2:NeedComms] Stopped 2026-01-21T06:42:09.232Z,1768977729.232 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T06:42:09.232Z,1768977729.232 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T06:42:09.232Z,1768977729.232 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T06:42:09.597Z,1768977729.597 [sci2:Lap:Dive] Running Loop=1 2026-01-21T06:42:09.597Z,1768977729.597 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T06:42:09.597Z,1768977729.597 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T06:42:09.597Z,1768977729.597 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T06:42:09.598Z,1768977729.598 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T06:42:09.599Z,1768977729.599 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T06:42:09.599Z,1768977729.599 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T06:42:09.599Z,1768977729.599 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T06:42:09.599Z,1768977729.599 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T06:42:09.599Z,1768977729.599 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T06:42:09.604Z,1768977729.604 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T06:42:09.604Z,1768977729.604 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T06:42:09.604Z,1768977729.604 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T06:42:40.673Z,1768977760.673 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T06:43:37.260Z,1768977817.260 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:44:08.384Z,1768977848.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:44:09.948Z,1768977849.948 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T06:42:09.6Z 2026-01-21T06:44:09.948Z,1768977849.948 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T06:44:09.948Z,1768977849.948 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T06:44:09.949Z,1768977849.949 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T06:44:09.949Z,1768977849.949 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 5.399627 m 2026-01-21T06:44:09.949Z,1768977849.949 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T06:44:09.949Z,1768977849.949 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T06:44:09.949Z,1768977849.949 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T06:44:09.949Z,1768977849.949 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T06:44:10.366Z,1768977850.366 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T06:44:10.366Z,1768977850.366 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T06:44:10.366Z,1768977850.366 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T06:44:10.366Z,1768977850.366 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T06:44:10.366Z,1768977850.366 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T06:44:10.367Z,1768977850.367 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T06:44:10.367Z,1768977850.367 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T06:44:10.367Z,1768977850.367 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T06:44:10.367Z,1768977850.367 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T06:44:10.759Z,1768977850.759 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T06:44:10.776Z,1768977850.776 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T06:44:10.777Z,1768977850.777 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T06:44:10.778Z,1768977850.778 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T06:44:10.779Z,1768977850.779 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T06:44:10.779Z,1768977850.779 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T06:44:10.779Z,1768977850.779 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T06:44:10.784Z,1768977850.784 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T06:44:10.784Z,1768977850.784 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T06:44:10.784Z,1768977850.784 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T06:44:13.120Z,1768977853.120 [Radio_Surface](INFO): Powering down 2026-01-21T06:44:39.516Z,1768977879.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T06:44:40.521Z,1768977880.521 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T06:44:47.489Z,1768977887.489 [CTD_Seabird](ERROR): Failed to parse device response: 6.11.95 2026-01-21T06:45:30.034Z,1768977930.034 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T06:45:30.038Z,1768977930.038 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T06:45:45.780Z,1768977945.780 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731110 2026-01-21T06:58:11.414Z,1768978691.414 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T07:08:02.309Z,1768979282.309 [CTD_Seabird](ERROR): Failed to parse device response: 41.15,1 2026-01-21T07:17:21.976Z,1768979841.976 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T07:26:31.871Z,1768980391.871 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T07:26:31.874Z,1768980391.874 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T07:38:11.827Z,1768981091.827 [RudderServo](ERROR): getPosition uart error serial timeout 2026-01-21T07:38:11.827Z,1768981091.827 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2026-01-21T07:38:11.827Z,1768981091.827 [RudderServo] Communications Fault, FailCount= 1 2026-01-21T07:38:11.827Z,1768981091.827 [RudderServo](ERROR): Communications Fault 2026-01-21T07:38:11.903Z,1768981091.903 [CBIT](ERROR): Communications Fault in component: RudderServo 2026-01-21T07:38:12.135Z,1768981092.135 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2026-01-21T07:38:12.136Z,1768981092.136 [RudderServo](INFO): Powering down 2026-01-21T07:38:12.951Z,1768981092.951 [RudderServo](DEBUG): Initializing EZServoServo. 2026-01-21T07:38:13.065Z,1768981093.065 [RudderServo](DEBUG): Initializing RudderServo. 2026-01-21T07:38:13.087Z,1768981093.087 [CBIT](INFO): Clearing failed state for component RudderServo 2026-01-21T07:38:13.087Z,1768981093.087 [RudderServo] No Fault, FailCount= 1 2026-01-21T07:48:46.229Z,1768981726.229 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T07:57:20.645Z,1768982240.645 [CTD_Seabird](ERROR): Failed to parse device response: .61 2026-01-21T07:58:23.264Z,1768982303.264 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T08:07:33.338Z,1768982853.338 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T08:07:33.341Z,1768982853.341 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T08:08:39.473Z,1768982919.473 [CTD_Seabird](ERROR): Failed to parse device response: ,11.2672,3.78769,2556.14 2026-01-21T08:12:08.535Z,1768983128.535 [sci2:NeedComms] Running Loop=1 2026-01-21T08:12:08.536Z,1768983128.536 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T08:12:08.536Z,1768983128.536 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T08:12:08.536Z,1768983128.536 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T08:12:08.536Z,1768983128.536 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T08:12:08.537Z,1768983128.537 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T08:12:08.537Z,1768983128.537 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T08:12:08.537Z,1768983128.537 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T08:12:08.538Z,1768983128.538 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T08:12:08.538Z,1768983128.538 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T08:12:08.539Z,1768983128.539 [sci2:NeedComms:A] Running Loop=1 2026-01-21T08:12:08.540Z,1768983128.540 [sci2:NeedComms:A](INFO): last time_fix was: 1768977725.000000 second since 1970/01/01T00:00:00Z 2026-01-21T08:12:08.540Z,1768983128.540 [sci2:NeedComms:A] Stopped 2026-01-21T08:12:08.541Z,1768983128.541 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T08:12:08.541Z,1768983128.541 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T08:12:08.541Z,1768983128.541 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T08:12:08.920Z,1768983128.920 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T08:12:08.920Z,1768983128.920 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T08:12:09.524Z,1768983129.524 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T08:12:09.524Z,1768983129.524 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T08:12:09.524Z,1768983129.524 [sci2:NeedComms:setActive] Stopped 2026-01-21T08:12:09.524Z,1768983129.524 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T08:12:09.735Z,1768983129.735 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T08:12:09.735Z,1768983129.735 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T08:12:09.735Z,1768983129.735 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T08:12:10.126Z,1768983130.126 [sci2:Lap:Dive] Stopped 2026-01-21T08:12:10.126Z,1768983130.126 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T08:12:10.126Z,1768983130.126 [sci2:Lap:Dive:A] Stopped 2026-01-21T08:12:10.126Z,1768983130.126 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T08:12:10.127Z,1768983130.127 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T08:12:10.128Z,1768983130.128 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T08:14:34.030Z,1768983274.030 [sci2:NeedComms:D] Running Loop=1 2026-01-21T08:14:34.516Z,1768983274.516 [Radio_Surface](INFO): Powering up 2026-01-21T08:14:39.884Z,1768983279.884 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T08:15:06.723Z,1768983306.723 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T08:15:06.796Z,1768983306.796 [NAL9602](ERROR): received: +CSQ:0 OK350, 2, 0, 0, 0 OK 2026-01-21T08:15:10.004Z,1768983310.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:15:38.638Z,1768983338.638 [NAL9602](INFO): SBD MO Status=2, MOMSN=25350, MT Status=2, MTMSN=0 2026-01-21T08:15:38.638Z,1768983338.638 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T08:15:41.128Z,1768983341.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:15:54.795Z,1768983354.795 [NAL9602](INFO): SBD MO Status=2, MOMSN=25350, MT Status=2, MTMSN=0 2026-01-21T08:15:54.795Z,1768983354.795 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T08:16:12.252Z,1768983372.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:16:43.376Z,1768983403.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:16:49.334Z,1768983409.334 [NAL9602](INFO): SBD MO Status=0, MOMSN=25350, MT Status=0, MTMSN=0 2026-01-21T08:16:49.335Z,1768983409.335 [NAL9602](INFO): No messages in MT queue 2026-01-21T08:16:50.556Z,1768983410.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081647.00,A,3654.20660,N,12211.89696,W,0.914,188.88,210126,,,A*74 2026-01-21T08:16:50.558Z,1768983410.558 [NAL9602](INFO): GPS fix at 20260121T081647: (36.903443, -122.198283) 2026-01-21T08:16:50.571Z,1768983410.571 [UniversalFixResidualReporter](INFO): Fix residual: 27.6 %DT, over the last 2865.2 m. Residual distance 791.0 m at bearing 18.1 degrees. Fix at (36.9034, -122.1983) with 3000.2 m made good. 2026-01-21T08:16:50.589Z,1768983410.589 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T08:16:50.596Z,1768983410.596 [sci2:NeedComms:D] Stopped 2026-01-21T08:16:50.596Z,1768983410.596 [sci2:NeedComms:E] Running Loop=1 2026-01-21T08:16:52.104Z,1768983412.104 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002861 2026-01-21T08:17:00.697Z,1768983420.697 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20260121T014546/Courier0012.lzma 2026-01-21T08:17:01.698Z,1768983421.698 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0012.lzma.bak 2026-01-21T08:17:01.698Z,1768983421.698 [DataOverHttps](INFO): SBD MOMSN=26583867 2026-01-21T08:17:18.233Z,1768983438.233 [DataOverHttps](INFO): Sending 1171 bytes from file Logs/20260121T014546/Express0013.lzma 2026-01-21T08:17:19.234Z,1768983439.234 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0013.lzma.bak 2026-01-21T08:17:19.234Z,1768983439.234 [DataOverHttps](INFO): SBD MOMSN=26583871 2026-01-21T08:17:19.930Z,1768983439.930 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T08:17:19.930Z,1768983439.930 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T08:17:20.753Z,1768983440.753 [sci2:NeedComms:E] Stopped 2026-01-21T08:17:20.753Z,1768983440.753 [sci2:NeedComms:F] Running Loop=1 2026-01-21T08:17:22.763Z,1768983442.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081719.00,A,3654.20340,N,12211.90062,W,0.233,207.25,210126,,,A*71 2026-01-21T08:17:22.769Z,1768983442.769 [NAL9602](INFO): GPS fix at 20260121T081719: (36.903390, -122.198344) 2026-01-21T08:17:22.794Z,1768983442.794 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T08:17:22.826Z,1768983442.826 [sci2:NeedComms:F] Stopped 2026-01-21T08:17:22.826Z,1768983442.826 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T08:17:22.826Z,1768983442.826 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T08:17:22.826Z,1768983442.826 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T08:17:23.207Z,1768983443.207 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T08:17:23.216Z,1768983443.216 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T08:17:23.216Z,1768983443.216 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T08:17:23.216Z,1768983443.216 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T08:17:23.216Z,1768983443.216 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T08:17:23.216Z,1768983443.216 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T08:17:23.216Z,1768983443.216 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T08:17:23.587Z,1768983443.587 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T08:17:23.587Z,1768983443.587 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T08:17:23.587Z,1768983443.587 [sci2:NeedComms:setInactive] Stopped 2026-01-21T08:17:23.587Z,1768983443.587 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T08:17:23.608Z,1768983443.608 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T08:17:23.608Z,1768983443.608 [sci2:NeedComms] Stopped 2026-01-21T08:17:23.608Z,1768983443.608 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T08:17:23.608Z,1768983443.608 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T08:17:23.608Z,1768983443.608 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T08:17:24.001Z,1768983444.001 [sci2:Lap:Dive] Running Loop=1 2026-01-21T08:17:24.001Z,1768983444.001 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T08:17:24.001Z,1768983444.001 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T08:17:24.001Z,1768983444.001 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T08:17:24.001Z,1768983444.001 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T08:17:24.001Z,1768983444.001 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T08:17:24.002Z,1768983444.002 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T08:17:24.003Z,1768983444.003 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T08:17:24.003Z,1768983444.003 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T08:17:24.003Z,1768983444.003 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T08:17:24.003Z,1768983444.003 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T08:17:24.040Z,1768983444.040 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T08:17:24.040Z,1768983444.040 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T08:17:24.040Z,1768983444.040 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T08:17:55.465Z,1768983475.465 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T08:18:50.772Z,1768983530.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:19:21.900Z,1768983561.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:19:24.037Z,1768983564.037 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T08:17:24.0Z 2026-01-21T08:19:24.037Z,1768983564.037 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T08:19:24.038Z,1768983564.038 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T08:19:24.038Z,1768983564.038 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T08:19:24.038Z,1768983564.038 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 5.413547 m 2026-01-21T08:19:24.039Z,1768983564.039 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T08:19:24.039Z,1768983564.039 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T08:19:24.039Z,1768983564.039 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T08:19:24.039Z,1768983564.039 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T08:19:24.412Z,1768983564.412 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T08:19:24.413Z,1768983564.413 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T08:19:24.414Z,1768983564.414 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T08:19:24.829Z,1768983564.829 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T08:19:24.829Z,1768983564.829 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T08:19:24.829Z,1768983564.829 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T08:19:24.829Z,1768983564.829 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T08:19:24.829Z,1768983564.829 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T08:19:24.829Z,1768983564.829 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T08:19:24.830Z,1768983564.830 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T08:19:24.831Z,1768983564.831 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T08:19:24.832Z,1768983564.832 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T08:19:24.833Z,1768983564.833 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T08:19:24.834Z,1768983564.834 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T08:19:24.834Z,1768983564.834 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T08:19:24.834Z,1768983564.834 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T08:19:24.834Z,1768983564.834 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T08:19:24.834Z,1768983564.834 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T08:19:28.240Z,1768983568.240 [Radio_Surface](INFO): Powering down 2026-01-21T08:19:53.025Z,1768983593.025 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T08:19:54.028Z,1768983594.028 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T08:20:59.288Z,1768983659.288 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738724 2026-01-21T08:29:47.979Z,1768984187.979 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T08:47:47.017Z,1768985267.017 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T08:48:35.140Z,1768985315.140 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T08:48:35.143Z,1768985315.143 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T08:59:35.177Z,1768985975.177 [CTD_Seabird](ERROR): Failed to parse device response: .71 2026-01-21T09:10:50.406Z,1768986650.406 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T09:14:23.789Z,1768986863.789 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T09:14:25.864Z,1768986865.864 [CTD_Seabird](ERROR): Failed to parse device response: 13.75,14.190400 2026-01-21T09:20:27.254Z,1768987227.254 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T09:26:17.601Z,1768987577.601 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-01-21T09:26:17.824Z,1768987577.824 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T09:26:17.826Z,1768987577.826 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T09:26:18.820Z,1768987578.820 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T09:26:18.821Z,1768987578.821 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T09:26:19.820Z,1768987579.820 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T09:26:19.821Z,1768987579.821 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T09:26:20.816Z,1768987580.816 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T09:26:20.817Z,1768987580.817 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T09:26:21.816Z,1768987581.816 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid. 2026-01-21T09:26:21.817Z,1768987581.817 [CTD_Seabird](INFO): some bad data, not updating bins 2026-01-21T09:26:22.031Z,1768987582.031 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.001497 CHAN A1 (24V): 0.005569 CHAN A2 (12V): -0.012455 CHAN A3 (5V): -0.013213 CHAN B0 (3.3V): -0.004003 CHAN B1 (3.15aV): -0.002108 CHAN B2 (3.15bV): -0.001674 CHAN B3 (GND): -0.002039 OPEN: 0.012407 Full Scale: +/- 1 mA 2026-01-21T09:29:36.748Z,1768987776.748 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T09:29:36.752Z,1768987776.752 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T09:43:53.977Z,1768988633.977 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2026-01-21T09:47:22.882Z,1768988842.882 [sci2:NeedComms] Running Loop=1 2026-01-21T09:47:22.883Z,1768988842.883 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T09:47:22.883Z,1768988842.883 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T09:47:22.883Z,1768988842.883 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T09:47:22.883Z,1768988842.883 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T09:47:22.883Z,1768988842.883 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T09:47:22.884Z,1768988842.884 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T09:47:22.884Z,1768988842.884 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T09:47:22.885Z,1768988842.885 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T09:47:22.885Z,1768988842.885 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T09:47:22.885Z,1768988842.885 [sci2:NeedComms:A] Running Loop=1 2026-01-21T09:47:22.887Z,1768988842.887 [sci2:NeedComms:A](INFO): last time_fix was: 1768983439.000000 second since 1970/01/01T00:00:00Z 2026-01-21T09:47:22.887Z,1768988842.887 [sci2:NeedComms:A] Stopped 2026-01-21T09:47:22.887Z,1768988842.887 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T09:47:22.887Z,1768988842.887 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T09:47:22.887Z,1768988842.887 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T09:47:23.296Z,1768988843.296 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T09:47:23.297Z,1768988843.297 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T09:47:23.707Z,1768988843.707 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T09:47:23.708Z,1768988843.708 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T09:47:23.708Z,1768988843.708 [sci2:NeedComms:setActive] Stopped 2026-01-21T09:47:23.708Z,1768988843.708 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T09:47:24.097Z,1768988844.097 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T09:47:24.098Z,1768988844.098 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T09:47:24.098Z,1768988844.098 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T09:47:24.496Z,1768988844.496 [sci2:Lap:Dive] Stopped 2026-01-21T09:47:24.496Z,1768988844.496 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T09:47:24.496Z,1768988844.496 [sci2:Lap:Dive:A] Stopped 2026-01-21T09:47:24.496Z,1768988844.496 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T09:47:24.496Z,1768988844.496 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T09:47:24.497Z,1768988844.497 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T09:47:24.498Z,1768988844.498 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T09:47:24.498Z,1768988844.498 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T09:47:24.498Z,1768988844.498 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T09:47:24.498Z,1768988844.498 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T09:47:24.498Z,1768988844.498 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T09:47:24.498Z,1768988844.498 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T09:50:48.187Z,1768989048.187 [sci2:NeedComms:D] Running Loop=1 2026-01-21T09:50:48.784Z,1768989048.784 [Radio_Surface](INFO): Powering up 2026-01-21T09:50:54.716Z,1768989054.716 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T09:51:32.703Z,1768989092.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T09:51:32.704Z,1768989092.704 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T09:51:50.462Z,1768989110.462 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T09:51:50.462Z,1768989110.462 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T09:52:07.849Z,1768989127.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T09:52:07.849Z,1768989127.849 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T09:52:58.012Z,1768989178.012 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.010969 2026-01-21T09:54:19.761Z,1768989259.761 [CTD_Seabird](ERROR): Failed to parse device response: ,14.0472,4.00290,4110.24 2026-01-21T09:55:52.484Z,1768989352.484 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T09:56:00.970Z,1768989360.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,095558.00,A,3653.34851,N,12210.42738,W,0.622,176.10,210126,,,D*7E 2026-01-21T09:56:00.973Z,1768989360.973 [NAL9602](INFO): GPS fix at 20260121T095558: (36.889142, -122.173790) 2026-01-21T09:56:01.005Z,1768989361.005 [UniversalFixResidualReporter](INFO): Fix residual: 24.0 %DT, over the last 2914.9 m. Residual distance 698.5 m at bearing 42.9 degrees. Fix at (36.8891, -122.1738) with 2697.7 m made good. 2026-01-21T09:56:01.007Z,1768989361.007 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T09:56:01.013Z,1768989361.013 [sci2:NeedComms:D] Stopped 2026-01-21T09:56:01.014Z,1768989361.014 [sci2:NeedComms:E] Running Loop=1 2026-01-21T09:56:08.327Z,1768989368.327 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20260121T014546/Courier0015.lzma 2026-01-21T09:56:09.330Z,1768989369.330 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0015.lzma.bak 2026-01-21T09:56:09.330Z,1768989369.330 [DataOverHttps](INFO): SBD MOMSN=26583941 2026-01-21T09:56:26.254Z,1768989386.254 [DataOverHttps](INFO): Sending 1404 bytes from file Logs/20260121T014546/Express0016.lzma 2026-01-21T09:56:27.254Z,1768989387.254 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0016.lzma.bak 2026-01-21T09:56:27.254Z,1768989387.254 [DataOverHttps](INFO): SBD MOMSN=26583944 2026-01-21T09:56:28.603Z,1768989388.603 [sci2:NeedComms:E] Stopped 2026-01-21T09:56:28.603Z,1768989388.603 [sci2:NeedComms:F] Running Loop=1 2026-01-21T09:56:30.621Z,1768989390.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,095627.00,A,3653.34752,N,12210.42927,W,0.525,282.22,210126,,,D*74 2026-01-21T09:56:30.628Z,1768989390.628 [NAL9602](INFO): GPS fix at 20260121T095627: (36.889125, -122.173821) 2026-01-21T09:56:30.663Z,1768989390.663 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T09:56:30.674Z,1768989390.674 [sci2:NeedComms:F] Stopped 2026-01-21T09:56:30.674Z,1768989390.674 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T09:56:30.674Z,1768989390.674 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T09:56:30.674Z,1768989390.674 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T09:56:31.063Z,1768989391.063 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T09:56:31.063Z,1768989391.063 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T09:56:31.063Z,1768989391.063 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T09:56:31.063Z,1768989391.063 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T09:56:31.063Z,1768989391.063 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T09:56:31.063Z,1768989391.063 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T09:56:31.088Z,1768989391.088 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T09:56:31.462Z,1768989391.462 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T09:56:31.462Z,1768989391.462 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T09:56:31.462Z,1768989391.462 [sci2:NeedComms:setInactive] Stopped 2026-01-21T09:56:31.462Z,1768989391.462 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T09:56:31.463Z,1768989391.463 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T09:56:31.463Z,1768989391.463 [sci2:NeedComms] Stopped 2026-01-21T09:56:31.463Z,1768989391.463 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T09:56:31.463Z,1768989391.463 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T09:56:31.463Z,1768989391.463 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T09:56:31.878Z,1768989391.878 [sci2:Lap:Dive] Running Loop=1 2026-01-21T09:56:31.878Z,1768989391.878 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T09:56:31.878Z,1768989391.878 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T09:56:31.878Z,1768989391.878 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T09:56:31.878Z,1768989391.878 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T09:56:31.878Z,1768989391.878 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T09:56:31.879Z,1768989391.879 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T09:56:31.888Z,1768989391.888 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T09:56:31.888Z,1768989391.888 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T09:56:31.888Z,1768989391.888 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T09:56:31.888Z,1768989391.888 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T09:56:31.892Z,1768989391.892 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T09:56:31.892Z,1768989391.892 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T09:56:31.892Z,1768989391.892 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T09:57:02.601Z,1768989422.601 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T09:58:31.472Z,1768989511.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T09:58:31.906Z,1768989511.906 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T09:56:31.9Z 2026-01-21T09:58:31.906Z,1768989511.906 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T09:58:31.906Z,1768989511.906 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T09:58:31.907Z,1768989511.907 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T09:58:31.907Z,1768989511.907 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 4.979611 m 2026-01-21T09:58:31.907Z,1768989511.907 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T09:58:31.907Z,1768989511.907 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T09:58:31.907Z,1768989511.907 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T09:58:31.907Z,1768989511.907 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T09:58:32.316Z,1768989512.316 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T09:58:32.317Z,1768989512.317 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T09:58:32.318Z,1768989512.318 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T09:58:32.723Z,1768989512.723 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T09:58:32.724Z,1768989512.724 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T09:58:32.725Z,1768989512.725 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T09:58:32.726Z,1768989512.726 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T09:58:32.728Z,1768989512.728 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T09:58:32.728Z,1768989512.728 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T09:58:32.728Z,1768989512.728 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T09:58:32.729Z,1768989512.729 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T09:58:32.729Z,1768989512.729 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T09:58:32.729Z,1768989512.729 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T09:58:46.756Z,1768989526.756 [Radio_Surface](INFO): Powering down 2026-01-21T09:59:02.596Z,1768989542.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T09:59:03.600Z,1768989543.600 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T10:00:07.872Z,1768989607.872 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.595215 2026-01-21T10:01:29.247Z,1768989689.247 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T10:10:38.508Z,1768990238.508 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T10:10:38.511Z,1768990238.511 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T10:33:23.569Z,1768991603.569 [CTD_Seabird](ERROR): Failed to parse device response: ,13.2495,3.94352,3287.01 2026-01-21T10:42:30.738Z,1768992150.738 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T10:51:40.452Z,1768992700.452 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T10:51:40.456Z,1768992700.456 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T11:09:09.626Z,1768993749.626 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T11:13:54.890Z,1768994034.890 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T11:15:14.020Z,1768994114.020 [CTD_Seabird](ERROR): Failed to parse device response: 2.73,14.061.29 2026-01-21T11:23:31.832Z,1768994611.832 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T11:25:05.466Z,1768994705.466 [CTD_Seabird](ERROR): Failed to parse device response: 69.41,11.6225,3.625.80 2026-01-21T11:26:30.839Z,1768994790.839 [sci2:NeedComms] Running Loop=1 2026-01-21T11:26:30.840Z,1768994790.840 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T11:26:30.840Z,1768994790.840 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T11:26:30.840Z,1768994790.840 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T11:26:30.840Z,1768994790.840 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T11:26:30.841Z,1768994790.841 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T11:26:30.841Z,1768994790.841 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T11:26:30.842Z,1768994790.842 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T11:26:30.842Z,1768994790.842 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T11:26:30.842Z,1768994790.842 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T11:26:30.843Z,1768994790.843 [sci2:NeedComms:A] Running Loop=1 2026-01-21T11:26:30.844Z,1768994790.844 [sci2:NeedComms:A](INFO): last time_fix was: 1768989387.000000 second since 1970/01/01T00:00:00Z 2026-01-21T11:26:30.845Z,1768994790.845 [sci2:NeedComms:A] Stopped 2026-01-21T11:26:30.845Z,1768994790.845 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T11:26:30.845Z,1768994790.845 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T11:26:30.845Z,1768994790.845 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T11:26:31.247Z,1768994791.247 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T11:26:31.247Z,1768994791.247 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T11:26:31.654Z,1768994791.654 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T11:26:31.655Z,1768994791.655 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T11:26:31.655Z,1768994791.655 [sci2:NeedComms:setActive] Stopped 2026-01-21T11:26:31.655Z,1768994791.655 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T11:26:32.045Z,1768994792.045 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T11:26:32.046Z,1768994792.046 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T11:26:32.046Z,1768994792.046 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T11:26:32.450Z,1768994792.450 [sci2:Lap:Dive] Stopped 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:Lap:Dive:A] Stopped 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T11:26:32.451Z,1768994792.451 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T11:26:32.452Z,1768994792.452 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T11:26:32.453Z,1768994792.453 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T11:26:32.453Z,1768994792.453 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T11:26:32.453Z,1768994792.453 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T11:26:32.453Z,1768994792.453 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T11:29:55.935Z,1768994995.935 [sci2:NeedComms:D] Running Loop=1 2026-01-21T11:29:56.416Z,1768994996.416 [Radio_Surface](INFO): Powering up 2026-01-21T11:30:02.152Z,1768995002.152 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T11:30:28.885Z,1768995028.885 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T11:30:28.968Z,1768995028.968 [NAL9602](ERROR): received: +CSQ:0 OK351, 2, 0, 0, 0 OK 2026-01-21T11:30:32.276Z,1768995032.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T11:30:51.506Z,1768995051.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T11:30:51.507Z,1768995051.507 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T11:31:03.416Z,1768995063.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T11:31:34.540Z,1768995094.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T11:32:05.668Z,1768995125.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T11:32:14.560Z,1768995134.560 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002932 2026-01-21T11:32:42.198Z,1768995162.198 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T11:32:42.202Z,1768995162.202 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T11:35:00.369Z,1768995300.369 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T11:35:05.218Z,1768995305.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113502.00,A,3652.75777,N,12209.01214,W,0.894,254.58,210126,,,D*79 2026-01-21T11:35:05.221Z,1768995305.221 [NAL9602](INFO): GPS fix at 20260121T113502: (36.879296, -122.150202) 2026-01-21T11:35:05.232Z,1768995305.232 [UniversalFixResidualReporter](INFO): Fix residual: 20.5 %DT, over the last 2916.8 m. Residual distance 596.9 m at bearing 81.4 degrees. Fix at (36.8793, -122.1502) with 2368.0 m made good. 2026-01-21T11:35:05.234Z,1768995305.234 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T11:35:05.237Z,1768995305.237 [sci2:NeedComms:D] Stopped 2026-01-21T11:35:05.237Z,1768995305.237 [sci2:NeedComms:E] Running Loop=1 2026-01-21T11:35:13.183Z,1768995313.183 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20260121T014546/Courier0018.lzma 2026-01-21T11:35:14.186Z,1768995314.186 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0018.lzma.bak 2026-01-21T11:35:14.186Z,1768995314.186 [DataOverHttps](INFO): SBD MOMSN=26584053 2026-01-21T11:35:30.697Z,1768995330.697 [DataOverHttps](INFO): Sending 1158 bytes from file Logs/20260121T014546/Express0019.lzma 2026-01-21T11:35:31.698Z,1768995331.698 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0019.lzma.bak 2026-01-21T11:35:31.698Z,1768995331.698 [DataOverHttps](INFO): SBD MOMSN=26584056 2026-01-21T11:35:33.264Z,1768995333.264 [sci2:NeedComms:E] Stopped 2026-01-21T11:35:33.264Z,1768995333.264 [sci2:NeedComms:F] Running Loop=1 2026-01-21T11:35:35.187Z,1768995335.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113532.00,A,3652.75768,N,12209.01628,W,0.233,289.37,210126,,,D*71 2026-01-21T11:35:35.189Z,1768995335.189 [NAL9602](INFO): GPS fix at 20260121T113532: (36.879295, -122.150271) 2026-01-21T11:35:35.225Z,1768995335.225 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T11:35:35.227Z,1768995335.227 [sci2:NeedComms:F] Stopped 2026-01-21T11:35:35.227Z,1768995335.227 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T11:35:35.232Z,1768995335.232 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T11:35:35.232Z,1768995335.232 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T11:35:35.643Z,1768995335.643 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T11:35:35.643Z,1768995335.643 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T11:35:35.643Z,1768995335.643 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T11:35:35.643Z,1768995335.643 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T11:35:35.643Z,1768995335.643 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T11:35:35.643Z,1768995335.643 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T11:35:35.648Z,1768995335.648 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T11:35:36.015Z,1768995336.015 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T11:35:36.015Z,1768995336.015 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T11:35:36.015Z,1768995336.015 [sci2:NeedComms:setInactive] Stopped 2026-01-21T11:35:36.015Z,1768995336.015 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T11:35:36.020Z,1768995336.020 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T11:35:36.020Z,1768995336.020 [sci2:NeedComms] Stopped 2026-01-21T11:35:36.020Z,1768995336.020 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T11:35:36.020Z,1768995336.020 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T11:35:36.021Z,1768995336.021 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T11:35:36.429Z,1768995336.429 [sci2:Lap:Dive] Running Loop=1 2026-01-21T11:35:36.429Z,1768995336.429 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T11:35:36.429Z,1768995336.429 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T11:35:36.429Z,1768995336.429 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T11:35:36.430Z,1768995336.430 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T11:35:36.431Z,1768995336.431 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T11:35:36.431Z,1768995336.431 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T11:35:36.431Z,1768995336.431 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T11:35:36.431Z,1768995336.431 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T11:35:36.436Z,1768995336.436 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T11:35:36.436Z,1768995336.436 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T11:35:36.436Z,1768995336.436 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T11:36:07.505Z,1768995367.505 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T11:36:55.438Z,1768995415.438 [CTD_Seabird](ERROR): Failed to parse device response: 1.17,13.9962,3.99574, 2026-01-21T11:37:33.701Z,1768995453.701 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T11:37:36.809Z,1768995456.809 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T11:35:36.4Z 2026-01-21T11:37:36.809Z,1768995456.809 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T11:37:36.809Z,1768995456.809 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T11:37:36.809Z,1768995456.809 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T11:37:36.810Z,1768995456.810 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 4.653822 m 2026-01-21T11:37:36.810Z,1768995456.810 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T11:37:36.810Z,1768995456.810 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T11:37:36.810Z,1768995456.810 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T11:37:36.810Z,1768995456.810 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T11:37:37.214Z,1768995457.214 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T11:37:37.214Z,1768995457.214 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T11:37:37.214Z,1768995457.214 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T11:37:37.214Z,1768995457.214 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T11:37:37.214Z,1768995457.214 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T11:37:37.215Z,1768995457.215 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T11:37:37.215Z,1768995457.215 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T11:37:37.215Z,1768995457.215 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T11:37:37.215Z,1768995457.215 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T11:37:37.611Z,1768995457.611 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T11:37:37.629Z,1768995457.629 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T11:37:37.630Z,1768995457.630 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T11:37:37.631Z,1768995457.631 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T11:37:37.638Z,1768995457.638 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T11:37:37.638Z,1768995457.638 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T11:37:37.638Z,1768995457.638 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T11:37:37.638Z,1768995457.638 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T11:37:37.638Z,1768995457.638 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T11:37:37.638Z,1768995457.638 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T11:37:53.168Z,1768995473.168 [Radio_Surface](INFO): Powering down 2026-01-21T11:38:04.828Z,1768995484.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T11:38:05.832Z,1768995485.832 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T11:39:43.232Z,1768995583.232 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739105 2026-01-21T11:43:25.025Z,1768995805.025 [CTD_Seabird](ERROR): Failed to parse device response: .03 2026-01-21T11:44:03.177Z,1768995843.177 [CTD_Seabird](ERROR): Failed to parse device response: ,13.4356,3.95948,3340.41 2026-01-21T11:46:31.053Z,1768995991.053 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T11:59:27.082Z,1768996767.082 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T12:04:33.819Z,1768997073.819 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T12:12:58.051Z,1768997578.051 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2026-01-21T12:13:43.707Z,1768997623.707 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T12:13:43.711Z,1768997623.711 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T12:30:03.694Z,1768998603.694 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2026-01-21T12:30:03.695Z,1768998603.695 [ThrusterHE](INFO): Powering down 2026-01-21T12:30:04.476Z,1768998604.476 [ThrusterHE](DEBUG): Initializing EZServoServo. 2026-01-21T12:30:04.597Z,1768998604.597 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2026-01-21T12:45:36.046Z,1768999536.046 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T12:54:45.383Z,1769000085.383 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T12:54:45.401Z,1769000085.401 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T13:05:35.506Z,1769000735.506 [sci2:NeedComms] Running Loop=1 2026-01-21T13:05:35.507Z,1769000735.507 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms 2026-01-21T13:05:35.507Z,1769000735.507 [sci2:NeedComms:C.GoToSurface] Running Loop=1 2026-01-21T13:05:35.507Z,1769000735.507 [sci2:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-21T13:05:35.507Z,1769000735.507 [sci2:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s. 2026-01-21T13:05:35.507Z,1769000735.507 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2026-01-21T13:05:35.512Z,1769000735.512 [sci2:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2026-01-21T13:05:35.512Z,1769000735.512 [sci2:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds. 2026-01-21T13:05:35.513Z,1769000735.513 [sci2:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-21T13:05:35.513Z,1769000735.513 [sci2:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-21T13:05:35.514Z,1769000735.514 [sci2:NeedComms:A] Running Loop=1 2026-01-21T13:05:35.515Z,1769000735.515 [sci2:NeedComms:A](INFO): last time_fix was: 1768995332.000000 second since 1970/01/01T00:00:00Z 2026-01-21T13:05:35.515Z,1769000735.515 [sci2:NeedComms:A] Stopped 2026-01-21T13:05:35.515Z,1769000735.515 [sci2:NeedComms:setActive] Running Loop=1 2026-01-21T13:05:35.515Z,1769000735.515 [sci2:NeedComms:setActive](DEBUG): Aggregate::initialize sci2:NeedComms:setActive 2026-01-21T13:05:35.516Z,1769000735.516 [sci2:NeedComms:setActive:A] Running Loop=1 2026-01-21T13:05:35.911Z,1769000735.911 [sci2:NeedComms:setActive:A] Stopped 2026-01-21T13:05:35.911Z,1769000735.911 [sci2:NeedComms:setActive:B] Running Loop=1 2026-01-21T13:05:36.301Z,1769000736.301 [sci2:NeedComms:setActive:B] Stopped 2026-01-21T13:05:36.301Z,1769000736.301 [sci2:NeedComms:setActive](INFO): Completed sci2:NeedComms:setActive 2026-01-21T13:05:36.302Z,1769000736.302 [sci2:NeedComms:setActive] Stopped 2026-01-21T13:05:36.302Z,1769000736.302 [sci2:NeedComms:setActive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setActive 2026-01-21T13:05:36.701Z,1769000736.701 [sci2:manageDiveMode:syncWhileActive] Running Loop=1 2026-01-21T13:05:36.702Z,1769000736.702 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::initialize sci2:manageDiveMode:syncWhileActive 2026-01-21T13:05:36.702Z,1769000736.702 [sci2:manageDiveMode:syncWhileActive:A] Running Loop=1 2026-01-21T13:05:37.105Z,1769000737.105 [sci2:Lap:Dive] Stopped 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:Lap:Dive](DEBUG): Aggregate::uninitialize sci2:Lap:Dive 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:Lap:Dive:A] Stopped 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2:Lap:Dive:A 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:DiveCmd:YoYoDive] Stopped 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:DiveCmd:YoYoDive:dive] Stopped 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T13:05:37.106Z,1769000737.106 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize. 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2026-01-21T13:05:37.107Z,1769000737.107 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Stopped 2026-01-21T13:05:37.108Z,1769000737.108 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent. 2026-01-21T13:05:39.501Z,1769000739.501 [CTD_Seabird](ERROR): Failed to parse device response: ,12.4669,3.88257,2947.39 2026-01-21T13:08:41.616Z,1769000921.616 [sci2:NeedComms:D] Running Loop=1 2026-01-21T13:08:42.388Z,1769000922.388 [Radio_Surface](INFO): Powering up 2026-01-21T13:08:48.348Z,1769000928.348 [DataOverHttps](INFO): Radio surface powered ON. 2026-01-21T13:09:14.451Z,1769000954.451 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2026-01-21T13:09:14.528Z,1769000954.528 [NAL9602](ERROR): received: +CSQ:0 OK351, 2, 0, 0, 0 OK 2026-01-21T13:09:18.472Z,1769000958.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T13:09:30.445Z,1769000970.445 [CTD_Seabird](ERROR): Failed to parse device response: 0.08,34 2026-01-21T13:09:49.596Z,1769000989.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T13:10:20.724Z,1769001020.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T13:10:51.848Z,1769001051.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T13:11:00.588Z,1769001060.588 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002870 2026-01-21T13:12:25.872Z,1769001145.872 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T13:12:25.873Z,1769001145.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T13:12:51.730Z,1769001171.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T13:12:51.730Z,1769001171.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T13:13:34.958Z,1769001214.958 [NAL9602](INFO): SBD MO Status=2, MOMSN=25351, MT Status=2, MTMSN=0 2026-01-21T13:13:34.958Z,1769001214.958 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-21T13:13:45.457Z,1769001225.457 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2026-01-21T13:13:53.197Z,1769001233.197 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131350.00,A,3652.15085,N,12207.78217,W,0.350,79.95,210126,,,D*48 2026-01-21T13:13:53.199Z,1769001233.199 [NAL9602](INFO): GPS fix at 20260121T131350: (36.869181, -122.129703) 2026-01-21T13:13:53.231Z,1769001233.231 [UniversalFixResidualReporter](INFO): Fix residual: 30.0 %DT, over the last 2899.0 m. Residual distance 869.3 m at bearing 78.9 degrees. Fix at (36.8692, -122.1297) with 2147.6 m made good. 2026-01-21T13:13:53.237Z,1769001233.237 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T13:13:53.241Z,1769001233.241 [sci2:NeedComms:D] Stopped 2026-01-21T13:13:53.241Z,1769001233.241 [sci2:NeedComms:E] Running Loop=1 2026-01-21T13:14:01.623Z,1769001241.623 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20260121T014546/Courier0021.lzma 2026-01-21T13:14:02.626Z,1769001242.626 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Courier0021.lzma.bak 2026-01-21T13:14:02.626Z,1769001242.626 [DataOverHttps](INFO): SBD MOMSN=26584131 2026-01-21T13:14:19.494Z,1769001259.494 [DataOverHttps](INFO): Sending 1219 bytes from file Logs/20260121T014546/Express0022.lzma 2026-01-21T13:14:20.494Z,1769001260.494 [DataOverHttps](INFO): Moved sent file to Logs/20260121T014546/Express0022.lzma.bak 2026-01-21T13:14:20.494Z,1769001260.494 [DataOverHttps](INFO): SBD MOMSN=26584134 2026-01-21T13:14:21.843Z,1769001261.843 [sci2:NeedComms:E] Stopped 2026-01-21T13:14:21.843Z,1769001261.843 [sci2:NeedComms:F] Running Loop=1 2026-01-21T13:14:23.843Z,1769001263.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131421.00,A,3652.15227,N,12207.78517,W,0.700,322.49,210126,,,D*79 2026-01-21T13:14:23.850Z,1769001263.850 [NAL9602](INFO): GPS fix at 20260121T131421: (36.869205, -122.129753) 2026-01-21T13:14:23.898Z,1769001263.898 [sci2:Lap:Wpt1:A.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2026-01-21T13:14:23.908Z,1769001263.908 [sci2:NeedComms:F] Stopped 2026-01-21T13:14:23.908Z,1769001263.908 [sci2:NeedComms:setRestoreMode] Running Loop=1 2026-01-21T13:14:23.909Z,1769001263.909 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize sci2:NeedComms:setRestoreMode 2026-01-21T13:14:23.909Z,1769001263.909 [sci2:NeedComms:setRestoreMode:A] Running Loop=1 2026-01-21T13:14:24.282Z,1769001264.282 [sci2:NeedComms:setRestoreMode:A] Stopped 2026-01-21T13:14:24.283Z,1769001264.283 [sci2:NeedComms:setRestoreMode](INFO): Completed sci2:NeedComms:setRestoreMode 2026-01-21T13:14:24.283Z,1769001264.283 [sci2:NeedComms:setRestoreMode] Stopped 2026-01-21T13:14:24.283Z,1769001264.283 [sci2:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize sci2:NeedComms:setRestoreMode 2026-01-21T13:14:24.283Z,1769001264.283 [sci2:NeedComms:setInactive] Running Loop=1 2026-01-21T13:14:24.283Z,1769001264.283 [sci2:NeedComms:setInactive](DEBUG): Aggregate::initialize sci2:NeedComms:setInactive 2026-01-21T13:14:24.283Z,1769001264.283 [sci2:NeedComms:setInactive:A] Running Loop=1 2026-01-21T13:14:24.694Z,1769001264.694 [sci2:NeedComms:setInactive:A] Stopped 2026-01-21T13:14:24.695Z,1769001264.695 [sci2:NeedComms:setInactive](INFO): Completed sci2:NeedComms:setInactive 2026-01-21T13:14:24.695Z,1769001264.695 [sci2:NeedComms:setInactive] Stopped 2026-01-21T13:14:24.695Z,1769001264.695 [sci2:NeedComms:setInactive](DEBUG): Aggregate::uninitialize sci2:NeedComms:setInactive 2026-01-21T13:14:24.695Z,1769001264.695 [sci2:NeedComms](INFO): Completed sci2:NeedComms 2026-01-21T13:14:24.695Z,1769001264.695 [sci2:NeedComms] Stopped 2026-01-21T13:14:24.700Z,1769001264.700 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms 2026-01-21T13:14:24.700Z,1769001264.700 [sci2:NeedComms:C.GoToSurface] Stopped 2026-01-21T13:14:24.700Z,1769001264.700 [sci2:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-21T13:14:25.100Z,1769001265.100 [sci2:Lap:Dive] Running Loop=1 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:Lap:Dive](DEBUG): Aggregate::initialize sci2:Lap:Dive 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:Lap:Dive:A] Running Loop=1 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:Lap:Dive:A](DEBUG): Aggregate::initialize sci2:Lap:Dive:A 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:DiveCmd:SurfaceSink] Running Loop=1 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:DiveCmd:SurfaceSink:sink] Running Loop=1 2026-01-21T13:14:25.101Z,1769001265.101 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T13:14:25.102Z,1769001265.102 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2026-01-21T13:14:25.102Z,1769001265.102 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T13:14:25.102Z,1769001265.102 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2026-01-21T13:14:25.102Z,1769001265.102 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2026-01-21T13:14:25.102Z,1769001265.102 [sci2:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2026-01-21T13:14:25.102Z,1769001265.102 [sci2:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2026-01-21T13:14:25.103Z,1769001265.103 [sci2:DiveCmd:SurfaceSink:sink:C] Stopped 2026-01-21T13:14:25.103Z,1769001265.103 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2026-01-21T13:14:25.103Z,1769001265.103 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2026-01-21T13:14:25.103Z,1769001265.103 [sci2:manageDiveMode:syncWhileActive] Stopped 2026-01-21T13:14:25.103Z,1769001265.103 [sci2:manageDiveMode:syncWhileActive](DEBUG): Aggregate::uninitialize sci2:manageDiveMode:syncWhileActive 2026-01-21T13:14:25.108Z,1769001265.108 [sci2:manageDiveMode:syncWhileActive:A] Stopped 2026-01-21T13:14:56.621Z,1769001296.621 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-21T13:15:08.236Z,1769001308.236 [BuoyancyServo](ERROR): getPosition uart error serial timeout 2026-01-21T13:15:08.236Z,1769001308.236 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout 2026-01-21T13:15:08.236Z,1769001308.236 [BuoyancyServo] Communications Fault, FailCount= 1 2026-01-21T13:15:08.236Z,1769001308.236 [BuoyancyServo](ERROR): Communications Fault 2026-01-21T13:15:08.425Z,1769001308.425 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2026-01-21T13:15:10.126Z,1769001310.126 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2026-01-21T13:15:10.127Z,1769001310.127 [BuoyancyServo](INFO): Powering down 2026-01-21T13:15:11.115Z,1769001311.115 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2026-01-21T13:15:11.115Z,1769001311.115 [BuoyancyServo] No Fault, FailCount= 1 2026-01-21T13:15:11.371Z,1769001311.371 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2026-01-21T13:15:11.372Z,1769001311.372 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2026-01-21T13:16:25.384Z,1769001385.384 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2026-01-21T13:14:25.1Z 2026-01-21T13:16:25.385Z,1769001385.385 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2026-01-21T13:16:25.385Z,1769001385.385 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T13:16:25.385Z,1769001385.385 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2026-01-21T13:16:25.386Z,1769001385.386 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 2.722389 m 2026-01-21T13:16:25.388Z,1769001385.388 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2026-01-21T13:16:25.388Z,1769001385.388 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed sci2:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2026-01-21T13:16:25.388Z,1769001385.388 [sci2:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2026-01-21T13:16:25.389Z,1769001385.389 [sci2:DiveCmd:SurfaceSink:sink:E] Running Loop=1 2026-01-21T13:16:25.781Z,1769001385.781 [sci2:DiveCmd:SurfaceSink:sink:E] Stopped 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink](INFO): Completed sci2:DiveCmd:SurfaceSink:sink 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink] Stopped 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize sci2:DiveCmd:SurfaceSink:sink 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2026-01-21T13:16:25.782Z,1769001385.782 [sci2:DiveCmd:SurfaceSink](INFO): Completed sci2:DiveCmd:SurfaceSink 2026-01-21T13:16:26.180Z,1769001386.180 [sci2:DiveCmd:YoYoDive] Running Loop=1 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive] Running Loop=1 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize. 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component. 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1 2026-01-21T13:16:26.181Z,1769001386.181 [sci2:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1 2026-01-21T13:16:26.182Z,1769001386.182 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize. 2026-01-21T13:16:26.183Z,1769001386.183 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1 2026-01-21T13:16:26.183Z,1769001386.183 [sci2:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2026-01-21T13:16:26.183Z,1769001386.183 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1 2026-01-21T13:16:26.183Z,1769001386.183 [sci2:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2026-01-21T13:16:26.183Z,1769001386.183 [sci2:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1 2026-01-21T13:16:26.183Z,1769001386.183 [sci2:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent. 2026-01-21T13:16:26.186Z,1769001386.186 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped 2026-01-21T13:16:26.186Z,1769001386.186 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive 2026-01-21T13:16:26.186Z,1769001386.186 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped 2026-01-21T13:16:26.186Z,1769001386.186 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed 2026-01-21T13:16:26.186Z,1769001386.186 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped 2026-01-21T13:16:26.186Z,1769001386.186 [sci2:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize. 2026-01-21T13:16:53.476Z,1769001413.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T13:17:05.833Z,1769001425.833 [Radio_Surface](INFO): Powering down 2026-01-21T13:17:24.600Z,1769001444.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2026-01-21T13:17:25.604Z,1769001445.604 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2026-01-21T13:18:30.864Z,1769001510.864 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735291 2026-01-21T13:19:23.881Z,1769001563.881 [CTD_Seabird](ERROR): Failed to parse device response: ,14.0557,4.00277,4124.21 2026-01-21T13:26:37.882Z,1769001997.882 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T13:31:45.493Z,1769002305.493 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2026-01-21T13:35:47.430Z,1769002547.430 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T13:35:47.434Z,1769002547.434 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T13:36:20.085Z,1769002580.085 [CTD_Seabird](ERROR): Failed to parse device response: 25.44,13.9003,3.706.42 2026-01-21T13:39:12.797Z,1769002752.797 [CTD_Seabird](ERROR): Failed to parse device response: .70 2026-01-21T14:00:09.053Z,1769004009.053 [CTD_Seabird](ERROR): Failed to parse device response: 4.36.80 2026-01-21T14:07:39.630Z,1769004459.630 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-21T14:16:49.299Z,1769005009.299 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-21T14:16:49.302Z,1769005009.302 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2026-01-21T14:31:47.281Z,1769005907.281 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2026-01-21T14:38:33.593Z,1769006313.593 [CTD_Seabird](ERROR): Failed to parse device response: .81 2026-01-21T14:42:21.549Z,1769006541.549 [CTD_Seabird](ERROR): Failed to parse device response: .84