2025-04-18T05:13:19.166Z,1744953199.166 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist
2025-04-18T05:13:19.173Z,1744953199.173 [CommandExec](IMPORTANT): got command restart logs
2025-04-18T05:13:31.768Z,1744953211.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T05:13:43.489Z,1744953223.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=19916, MT Status=2, MTMSN=0
2025-04-18T05:13:43.489Z,1744953223.489 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-18T05:14:00.917Z,1744953240.917 [NAL9602](INFO): SBD MO Status=2, MOMSN=19916, MT Status=2, MTMSN=0
2025-04-18T05:14:00.918Z,1744953240.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-18T05:14:02.892Z,1744953242.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T05:14:11.073Z,1744953251.073 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=19916, MT Status=1, MTMSN=4274
2025-04-18T05:14:11.132Z,1744953251.132 [NAL9602](INFO): Sent 40 bytes from file Logs/20250417T194917/Courier0127.lzma
2025-04-18T05:14:11.132Z,1744953251.132 [NAL9602](INFO): Packets left to send: 0
2025-04-18T05:14:11.661Z,1744953251.661 [NAL9602](INFO): Received command: configSet Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50 milliampere persist
2025-04-18T05:14:15.468Z,1744953255.468 [CommandExec](IMPORTANT): got command configSet Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50 milliampere persist
2025-04-18T05:14:31.970Z,1744953271.970 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=19917, MT Status=1, MTMSN=4275
2025-04-18T05:14:32.032Z,1744953272.032 [NAL9602](INFO): Sent 332 bytes from file Logs/20250417T194917/Express0125.lzma
2025-04-18T05:14:32.032Z,1744953272.032 [NAL9602](INFO): Packets left to send: 4
2025-04-18T05:14:32.108Z,1744953272.108 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003261
2025-04-18T05:14:32.521Z,1744953272.521 [NAL9602](INFO): Received command: sched 20250418T13 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs"
2025-04-18T05:14:32.553Z,1744953272.553 [CommandExec](IMPORTANT): got command schedule 20250418T130000 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs"
2025-04-18T05:14:32.555Z,1744953272.555 [CommandExec](IMPORTANT): Scheduled #6: "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs", AT TIME: 2025-04-18T13:00:00Z
2025-04-18T05:14:41.351Z,1744953281.351 [DataOverHttps](INFO): Sending 1458 bytes from file Logs/20250417T194917/Express0125.lzma
2025-04-18T05:14:42.350Z,1744953282.350 [DataOverHttps](INFO): Moved sent file to Logs/20250417T194917/Express0125.lzma.bak
2025-04-18T05:14:42.350Z,1744953282.350 [DataOverHttps](INFO): SBD MOMSN=24684816
2025-04-18T05:15:00.231Z,1744953300.231 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250417T194917/Express0128.lzma
2025-04-18T05:15:01.235Z,1744953301.235 [DataOverHttps](INFO): Moved sent file to Logs/20250417T194917/Express0128.lzma.bak
2025-04-18T05:15:01.235Z,1744953301.235 [DataOverHttps](INFO): SBD MOMSN=24684826
2025-04-18T05:15:03.072Z,1744953303.072 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T05:15:03.100Z,1744953303.100 [profile_station:NeedComms:D] Stopped
2025-04-18T05:15:03.104Z,1744953303.104 [profile_station:NeedComms:E] Running Loop=1
2025-04-18T05:15:05.129Z,1744953305.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,051505.00,A,3646.83752,N,12150.95633,W,0.797,30.17,180425,,,A*4F
2025-04-18T05:15:05.138Z,1744953305.138 [NAL9602](INFO): GPS fix at 20250418T051505: (36.780625, -121.849272)
2025-04-18T05:15:05.213Z,1744953305.213 [profile_station:NeedComms:E] Stopped
2025-04-18T05:15:05.214Z,1744953305.214 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2025-04-18T05:15:05.214Z,1744953305.214 [profile_station:NeedComms] Stopped
2025-04-18T05:15:05.215Z,1744953305.215 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2025-04-18T05:15:05.215Z,1744953305.215 [profile_station:NeedComms:B.GoToSurface] Stopped
2025-04-18T05:15:05.215Z,1744953305.215 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T05:15:05.580Z,1744953305.580 [profile_station:SurfaceComms:B](INFO): Completed profile_station:SurfaceComms:B
2025-04-18T05:15:05.580Z,1744953305.580 [profile_station:SurfaceComms:B] Stopped
2025-04-18T05:15:05.580Z,1744953305.580 [profile_station:SurfaceComms:B](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:B
2025-04-18T05:15:05.580Z,1744953305.580 [profile_station:SurfaceComms:setInitial] Running Loop=1
2025-04-18T05:15:05.580Z,1744953305.580 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setInitial
2025-04-18T05:15:05.580Z,1744953305.580 [profile_station:SurfaceComms:setInitial:A] Running Loop=1
2025-04-18T05:15:05.948Z,1744953305.948 [profile_station:SurfaceComms:setInitial] Stopped
2025-04-18T05:15:05.948Z,1744953305.948 [profile_station:SurfaceComms:setInitial](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setInitial
2025-04-18T05:15:05.948Z,1744953305.948 [profile_station:SurfaceComms:setInitial:A] Stopped
2025-04-18T05:15:05.948Z,1744953305.948 [profile_station:SurfaceComms:setSink] Running Loop=1
2025-04-18T05:15:05.948Z,1744953305.948 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::initialize profile_station:SurfaceComms:setSink
2025-04-18T05:15:05.948Z,1744953305.948 [profile_station:SurfaceComms:setSink:A] Running Loop=1
2025-04-18T05:15:06.359Z,1744953306.359 [profile_station:SurfaceComms:setSink:A] Stopped
2025-04-18T05:15:06.359Z,1744953306.359 [profile_station:SurfaceComms:setSink](INFO): Completed profile_station:SurfaceComms:setSink
2025-04-18T05:15:06.359Z,1744953306.359 [profile_station:SurfaceComms:setSink] Stopped
2025-04-18T05:15:06.359Z,1744953306.359 [profile_station:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:setSink
2025-04-18T05:15:06.364Z,1744953306.364 [profile_station:SurfaceComms](INFO): Completed profile_station:SurfaceComms
2025-04-18T05:15:06.364Z,1744953306.364 [profile_station:SurfaceComms] Stopped
2025-04-18T05:15:06.364Z,1744953306.364 [profile_station:SurfaceComms](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms
2025-04-18T05:15:06.759Z,1744953306.759 [profile_station:CircleWrapper:Dive] Running Loop=1
2025-04-18T05:15:06.759Z,1744953306.759 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive
2025-04-18T05:15:06.763Z,1744953306.763 [profile_station:CircleWrapper:Dive:A] Running Loop=1
2025-04-18T05:15:06.763Z,1744953306.763 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::initialize profile_station:CircleWrapper:Dive:A
2025-04-18T05:15:06.764Z,1744953306.764 [profile_station:DiveCmd:SurfaceSink] Running Loop=1
2025-04-18T05:15:06.764Z,1744953306.764 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize profile_station:DiveCmd:SurfaceSink
2025-04-18T05:15:06.764Z,1744953306.764 [profile_station:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-18T05:15:06.764Z,1744953306.764 [profile_station:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize profile_station:DiveCmd:SurfaceSink:sink
2025-04-18T05:15:06.764Z,1744953306.764 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-18T05:15:06.765Z,1744953306.765 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T05:15:06.765Z,1744953306.765 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-18T05:15:06.765Z,1744953306.765 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-18T05:15:06.765Z,1744953306.765 [profile_station:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-18T05:15:06.765Z,1744953306.765 [profile_station:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-18T05:15:06.765Z,1744953306.765 [profile_station:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-18T05:15:06.766Z,1744953306.766 [profile_station:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-18T05:15:06.766Z,1744953306.766 [profile_station:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-18T05:15:23.828Z,1744953323.828 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19918, MT Status=1, MTMSN=4276
2025-04-18T05:15:23.828Z,1744953323.828 [NAL9602](INFO): Data available in MT queue
2025-04-18T05:15:24.572Z,1744953324.572 [NAL9602](INFO): Received command: sched 20250418T13 "load Science/profile_station.tl;set profile_station.MissionTimeout 14 h;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree;set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m" 4y422 1 2
2025-04-18T05:15:24.591Z,1744953324.591 [CommandExec](IMPORTANT): got command schedule 20250418T130000 "load Science/profile_station.tl;set profile_station.MissionTimeout 14 h;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree;set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m" 4y422 1 2.000000
2025-04-18T05:15:24.591Z,1744953324.591 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4y422
2025-04-18T05:15:24.593Z,1744953324.593 [CommandExec](IMPORTANT): Scheduled #7 (#1 of 2 with id='4y422'): "load Science/profile_station.tl;set profile_station.MissionTimeout 14 h;set profile_station.Lat 36.7948 degree;set profile_station.Lon -121.8726 degree;set profile_station.Radius 3000 m;set profile_station.YoYoMinDepth 4 m", AT TIME: 2025-04-18T13:00:00Z
2025-04-18T05:15:55.191Z,1744953355.191 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T05:16:34.428Z,1744953394.428 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-04-18T05:17:05.561Z,1744953425.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T05:17:23.203Z,1744953443.203 [Radio_Surface](INFO): Powering down
2025-04-18T05:17:36.685Z,1744953456.685 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T05:17:37.690Z,1744953457.690 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-18T05:18:14.988Z,1744953494.988 [profile_station:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-18T05:18:14.989Z,1744953494.989 [profile_station:DiveCmd:SurfaceSink:sink:E] Running Loop=1
2025-04-18T05:18:15.403Z,1744953495.403 [profile_station:DiveCmd:SurfaceSink:sink:E] Stopped
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink](INFO): Completed profile_station:DiveCmd:SurfaceSink:sink
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink] Stopped
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink:sink
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-18T05:18:15.404Z,1744953495.404 [profile_station:DiveCmd:SurfaceSink](INFO): Completed profile_station:DiveCmd:SurfaceSink
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive] Running Loop=1
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive:dive] Running Loop=1
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Running Loop=1
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch](DEBUG): Initialize.
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Running Loop=1
2025-04-18T05:18:15.804Z,1744953495.804 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Running Loop=1
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Running Loop=1
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Running Loop=1
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Running Loop=1
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Initialize.
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Running Loop=1
2025-04-18T05:18:15.805Z,1744953495.805 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::initialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Running Loop=1
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Initialize.
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Running Loop=1
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Running Loop=1
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Running Loop=1
2025-04-18T05:18:15.806Z,1744953495.806 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Initialize YoYoComponent.
2025-04-18T05:18:15.808Z,1744953495.808 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive] Stopped
2025-04-18T05:18:15.808Z,1744953495.808 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive
2025-04-18T05:18:15.808Z,1744953495.808 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed] Stopped
2025-04-18T05:18:15.808Z,1744953495.808 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed
2025-04-18T05:18:15.809Z,1744953495.809 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed] Stopped
2025-04-18T05:18:15.809Z,1744953495.809 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:SurfaceDive:SurfaceSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-04-18T05:18:31.744Z,1744953511.744 [ElevatorServo](FAULT): Overload Error
2025-04-18T05:18:31.744Z,1744953511.744 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T05:18:31.744Z,1744953511.744 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T05:18:31.767Z,1744953511.767 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T05:18:32.041Z,1744953512.041 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T05:18:32.041Z,1744953512.041 [ElevatorServo](INFO): Powering down
2025-04-18T05:18:32.868Z,1744953512.868 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T05:18:32.988Z,1744953512.988 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T05:18:33.064Z,1744953513.064 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T05:18:33.064Z,1744953513.064 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T05:18:42.984Z,1744953522.984 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.383392
2025-04-18T05:19:22.636Z,1744953562.636 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-18T05:19:22.637Z,1744953562.637 [ThrusterHE](INFO): Powering down
2025-04-18T05:19:23.449Z,1744953563.449 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-18T05:19:23.568Z,1744953563.568 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-18T05:20:52.217Z,1744953652.217 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,00652,+00145,+00000,A
2025-04-18T05:20:52.217Z,1744953652.217 [DVL_micro](ERROR): Failed to parse:
:BI,00652,+00145,+00000,A
2025-04-18T05:22:31.534Z,1744953751.534 [DVL_micro](ERROR): Failed to parse:
:WI,+00156,+00455,+00025,+00000,A
2025-04-18T05:23:46.407Z,1744953826.407 [DVL_micro](ERROR): Failed to parse:
:SA,+00.75,-00.25,120.4
2025-04-18T05:29:30.353Z,1744954170.353 [DVL_micro](ERROR): Failed to parse:000000.00,+00000000.00,0054.50,000.00
2025-04-18T05:29:38.308Z,1744954178.308 [CTD_Seabird](ERROR): Failed to parse device response: 5.54.60
2025-04-18T05:33:41.659Z,1744954421.659 [DVL_micro](ERROR): Failed to parse:
:BI,-00056,+00415,+00066,+00000,A
2025-04-18T05:37:05.188Z,1744954625.188 [CTD_Seabird](ERROR): Failed to parse device response: ,10.3425,3.71761,2829.89
2025-04-18T05:43:47.011Z,1744955027.011 [DVL_micro](ERROR): Failed to parse:
:BI,-00054,+00496,-00055,+00000,A
2025-04-18T05:43:48.183Z,1744955028.183 [DVL_micro](ERROR): Failed to parse:
:SA,-19.63,-03.49,186.4
2025-04-18T05:47:29.282Z,1744955249.282 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 70
Device respsone is::TS,00000000000000,35.0,+10.7,0000.0,1489.0,0000070,+00702,-00088,+00000,A
2025-04-18T05:47:29.299Z,1744955249.299 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-04-18T05:48:23.080Z,1744955303.080 [CTD_Seabird](ERROR): Failed to parse device response: 21.65,10.5262,3.730.90
2025-04-18T06:02:31.580Z,1744956151.580 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:02:31.580Z,1744956151.580 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:02:31.580Z,1744956151.580 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:02:31.749Z,1744956151.749 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:02:31.916Z,1744956151.916 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:02:31.916Z,1744956151.916 [ElevatorServo](INFO): Powering down
2025-04-18T06:02:32.764Z,1744956152.764 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:02:32.884Z,1744956152.884 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:02:33.036Z,1744956153.036 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:02:33.036Z,1744956153.036 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T06:04:43.284Z,1744956283.284 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-18T06:04:43.285Z,1744956283.285 [ThrusterHE](INFO): Powering down
2025-04-18T06:04:44.118Z,1744956284.118 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-18T06:04:44.236Z,1744956284.236 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-18T06:05:42.238Z,1744956342.238 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T06:05:42.240Z,1744956342.240 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-18T06:05:42.245Z,1744956342.245 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T06:05:58.594Z,1744956358.594 [DVL_micro](ERROR): Failed to parse:
:RD,+0037.62,+0043.37,+9999.99,+0042.70
2025-04-18T06:06:29.576Z,1744956389.576 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-18T06:06:29.577Z,1744956389.577 [ThrusterHE](INFO): Powering down
2025-04-18T06:06:30.381Z,1744956390.381 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-18T06:06:30.496Z,1744956390.496 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-18T06:06:57.708Z,1744956417.708 [CTD_Seabird](ERROR): Failed to parse device response: 03,2702.01
2025-04-18T06:07:15.281Z,1744956435.281 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,+999

2025-04-18T06:07:15.281Z,1744956435.281 [DVL_micro](ERROR): Failed to parse:
:RD,+999

2025-04-18T06:08:00.892Z,1744956480.892 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:08:00.892Z,1744956480.892 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:08:00.892Z,1744956480.892 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:08:00.913Z,1744956480.913 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:08:01.188Z,1744956481.188 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:08:01.188Z,1744956481.188 [ElevatorServo](INFO): Powering down
2025-04-18T06:08:02.008Z,1744956482.008 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:08:02.128Z,1744956482.128 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:08:02.271Z,1744956482.271 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:08:02.271Z,1744956482.271 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T06:10:45.384Z,1744956645.384 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00113,+00291,+000030.99,+0033.77
2025-04-18T06:10:45.384Z,1744956645.384 [DVL_micro](ERROR): Failed to parse:
:BI,-00113,+00291,+000030.99,+0033.77
2025-04-18T06:11:25.801Z,1744956685.801 [CTD_Seabird](ERROR): Failed to parse device response: 00,2789.04
2025-04-18T06:11:26.181Z,1744956686.181 [DVL_micro](ERROR): Failed to parse:
:039.70
2025-04-18T06:15:05.150Z,1744956905.150 [profile_station:SurfaceComms] Running Loop=1
2025-04-18T06:15:05.151Z,1744956905.151 [profile_station:SurfaceComms](DEBUG): Aggregate::initialize profile_station:SurfaceComms
2025-04-18T06:15:05.152Z,1744956905.152 [profile_station:SurfaceComms:A] Running Loop=1
2025-04-18T06:15:05.153Z,1744956905.153 [profile_station:SurfaceComms:A] Stopped
2025-04-18T06:15:05.153Z,1744956905.153 [profile_station:SurfaceComms:B] Running Loop=1
2025-04-18T06:15:05.153Z,1744956905.153 [profile_station:SurfaceComms:B](DEBUG): Aggregate::initialize profile_station:SurfaceComms:B
2025-04-18T06:15:05.568Z,1744956905.568 [profile_station:CircleWrapper:Dive] Stopped
2025-04-18T06:15:05.568Z,1744956905.568 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive
2025-04-18T06:15:05.568Z,1744956905.568 [profile_station:CircleWrapper:Dive:A] Stopped
2025-04-18T06:15:05.569Z,1744956905.569 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A
2025-04-18T06:15:05.569Z,1744956905.569 [profile_station:DiveCmd:YoYoDive] Stopped
2025-04-18T06:15:05.569Z,1744956905.569 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive
2025-04-18T06:15:05.569Z,1744956905.569 [profile_station:DiveCmd:YoYoDive:dive] Stopped
2025-04-18T06:15:05.569Z,1744956905.569 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize.
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped
2025-04-18T06:15:05.570Z,1744956905.570 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-04-18T06:15:05.571Z,1744956905.571 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Stopped
2025-04-18T06:15:05.571Z,1744956905.571 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent.
2025-04-18T06:15:05.572Z,1744956905.572 [profile_station:NeedComms] Running Loop=1
2025-04-18T06:15:05.572Z,1744956905.572 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2025-04-18T06:15:05.572Z,1744956905.572 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2025-04-18T06:15:05.573Z,1744956905.573 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T06:15:05.573Z,1744956905.573 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-18T06:15:05.573Z,1744956905.573 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-18T06:15:05.574Z,1744956905.574 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-18T06:15:05.574Z,1744956905.574 [profile_station:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-18T06:15:05.574Z,1744956905.574 [profile_station:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T06:15:05.575Z,1744956905.575 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T06:15:05.575Z,1744956905.575 [profile_station:NeedComms:A] Running Loop=1
2025-04-18T06:15:05.593Z,1744956905.593 [profile_station:NeedComms:A](INFO): last time_fix was:  1744953305.000000 second since 1970/01/01T00:00:00Z 
2025-04-18T06:15:05.593Z,1744956905.593 [profile_station:NeedComms:A] Stopped
2025-04-18T06:15:31.306Z,1744956931.306 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0065.20,+0053.20,+0057.99
2025-04-18T06:15:58.038Z,1744956958.038 [profile_station:NeedComms:C] Running Loop=1
2025-04-18T06:15:58.656Z,1744956958.656 [Radio_Surface](INFO): Powering up
2025-04-18T06:16:02.959Z,1744956962.959 [CTD_Seabird](ERROR): Salinity reading out of range: 19.643660 psu
2025-04-18T06:16:02.961Z,1744956962.961 [CTD_Seabird](INFO): some bad data, not updating bins
2025-04-18T06:16:04.632Z,1744956964.632 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-18T06:16:29.946Z,1744956989.946 [NAL9602](INFO): SBD MO Status=2, MOMSN=19919, MT Status=2, MTMSN=0
2025-04-18T06:16:29.946Z,1744956989.946 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-18T06:16:34.751Z,1744956994.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T06:16:41.649Z,1744957001.649 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19919, MT Status=1, MTMSN=4277
2025-04-18T06:16:41.649Z,1744957001.649 [NAL9602](INFO): Data available in MT queue
2025-04-18T06:16:42.380Z,1744957002.380 [NAL9602](INFO): Received command: sched 20250418T13 "set profile_station.YoYoMaxDepth 40 m;set profile_station.Speed 0.75 m/s;set profile_station.SinkOffSurface 1 bool;set profile_station.MaxDepth 50 m;set profile_station:NeedComms.CommsTimeout 10 min;run" 4y422 2 2
2025-04-18T06:16:42.401Z,1744957002.401 [CommandExec](IMPORTANT): got command schedule 20250418T130000 "set profile_station.YoYoMaxDepth 40 m;set profile_station.Speed 0.75 m/s;set profile_station.SinkOffSurface 1 bool;set profile_station.MaxDepth 50 m;set profile_station:NeedComms.CommsTimeout 10 min;run" 4y422 2 2.000000
2025-04-18T06:16:42.402Z,1744957002.402 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4y422
2025-04-18T06:16:42.403Z,1744957002.403 [CommandExec](IMPORTANT): Scheduled #8 (#2 of 2 with id='4y422'): "set profile_station.YoYoMaxDepth 40 m;set profile_station.Speed 0.75 m/s;set profile_station.SinkOffSurface 1 bool;set profile_station.MaxDepth 50 m;set profile_station:NeedComms.CommsTimeout 10 min;run", AT TIME: 2025-04-18T13:00:00Z
2025-04-18T06:16:43.369Z,1744957003.369 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061644.00,A,3646.53768,N,12150.96692,W,0.641,7.09,180425,,,A*77
2025-04-18T06:16:43.372Z,1744957003.372 [NAL9602](INFO): GPS fix at 20250418T061644: (36.775628, -121.849449)
2025-04-18T06:16:43.401Z,1744957003.401 [UniversalFixResidualReporter](INFO): Fix residual: 2.7 %DT, over the last 1519.1 m. Residual distance 40.7 m at bearing -127.6 degrees. Fix at (36.7756, -121.8494) with 555.9 m made good.
2025-04-18T06:16:43.405Z,1744957003.405 [profile_station:NeedComms:C] Stopped
2025-04-18T06:16:43.406Z,1744957003.406 [profile_station:NeedComms:D] Running Loop=1
2025-04-18T06:17:05.876Z,1744957025.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T06:17:06.587Z,1744957026.587 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=19920, MT Status=1, MTMSN=4278
2025-04-18T06:17:06.644Z,1744957026.644 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Courier0000.lzma
2025-04-18T06:17:06.644Z,1744957026.644 [NAL9602](INFO): Packets left to send: 1
2025-04-18T06:17:07.296Z,1744957027.296 [NAL9602](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 40 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s" 4y479 1 2
2025-04-18T06:17:07.396Z,1744957027.396 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 40 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s" 4y479 1 2.000000
2025-04-18T06:17:07.397Z,1744957027.397 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=4y479
2025-04-18T06:17:07.398Z,1744957027.398 [CommandExec](IMPORTANT): Scheduled #9 (#1 of 2 with id='4y479'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 40 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s", ASAP
2025-04-18T06:17:24.905Z,1744957044.905 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=19921, MT Status=1, MTMSN=4279
2025-04-18T06:17:24.964Z,1744957044.964 [NAL9602](INFO): Sent 38 bytes from file Logs/20250418T051319/Courier0000.lzma
2025-04-18T06:17:24.965Z,1744957044.965 [NAL9602](INFO): Packets left to send: 0
2025-04-18T06:17:25.492Z,1744957045.492 [NAL9602](INFO): Received command: sched asap "set keepstation:NeedComms.CommsTimeout 10 min;run" 4y479 2 2
2025-04-18T06:17:25.551Z,1744957045.551 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:NeedComms.CommsTimeout 10 min;run" 4y479 2 2.000000
2025-04-18T06:17:25.552Z,1744957045.552 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=4y479
2025-04-18T06:17:25.553Z,1744957045.553 [CommandExec](IMPORTANT): Scheduled #10 (#2 of 2 with id='4y479'): "set keepstation:NeedComms.CommsTimeout 10 min;run", ASAP
2025-04-18T06:17:25.668Z,1744957045.668 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-04-18T06:17:25.668Z,1744957045.668 [MissionManager](INFO): Uninitializing Mission profile_station
2025-04-18T06:17:25.668Z,1744957045.668 [profile_station] Stopped
2025-04-18T06:17:25.668Z,1744957045.668 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2025-04-18T06:17:25.668Z,1744957045.668 [profile_station:Science] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:B] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:C] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:E] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:OceanCurrent] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:OceanCurrent:A.] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:PeakDetectChl] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth] Stopped
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Uninitialize.
2025-04-18T06:17:25.669Z,1744957045.669 [profile_station:NeedComms] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:NeedComms:B.GoToSurface] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:NeedComms:D] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-04-18T06:17:25.670Z,1744957045.670 [profile_station:BackseatDriver] Stopped
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:BackseatDriver](DEBUG): Aggregate::uninitialize profile_station:BackseatDriver
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:BackseatDriver:A.BackseatDriver] Stopped
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:PowerOnly] Stopped
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:PowerOnly](DEBUG): Aggregate::uninitialize profile_station:PowerOnly
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:PowerOnly:E.Wait] Stopped
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:SurfaceComms] Stopped
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:SurfaceComms](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms
2025-04-18T06:17:25.671Z,1744957045.671 [profile_station:SurfaceComms:B] Stopped
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:SurfaceComms:B](DEBUG): Aggregate::uninitialize profile_station:SurfaceComms:B
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:DiveCmd] Stopped
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:DiveCmd](DEBUG): Aggregate::uninitialize profile_station:DiveCmd
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:DiveCmd:SurfaceSink] Stopped
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:CircleWrapper] Stopped
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:CircleWrapper:A] Stopped
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:CircleWrapper:C.Circle] Stopped
2025-04-18T06:17:25.672Z,1744957045.672 [profile_station:CircleWrapper:C.Circle](DEBUG): Uninitialize.
2025-04-18T06:17:25.911Z,1744957045.911 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-04-18T06:17:25.915Z,1744957045.915 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-04-18T06:17:25.928Z,1744957045.928 [WetLabsBB2FL](INFO): Powering down
2025-04-18T06:17:25.957Z,1744957045.957 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-04-18T06:17:27.549Z,1744957047.549 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="SinkOffSurface">
        <False />
    </DefineArg>
    <DefineArg Name="SinkDepth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SinkDepthTimeout">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <DefineOutput Name="DiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="DoingComms">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="SurfaceSink">
        <Units:count />
        <Value>1</Value>
    </DefineOutput>
    <DefineOutput Name="TransitToStation">
        <Units:count />
        <Value>2</Value>
    </DefineOutput>
    <DefineOutput Name="StationKeep">
        <Units:count />
        <Value>3</Value>
    </DefineOutput>
    <DefineOutput Name="OnStation">
        <False />
    </DefineOutput>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="SurfaceComms">
        <When>
            <Called />
            <Or>
                <Elapsed>
                    <Universal:time_fix />
                </Elapsed>
                <Gt>
                    <Arg Name="NeedCommsTime" />
                </Gt>
            </Or>
        </When>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="DoingComms" />
        </Assign>
        <Call RefId="NeedComms" />
        <Aggregate Id="setTransit">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Arg Name="OnStation" />
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="TransitToStation" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setStation">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Not>
                        <Arg Name="OnStation" />
                    </Not>
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="StationKeep" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setSink">
            <Sequence />
            <Break>
                <Not>
                    <Arg Name="SinkOffSurface" />
                </Not>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="SurfaceSink" />
            </Assign>
        </Aggregate>
    </Aggregate>
    <Call RefId="SurfaceComms" Id="StartingMission" />
    <Aggregate Id="DiveCmd">
        <When>
            <Called />
        </When>
        <Aggregate Id="SurfaceSink">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="SurfaceSink" />
                </Eq>
            </While>
            <Aggregate Id="sink">
                <Sequence />
                <Guidance:Buoyancy Id="BuoyancyHold">
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Units:cubic_centimeter />
                        <Value>NaN</Value>
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Units:meter_per_second />
                        <Value>0</Value>
                    </Setting>
                </Guidance:SetSpeed>
                <Syslog Severity="Info">
                    Sinking to 
                    <Arg Name="SinkDepth" />
                    <Units:meter />
                </Syslog>
                <Guidance:Pitch>
                    <Sequence />
                    <Timeout Duration="SinkDepthTimeout">
                        <Syslog Severity="Important">
                            Timed out while trying to reach target sink depth at current depth of 
                            <Universal:depth />
                            <Units:meter />
                        </Syslog>
                    </Timeout>
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="SinkDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Aggregate Id="sinkToTransit">
                    <Sequence />
                    <Break>
                        <Arg Name="OnStation" />
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="TransitToStation" />
                    </Assign>
                </Aggregate>
                <Aggregate Id="sinkToStation">
                    <Sequence />
                    <Break>
                        <Not>
                            <Arg Name="OnStation" />
                        </Not>
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="StationKeep" />
                    </Assign>
                </Aggregate>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="TransitToStation">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="TransitToStation" />
                </Eq>
            </While>
            <Aggregate Id="dive">
                <Sequence />
                <Guidance:Buoyancy>
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Control:VerticalControl.buoyancyNeutral />
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:Pitch>
                    <Parallel />
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="ApproachDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Arg Name="Speed" />
                    </Setting>
                </Guidance:SetSpeed>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="StationKeep">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="StationKeep" />
                </Eq>
            </While>
            <Assign>
                <Parallel />
                <Control:VerticalControl.depthDeadband />
                <Arg Name="DepthDeadband" />
            </Assign>
            <Guidance:Pitch>
                <Parallel />
                <Setting>
                    <Guidance:Pitch.depth />
                    <Arg Name="Depth" />
                </Setting>
            </Guidance:Pitch>
            <Guidance:KeepStation>
                <Parallel />
                <Setting>
                    <Guidance:KeepStation.latitude />
                    <Arg Name="Latitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.longitude />
                    <Arg Name="Longitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.radius />
                    <Arg Name="Radius" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.speed />
                    <Arg Name="Speed" />
                </Setting>
            </Guidance:KeepStation>
        </Aggregate>
    </Aggregate>
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" />
        </Aggregate>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="StationKeep" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="OnStation" />
            <True />
        </Assign>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-04-18T06:17:27.559Z,1744957047.559 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-04-18T06:17:27.562Z,1744957047.562 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-04-18T06:17:27.566Z,1744957047.566 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-04-18T06:17:27.569Z,1744957047.569 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-04-18T06:17:27.573Z,1744957047.573 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-04-18T06:17:27.576Z,1744957047.576 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-04-18T06:17:27.580Z,1744957047.580 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-04-18T06:17:27.583Z,1744957047.583 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-04-18T06:17:27.587Z,1744957047.587 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-04-18T06:17:27.590Z,1744957047.590 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-04-18T06:17:27.594Z,1744957047.594 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-04-18T06:17:27.617Z,1744957047.617 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-04-18T06:17:27.636Z,1744957047.636 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-04-18T06:17:27.644Z,1744957047.644 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-04-18T06:17:27.652Z,1744957047.652 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-04-18T06:17:27.660Z,1744957047.660 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-04-18T06:17:27.671Z,1744957047.671 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-04-18T06:17:27.679Z,1744957047.679 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-04-18T06:17:27.683Z,1744957047.683 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-04-18T06:17:27.690Z,1744957047.690 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-04-18T06:17:27.691Z,1744957047.691 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-04-18T06:17:27.695Z,1744957047.695 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-04-18T06:17:27.697Z,1744957047.697 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-04-18T06:17:28.149Z,1744957048.149 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-04-18T06:17:28.176Z,1744957048.176 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-04-18T06:17:28.183Z,1744957048.183 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-04-18T06:17:28.187Z,1744957048.187 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-04-18T06:17:28.195Z,1744957048.195 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-04-18T06:17:28.202Z,1744957048.202 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-04-18T06:17:28.210Z,1744957048.210 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-04-18T06:17:28.218Z,1744957048.218 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-04-18T06:17:28.227Z,1744957048.227 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-04-18T06:17:28.244Z,1744957048.244 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-18T06:17:28.315Z,1744957048.315 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-04-18T06:17:28.316Z,1744957048.316 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-04-18T06:17:28.318Z,1744957048.318 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-04-18T06:17:28.502Z,1744957048.502 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-04-18T06:17:28.547Z,1744957048.547 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-04-18T06:17:28.551Z,1744957048.551 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-04-18T06:17:28.563Z,1744957048.563 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-04-18T06:17:28.574Z,1744957048.574 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-04-18T06:17:28.585Z,1744957048.585 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-04-18T06:17:28.599Z,1744957048.599 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-04-18T06:17:28.629Z,1744957048.629 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-04-18T06:17:28.664Z,1744957048.664 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-04-18T06:17:28.665Z,1744957048.665 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-04-18T06:17:28.666Z,1744957048.666 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-04-18T06:17:28.721Z,1744957048.721 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-04-18T06:17:28.729Z,1744957048.729 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-04-18T06:17:28.736Z,1744957048.736 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-04-18T06:17:28.744Z,1744957048.744 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-04-18T06:17:28.744Z,1744957048.744 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-04-18T06:17:28.746Z,1744957048.746 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-04-18T06:17:29.016Z,1744957049.016 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-04-18T06:17:29.032Z,1744957049.032 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-04-18T06:17:29.035Z,1744957049.035 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-04-18T06:17:29.042Z,1744957049.042 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-04-18T06:17:29.046Z,1744957049.046 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-04-18T06:17:29.058Z,1744957049.058 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-04-18T06:17:29.074Z,1744957049.074 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-04-18T06:17:29.081Z,1744957049.081 [keepstation:H.Pitch](DEBUG): Construct.
2025-04-18T06:17:29.165Z,1744957049.165 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-04-18T06:17:29.172Z,1744957049.172 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-04-18T06:17:29.181Z,1744957049.181 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-04-18T06:17:29.220Z,1744957049.220 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-04-18T06:17:29.228Z,1744957049.228 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-04-18T06:17:29.234Z,1744957049.234 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-04-18T06:17:29.250Z,1744957049.250 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-04-18T06:17:29.269Z,1744957049.269 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-04-18T06:17:29.302Z,1744957049.302 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-04-18T06:17:29.406Z,1744957049.406 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-04-18T06:17:29.472Z,1744957049.472 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission keepstation {
  """
  Vehicle transits to desired waypoint (or stays put if none is commanded)
  and stays within the specified radius.
  """

  arguments {
    MissionTimeout = 4 hour
      """
      Maximum duration of mission
      """

    NeedCommsTime = 45 minute
      """
      How often to surface for communications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to hold. If NaN, hold the latitude at start of
      mission.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to hold. If NaN, hold the longitude at start of
      mission.
      """

    Depth = 30 meter
      """
      Depth held during drift mode
      """

    ApproachDepth = 15 meter
      """
      Depth for initial approach to station.
      """

    DepthDeadband = 4 meter
      """
      How much vertical drift from the specified depth is allowed in drift
      mode
      """

    Speed = 0.75 meter_per_second
      """
      Vehicle transit speed.
      """

    Radius = 200 meter
      """
      Radius of circle around waypoint to hold.
      """

    SinkOffSurface = false
      """
      If true, use buoyancy only to decend to SinkDepth before driving to first target depth
      """

    SinkDepth = 10 meter
      """
      Initial depth to decend using only buoyancy before driving
      """

    SinkDepthTimeout = 10 minute
      """
      Max wait time for the vehicle to reach the target depth
      """

    MaxDepth = 45 meter
      """
      Maximum allowable depth during the mission.
      """

    MinOffshore = 2 kilometer
      """
      Minimum distance from the shoreline to maintain
      """
  }

  output {
    DiveMode = 0 count
      """
      Mission variable (don't change). The mission sets this variable to
      switch between dive modes. Initialized to DoingComms.
      """

    DoingComms = 0 count
      """
      Mission variable (don't change). The mission will run in this mode
      when doing surface comms.
      """

    SurfaceSink = 1 count
      """
      Mission variable (don't change). The mission will run in this mode
      when sinking from the surface using only buoyancy.
      """

    TransitToStation = 2 count
      """
      Mission variable (don't change). The mission will run in this mode
      when performing the transit to station
      """

    StationKeep = 3 count
      """
      Mission variable (don't change). The mission will run in this mode
      when keeping station
      """

    OnStation = false
      """
      Mission variable (don't change). Determines last mode (Transit or StationKeep)
      to resume after needcomms
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate SurfaceComms {
    run when (
      called
      or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
    )

    assign in sequence DiveMode = DoingComms

    call refId="NeedComms"

    aggregate setTransit {
      run in sequence

      break if (
        SinkOffSurface
        or OnStation
      )

      assign in sequence DiveMode = TransitToStation
    }

    aggregate setStation {
      run in sequence

      break if (
        SinkOffSurface
        or not OnStation
      )

      assign in sequence DiveMode = StationKeep
    }

    aggregate setSink {
      run in sequence

      break if ( not SinkOffSurface )

      assign in sequence DiveMode = SurfaceSink
    }
  }

  call id="StartingMission" refId="SurfaceComms"

  aggregate DiveCmd {
    run when ( called )

    aggregate SurfaceSink {
      run while ( DiveMode == SurfaceSink )

      aggregate sink {
        run in sequence

        behavior Guidance:Buoyancy id="BuoyancyHold" {
          run in parallel

          set position = NaN cc
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = 0 m/s
        }

        syslog info "Sinking to " + SinkDepth~meter

        behavior Guidance:Pitch {
          run in sequence

          timeout duration=SinkDepthTimeout {
            syslog important "Timed out while trying to reach target sink depth at current depth of "
                 + Universal:depth~meter
          }

          set depth = SinkDepth
        }

        # Once we've sunk, either resume transit or station keeping depths and speeds
        aggregate sinkToTransit {
          run in sequence

          break if ( OnStation )

          assign in sequence DiveMode = TransitToStation
        }

        aggregate sinkToStation {
          run in sequence

          break if ( not OnStation )

          assign in sequence DiveMode = StationKeep
        }
      }
    }

    aggregate TransitToStation {
      run while ( DiveMode == TransitToStation )

      aggregate dive {
        run in sequence

        behavior Guidance:Buoyancy {
          run in parallel

          set position = Control:VerticalControl.buoyancyNeutral
        }

        behavior Guidance:Pitch {
          run in parallel

          set depth = ApproachDepth
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = Speed
        }
      }
    }

    aggregate StationKeep {
      run while ( DiveMode == StationKeep )

      assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

      behavior Guidance:Pitch {
        run in parallel

        set depth = Depth
      }

      behavior Guidance:KeepStation {
        run in parallel

        set latitude = Latitude
        set longitude = Longitude
        set radius = Radius
        set speed = Speed
      }
    }
  }

  aggregate TransitToStation {
    """
    Need a separate aggregate for transit if we want to specify a
    different depth for the approach.
    """

    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call refId="DiveCmd"
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
    }

    assign in sequence DiveMode = StationKeep
    assign in sequence OnStation = true
  }

  call id="OnStation" refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call refId="DiveCmd"
    }

    behavior Guidance:Wait {
      """
      Due to the way the KeepStation behavior is currently
      implemented, we must run it in parallel and use a Wait to keep
      from completing the mission before the timeout expires. However,
      this new version of the mission uses a top-level timeout so that
      the entire mission will not run for longer then MissionTimeout.
      """

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-04-18T06:17:29.473Z,1744957049.473 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-04-18T06:17:29.793Z,1744957049.793 [MissionManager](IMPORTANT): Started mission Default
2025-04-18T06:17:29.793Z,1744957049.793 [Default] Running Loop=1
2025-04-18T06:17:29.793Z,1744957049.793 [Default](DEBUG): Aggregate::initialize Default
2025-04-18T06:17:29.793Z,1744957049.793 [Default:B.GoToSurface] Running Loop=1
2025-04-18T06:17:29.793Z,1744957049.793 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T06:17:29.793Z,1744957049.793 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-18T06:17:29.794Z,1744957049.794 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-18T06:17:29.794Z,1744957049.794 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-18T06:17:29.794Z,1744957049.794 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-18T06:17:29.795Z,1744957049.795 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T06:17:29.795Z,1744957049.795 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T06:17:29.819Z,1744957049.819 [Default:A.Wait] Running Loop=1
2025-04-18T06:17:29.820Z,1744957049.820 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-04-18T06:17:37.004Z,1744957057.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T06:17:43.128Z,1744957063.128 [Default:A.Wait](INFO): Done Waiting.
2025-04-18T06:17:43.128Z,1744957063.128 [Default:A.Wait] Stopped
2025-04-18T06:17:43.128Z,1744957063.128 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-04-18T06:17:43.549Z,1744957063.549 [Default:CheckIn] Running Loop=1
2025-04-18T06:17:43.550Z,1744957063.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-18T06:17:43.550Z,1744957063.550 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-18T06:17:45.547Z,1744957065.547 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061746.00,A,3646.54432,N,12150.95881,W,0.719,357.59,180425,,,A*7F
2025-04-18T06:17:45.550Z,1744957065.550 [NAL9602](INFO): GPS fix at 20250418T061746: (36.775739, -121.849313)
2025-04-18T06:17:45.592Z,1744957065.592 [Default:CheckIn:Read_GPS] Stopped
2025-04-18T06:17:45.592Z,1744957065.592 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-18T06:18:09.566Z,1744957089.566 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour
2025-04-18T06:18:09.566Z,1744957089.566 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 120 minute
2025-04-18T06:18:09.571Z,1744957089.571 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool
2025-04-18T06:18:09.572Z,1744957089.572 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 40 meter
2025-04-18T06:18:09.573Z,1744957089.573 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second
2025-04-18T06:18:11.376Z,1744957091.376 [NAL9602](INFO): SBD MO Status=2, MOMSN=19922, MT Status=2, MTMSN=0
2025-04-18T06:18:11.376Z,1744957091.376 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-18T06:18:21.936Z,1744957101.936 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003369
2025-04-18T06:18:22.253Z,1744957102.253 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute
2025-04-18T06:18:22.253Z,1744957102.253 [CommandExec](IMPORTANT): got command run
2025-04-18T06:18:22.256Z,1744957102.256 [CommandExec](IMPORTANT): Running 
2025-04-18T06:18:22.382Z,1744957102.382 [Default] Stopped
2025-04-18T06:18:22.382Z,1744957102.382 [Default](DEBUG): Aggregate::uninitialize Default
2025-04-18T06:18:22.382Z,1744957102.382 [Default:B.GoToSurface] Stopped
2025-04-18T06:18:22.382Z,1744957102.382 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T06:18:22.382Z,1744957102.382 [Default:CheckIn] Stopped
2025-04-18T06:18:22.382Z,1744957102.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-18T06:18:22.382Z,1744957102.382 [Default:CheckIn:Read_Iridium] Stopped
2025-04-18T06:18:22.382Z,1744957102.382 [MissionManager](IMPORTANT): Started mission keepstation
2025-04-18T06:18:22.383Z,1744957102.383 [keepstation] Running Loop=1
2025-04-18T06:18:22.383Z,1744957102.383 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-04-18T06:18:22.383Z,1744957102.383 [keepstation:StandardEnvelopes] Running Loop=1
2025-04-18T06:18:22.383Z,1744957102.383 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-04-18T06:18:22.383Z,1744957102.383 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-04-18T06:18:22.383Z,1744957102.383 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:BackseatDriver] Running Loop=1
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-04-18T06:18:22.384Z,1744957102.384 [keepstation:PowerOnly] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly:A] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly:B] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly:C] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly:D] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:H.Pitch] Running Loop=1
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:H.Pitch](DEBUG): Initialize.
2025-04-18T06:18:22.385Z,1744957102.385 [keepstation:B] Running Loop=1
2025-04-18T06:18:22.386Z,1744957102.386 [keepstation:H.Pitch] Running Loop=1
2025-04-18T06:18:22.386Z,1744957102.386 [keepstation:PowerOnly] Running Loop=1
2025-04-18T06:18:22.387Z,1744957102.387 [keepstation:PowerOnly:D] Stopped
2025-04-18T06:18:22.387Z,1744957102.387 [keepstation:PowerOnly:C] Stopped
2025-04-18T06:18:22.387Z,1744957102.387 [keepstation:PowerOnly:B] Stopped
2025-04-18T06:18:22.387Z,1744957102.387 [keepstation:PowerOnly:A] Stopped
2025-04-18T06:18:22.387Z,1744957102.387 [keepstation:BackseatDriver] Running Loop=1
2025-04-18T06:18:22.387Z,1744957102.387 [keepstation:StandardEnvelopes] Running Loop=1
2025-04-18T06:18:22.388Z,1744957102.388 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-04-18T06:18:22.388Z,1744957102.388 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-04-18T06:18:22.388Z,1744957102.388 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-04-18T06:18:22.389Z,1744957102.389 [keepstation:B] Stopped
2025-04-18T06:18:22.389Z,1744957102.389 [keepstation:D] Running Loop=1
2025-04-18T06:18:22.736Z,1744957102.736 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 3.45 mm.
2025-04-18T06:18:22.737Z,1744957102.737 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 3.45 mm.
2025-04-18T06:18:22.738Z,1744957102.738 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position 3.45 mm.
2025-04-18T06:18:22.745Z,1744957102.745 [keepstation:D] Stopped
2025-04-18T06:18:22.745Z,1744957102.745 [keepstation:E] Running Loop=1
2025-04-18T06:18:23.161Z,1744957103.161 [keepstation:E] Stopped
2025-04-18T06:18:23.161Z,1744957103.161 [keepstation:StartingMission] Running Loop=1
2025-04-18T06:18:23.161Z,1744957103.161 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-04-18T06:18:23.546Z,1744957103.546 [keepstation:SurfaceComms] Running Loop=1
2025-04-18T06:18:23.547Z,1744957103.547 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-18T06:18:23.547Z,1744957103.547 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-18T06:18:23.547Z,1744957103.547 [keepstation:SurfaceComms:A] Stopped
2025-04-18T06:18:23.547Z,1744957103.547 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-18T06:18:23.547Z,1744957103.547 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-18T06:18:23.952Z,1744957103.952 [keepstation:NeedComms] Running Loop=1
2025-04-18T06:18:23.952Z,1744957103.952 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-18T06:18:23.952Z,1744957103.952 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-18T06:18:23.952Z,1744957103.952 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T06:18:23.953Z,1744957103.953 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-18T06:18:23.953Z,1744957103.953 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-18T06:18:23.954Z,1744957103.954 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-18T06:18:23.954Z,1744957103.954 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-18T06:18:23.954Z,1744957103.954 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T06:18:23.955Z,1744957103.955 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T06:18:23.955Z,1744957103.955 [keepstation:NeedComms:A] Running Loop=1
2025-04-18T06:18:23.957Z,1744957103.957 [keepstation:NeedComms:A](INFO): last time_fix was:  1744957066.000000 second since 1970/01/01T00:00:00Z 
2025-04-18T06:18:23.957Z,1744957103.957 [keepstation:NeedComms:A] Stopped
2025-04-18T06:18:24.370Z,1744957104.370 [keepstation:NeedComms:C] Running Loop=1
2025-04-18T06:18:24.771Z,1744957104.771 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-18T06:18:29.603Z,1744957109.603 [NAL9602](INFO): SBD MO Status=1, MOMSN=19922, MT Status=0, MTMSN=0
2025-04-18T06:18:29.656Z,1744957109.656 [NAL9602](INFO): Sent 75 bytes from file Logs/20250418T051319/Courier0003.lzma
2025-04-18T06:18:29.656Z,1744957109.656 [NAL9602](INFO): Packets left to send: 0
2025-04-18T06:18:30.815Z,1744957110.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061831.00,A,3646.54962,N,12150.95218,W,0.641,11.50,180425,,,A*46
2025-04-18T06:18:30.818Z,1744957110.818 [NAL9602](INFO): GPS fix at 20250418T061831: (36.775827, -121.849203)
2025-04-18T06:18:30.867Z,1744957110.867 [keepstation:NeedComms:C] Stopped
2025-04-18T06:18:30.867Z,1744957110.867 [keepstation:NeedComms:D] Running Loop=1
2025-04-18T06:18:31.278Z,1744957111.278 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-18T06:18:39.839Z,1744957119.839 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250418T051319/Courier0006.lzma
2025-04-18T06:18:40.842Z,1744957120.842 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Courier0006.lzma.bak
2025-04-18T06:18:40.842Z,1744957120.842 [DataOverHttps](INFO): SBD MOMSN=24684983
2025-04-18T06:18:58.455Z,1744957138.455 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250418T051319/Courier0009.lzma
2025-04-18T06:18:59.457Z,1744957139.457 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Courier0009.lzma.bak
2025-04-18T06:18:59.457Z,1744957139.457 [DataOverHttps](INFO): SBD MOMSN=24684990
2025-04-18T06:19:02.762Z,1744957142.762 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T06:19:16.093Z,1744957156.093 [DataOverHttps](INFO): Sending 2299 bytes from file Logs/20250418T051319/Express0001.lzma
2025-04-18T06:19:18.093Z,1744957158.093 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Express0001.lzma.bak
2025-04-18T06:19:18.094Z,1744957158.094 [DataOverHttps](INFO): SBD MOMSN=24684996
2025-04-18T06:19:18.134Z,1744957158.134 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,3I
2025-04-18T06:19:18.134Z,1744957158.134 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,3I
2025-04-18T06:19:37.261Z,1744957177.261 [DataOverHttps](INFO): Sending 546 bytes from file Logs/20250418T051319/Express0004.lzma
2025-04-18T06:19:38.261Z,1744957178.261 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Express0004.lzma.bak
2025-04-18T06:19:38.262Z,1744957178.262 [DataOverHttps](INFO): SBD MOMSN=24685017
2025-04-18T06:19:56.856Z,1744957196.856 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20250418T051319/Express0007.lzma
2025-04-18T06:19:58.051Z,1744957198.051 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Express0007.lzma.bak
2025-04-18T06:19:58.051Z,1744957198.051 [DataOverHttps](INFO): SBD MOMSN=24685030
2025-04-18T06:20:14.599Z,1744957214.599 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20250418T051319/Express0010.lzma
2025-04-18T06:20:15.601Z,1744957215.601 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Express0010.lzma.bak
2025-04-18T06:20:15.601Z,1744957215.601 [DataOverHttps](INFO): SBD MOMSN=24685041
2025-04-18T06:20:17.129Z,1744957217.129 [keepstation:NeedComms:D] Stopped
2025-04-18T06:20:17.129Z,1744957217.129 [keepstation:NeedComms:E] Running Loop=1
2025-04-18T06:20:17.539Z,1744957217.539 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-18T06:20:19.134Z,1744957219.134 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062019.00,A,3646.56265,N,12150.93678,W,1.341,49.06,180425,,,A*47
2025-04-18T06:20:19.137Z,1744957219.137 [NAL9602](INFO): GPS fix at 20250418T062019: (36.776044, -121.848946)
2025-04-18T06:20:19.150Z,1744957219.150 [keepstation:NeedComms:E] Stopped
2025-04-18T06:20:19.151Z,1744957219.151 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-18T06:20:19.151Z,1744957219.151 [keepstation:NeedComms] Stopped
2025-04-18T06:20:19.151Z,1744957219.151 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-18T06:20:19.151Z,1744957219.151 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-18T06:20:19.151Z,1744957219.151 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T06:20:19.576Z,1744957219.576 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-18T06:20:19.576Z,1744957219.576 [keepstation:SurfaceComms:B] Stopped
2025-04-18T06:20:19.576Z,1744957219.576 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-18T06:20:19.577Z,1744957219.577 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-18T06:20:19.577Z,1744957219.577 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-18T06:20:19.577Z,1744957219.577 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-18T06:20:19.970Z,1744957219.970 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-18T06:20:19.971Z,1744957219.971 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-18T06:20:19.971Z,1744957219.971 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-18T06:20:19.971Z,1744957219.971 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-18T06:20:19.971Z,1744957219.971 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-18T06:20:19.975Z,1744957219.975 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-18T06:20:20.394Z,1744957220.394 [keepstation:SurfaceComms:setStation] Stopped
2025-04-18T06:20:20.394Z,1744957220.394 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-18T06:20:20.394Z,1744957220.394 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-18T06:20:20.394Z,1744957220.394 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-18T06:20:20.394Z,1744957220.394 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-18T06:20:20.394Z,1744957220.394 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-18T06:20:20.785Z,1744957220.785 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-18T06:20:20.786Z,1744957220.786 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-18T06:20:20.786Z,1744957220.786 [keepstation:SurfaceComms:setSink] Stopped
2025-04-18T06:20:20.786Z,1744957220.786 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-18T06:20:20.786Z,1744957220.786 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-18T06:20:20.786Z,1744957220.786 [keepstation:SurfaceComms] Stopped
2025-04-18T06:20:20.786Z,1744957220.786 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-18T06:20:21.193Z,1744957221.193 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-04-18T06:20:21.193Z,1744957221.193 [keepstation:StartingMission] Stopped
2025-04-18T06:20:21.193Z,1744957221.193 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-04-18T06:20:21.193Z,1744957221.193 [keepstation:TransitToStation] Running Loop=1
2025-04-18T06:20:21.193Z,1744957221.193 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-04-18T06:20:21.193Z,1744957221.193 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-18T06:20:21.194Z,1744957221.194 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-04-18T06:20:21.194Z,1744957221.194 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-04-18T06:20:21.194Z,1744957221.194 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-04-18T06:20:21.194Z,1744957221.194 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-04-18T06:20:21.194Z,1744957221.194 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-04-18T06:20:21.605Z,1744957221.605 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-18T06:20:21.605Z,1744957221.605 [keepstation:DiveCmd] Running Loop=1
2025-04-18T06:20:21.605Z,1744957221.605 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-04-18T06:20:21.605Z,1744957221.605 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-18T06:20:21.605Z,1744957221.605 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-18T06:20:21.606Z,1744957221.606 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-18T06:20:21.607Z,1744957221.607 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-18T06:20:21.607Z,1744957221.607 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-18T06:20:21.607Z,1744957221.607 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-18T06:20:21.607Z,1744957221.607 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T06:20:21.607Z,1744957221.607 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-18T06:20:21.607Z,1744957221.607 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-18T06:20:21.611Z,1744957221.611 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-18T06:20:21.611Z,1744957221.611 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-18T06:20:21.612Z,1744957221.612 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-18T06:20:21.613Z,1744957221.613 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-18T06:20:21.614Z,1744957221.614 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-18T06:20:21.615Z,1744957221.615 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-18T06:20:21.615Z,1744957221.615 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-18T06:20:21.615Z,1744957221.615 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-18T06:20:21.619Z,1744957221.619 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-18T06:20:21.619Z,1744957221.619 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-18T06:20:21.620Z,1744957221.620 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-18T06:20:22.802Z,1744957222.802 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-04-18T06:20:22.802Z,1744957222.802 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-04-18T06:20:22.802Z,1744957222.802 [keepstation:TransitToStation:C] Running Loop=1
2025-04-18T06:20:23.200Z,1744957223.200 [keepstation:TransitToStation:C] Stopped
2025-04-18T06:20:23.200Z,1744957223.200 [keepstation:TransitToStation:D] Running Loop=1
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-18T06:20:23.201Z,1744957223.201 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-18T06:20:23.202Z,1744957223.202 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-18T06:20:23.202Z,1744957223.202 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-18T06:20:23.202Z,1744957223.202 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-18T06:20:23.203Z,1744957223.203 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-18T06:20:23.203Z,1744957223.203 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-04-18T06:20:23.207Z,1744957223.207 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-04-18T06:20:23.207Z,1744957223.207 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-18T06:20:23.207Z,1744957223.207 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T06:20:23.208Z,1744957223.208 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-18T06:20:23.208Z,1744957223.208 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T06:20:23.208Z,1744957223.208 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-18T06:20:23.208Z,1744957223.208 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-18T06:20:23.208Z,1744957223.208 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-18T06:20:23.621Z,1744957223.621 [keepstation:TransitToStation:D] Stopped
2025-04-18T06:20:23.621Z,1744957223.621 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-04-18T06:20:23.621Z,1744957223.621 [keepstation:TransitToStation] Stopped
2025-04-18T06:20:23.621Z,1744957223.621 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-04-18T06:20:23.621Z,1744957223.621 [keepstation:TransitToStation:Dive] Stopped
2025-04-18T06:20:23.622Z,1744957223.622 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-04-18T06:20:23.622Z,1744957223.622 [keepstation:TransitToStation:Dive:A] Stopped
2025-04-18T06:20:23.622Z,1744957223.622 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-04-18T06:20:23.622Z,1744957223.622 [keepstation:OnStation] Running Loop=1
2025-04-18T06:20:23.622Z,1744957223.622 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-04-18T06:20:23.991Z,1744957223.991 [keepstation:NeedComms] Running Loop=1
2025-04-18T06:20:23.991Z,1744957223.991 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-18T06:20:23.991Z,1744957223.991 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-18T06:20:23.991Z,1744957223.991 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T06:20:23.992Z,1744957223.992 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-18T06:20:23.992Z,1744957223.992 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-18T06:20:23.992Z,1744957223.992 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-18T06:20:23.993Z,1744957223.993 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-18T06:20:23.993Z,1744957223.993 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T06:20:23.993Z,1744957223.993 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T06:20:23.994Z,1744957223.994 [keepstation:NeedComms:A] Running Loop=1
2025-04-18T06:20:23.995Z,1744957223.995 [keepstation:NeedComms:A](INFO): last time_fix was:  1744957219.000000 second since 1970/01/01T00:00:00Z 
2025-04-18T06:20:23.995Z,1744957223.995 [keepstation:NeedComms:A] Stopped
2025-04-18T06:20:24.420Z,1744957224.420 [keepstation:NeedComms:C] Running Loop=1
2025-04-18T06:20:32.054Z,1744957232.054 [NAL9602](INFO): SBD MO Status=0, MOMSN=19923, MT Status=0, MTMSN=0
2025-04-18T06:20:32.054Z,1744957232.054 [NAL9602](INFO): No messages in MT queue
2025-04-18T06:20:33.267Z,1744957233.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062034.00,A,3646.56395,N,12150.93489,W,0.233,13.98,180425,,,A*47
2025-04-18T06:20:33.269Z,1744957233.269 [NAL9602](INFO): GPS fix at 20250418T062034: (36.776066, -121.848915)
2025-04-18T06:20:33.346Z,1744957233.346 [keepstation:NeedComms:C] Stopped
2025-04-18T06:20:33.346Z,1744957233.346 [keepstation:NeedComms:D] Running Loop=1
2025-04-18T06:20:41.443Z,1744957241.443 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250418T051319/Courier0012.lzma
2025-04-18T06:20:42.445Z,1744957242.445 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Courier0012.lzma.bak
2025-04-18T06:20:42.445Z,1744957242.445 [DataOverHttps](INFO): SBD MOMSN=24685043
2025-04-18T06:20:59.019Z,1744957259.019 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250418T051319/Express0013.lzma
2025-04-18T06:21:00.021Z,1744957260.021 [DataOverHttps](INFO): Moved sent file to Logs/20250418T051319/Express0013.lzma.bak
2025-04-18T06:21:00.021Z,1744957260.021 [DataOverHttps](INFO): SBD MOMSN=24685046
2025-04-18T06:21:01.592Z,1744957261.592 [keepstation:NeedComms:D] Stopped
2025-04-18T06:21:01.592Z,1744957261.592 [keepstation:NeedComms:E] Running Loop=1
2025-04-18T06:21:03.581Z,1744957263.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,062104.00,A,3646.56748,N,12150.93072,W,0.836,52.60,180425,,,A*4C
2025-04-18T06:21:03.583Z,1744957263.583 [NAL9602](INFO): GPS fix at 20250418T062104: (36.776125, -121.848845)
2025-04-18T06:21:03.634Z,1744957263.634 [keepstation:NeedComms:E] Stopped
2025-04-18T06:21:03.643Z,1744957263.643 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-18T06:21:03.643Z,1744957263.643 [keepstation:NeedComms] Stopped
2025-04-18T06:21:03.644Z,1744957263.644 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-18T06:21:03.644Z,1744957263.644 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-18T06:21:03.644Z,1744957263.644 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T06:21:04.018Z,1744957264.018 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-04-18T06:21:04.018Z,1744957264.018 [keepstation:OnStation] Stopped
2025-04-18T06:21:04.018Z,1744957264.018 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-04-18T06:21:04.023Z,1744957264.023 [keepstation:KeepStation] Running Loop=1
2025-04-18T06:21:04.023Z,1744957264.023 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-04-18T06:21:04.023Z,1744957264.023 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-18T06:21:04.024Z,1744957264.024 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-18T06:21:04.024Z,1744957264.024 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-18T06:21:04.024Z,1744957264.024 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-18T06:21:04.024Z,1744957264.024 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-04-18T06:21:04.024Z,1744957264.024 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-04-18T06:21:04.432Z,1744957264.432 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-18T06:21:35.889Z,1744957295.889 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T06:23:04.464Z,1744957384.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T06:23:19.879Z,1744957399.879 [Radio_Surface](INFO): Powering down
2025-04-18T06:23:35.604Z,1744957415.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T06:23:36.608Z,1744957416.608 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-18T06:24:40.872Z,1744957480.872 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.579254
2025-04-18T06:36:19.849Z,1744958179.849 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00039,00,0038.90,000.00
2025-04-18T06:36:19.849Z,1744958179.849 [DVL_micro](ERROR): Failed to parse:
:BI,-00039,00,0038.90,000.00
2025-04-18T06:39:57.203Z,1744958397.203 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T06:39:57.603Z,1744958397.603 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 3.45 mm (1 active estimators).
2025-04-18T06:39:58.430Z,1744958398.430 [DVL_micro](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BI,-00178.80
2025-04-18T06:39:58.430Z,1744958398.430 [DVL_micro](ERROR): Failed to parse:
:BI,-00178.80
2025-04-18T06:43:11.239Z,1744958591.239 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:43:11.240Z,1744958591.240 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:43:11.240Z,1744958591.240 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:43:11.391Z,1744958591.391 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:43:11.682Z,1744958591.682 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:43:11.682Z,1744958591.682 [ElevatorServo](INFO): Powering down
2025-04-18T06:43:12.436Z,1744958592.436 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:43:12.560Z,1744958592.560 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:43:12.673Z,1744958592.673 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:43:12.673Z,1744958592.673 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T06:45:27.372Z,1744958727.372 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:45:27.372Z,1744958727.372 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:45:27.372Z,1744958727.372 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:45:27.503Z,1744958727.503 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:45:27.751Z,1744958727.751 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:45:27.751Z,1744958727.751 [ElevatorServo](INFO): Powering down
2025-04-18T06:45:28.556Z,1744958728.556 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:45:28.676Z,1744958728.676 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:45:28.806Z,1744958728.806 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:45:28.807Z,1744958728.807 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T06:46:42.560Z,1744958802.560 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:46:42.560Z,1744958802.560 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:46:42.560Z,1744958802.560 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:46:42.682Z,1744958802.682 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:46:42.947Z,1744958802.947 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:46:42.948Z,1744958802.948 [ElevatorServo](INFO): Powering down
2025-04-18T06:46:43.746Z,1744958803.746 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:46:43.861Z,1744958803.861 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:46:43.866Z,1744958803.866 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:46:43.866Z,1744958803.866 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T06:47:18.060Z,1744958838.060 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000,+00000,A
2025-04-18T06:47:18.060Z,1744958838.060 [DVL_micro](ERROR): Failed to parse:
:TS,00000,+00000,A
2025-04-18T06:47:24.948Z,1744958844.948 [DVL_micro](ERROR): Failed to parse:
:SA,+10.26,-02.02,206.9
2025-04-18T06:51:40.025Z,1744959100.025 [DVL_micro](ERROR): Failed to parse:
:BI,+00074,+00169,+00016,+00000,A
2025-04-18T06:53:30.791Z,1744959210.791 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2025-04-18T06:55:00.799Z,1744959300.799 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:55:00.799Z,1744959300.799 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:55:00.799Z,1744959300.799 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:55:00.991Z,1744959300.991 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:55:01.053Z,1744959301.053 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:55:01.053Z,1744959301.053 [ElevatorServo](INFO): Powering down
2025-04-18T06:55:01.889Z,1744959301.889 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:55:02.009Z,1744959302.009 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:55:02.079Z,1744959302.079 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:55:02.079Z,1744959302.079 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T06:58:55.983Z,1744959535.983 [ElevatorServo](FAULT): Overload Error
2025-04-18T06:58:55.984Z,1744959535.984 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T06:58:55.984Z,1744959535.984 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T06:58:56.094Z,1744959536.094 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T06:58:56.269Z,1744959536.269 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T06:58:56.269Z,1744959536.269 [ElevatorServo](INFO): Powering down
2025-04-18T06:58:57.141Z,1744959537.141 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T06:58:57.260Z,1744959537.260 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T06:58:57.345Z,1744959537.345 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T06:58:57.345Z,1744959537.345 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:02:48.231Z,1744959768.231 [ElevatorServo](FAULT): Overload Error
2025-04-18T07:02:48.232Z,1744959768.232 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T07:02:48.232Z,1744959768.232 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T07:02:48.359Z,1744959768.359 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T07:02:48.612Z,1744959768.612 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T07:02:48.612Z,1744959768.612 [ElevatorServo](INFO): Powering down
2025-04-18T07:02:49.433Z,1744959769.433 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T07:02:49.552Z,1744959769.552 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T07:02:49.557Z,1744959769.557 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T07:02:49.557Z,1744959769.557 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:03:53.298Z,1744959833.298 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-18T07:07:30.233Z,1744960050.233 [DVL_micro](ERROR): Failed to parse:
:WI,-00124,-00025,+00037,+00000,A
2025-04-18T07:10:03.760Z,1744960203.760 [DVL_micro](ERROR): Failed to parse:
:WI,+00048,+00140,+00028,+00000,A
2025-04-18T07:10:32.834Z,1744960232.834 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+0040.27,+0040.80,+0040.852
2025-04-18T07:10:32.834Z,1744960232.834 [DVL_micro](ERROR): Failed to parse:
:RD,+0040.27,+0040.80,+0040.852
2025-04-18T07:13:18.147Z,1744960398.147 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T07:13:18.148Z,1744960398.148 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-18T07:13:18.151Z,1744960398.151 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T07:16:32.070Z,1744960592.070 [DVL_micro](ERROR): Failed to parse:
:SA,-01.89,+00.49,167.7
2025-04-18T07:18:22.365Z,1744960702.365 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,-00160,64
2025-04-18T07:18:22.365Z,1744960702.365 [DVL_micro](ERROR): Failed to parse:
:BI,-00160,64
2025-04-18T07:22:05.770Z,1744960925.770 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0023.60,000.00
2025-04-18T07:30:25.529Z,1744961425.529 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T07:35:02.648Z,1744961702.648 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+9999.99,+0043.45,+00341.37
2025-04-18T07:35:02.648Z,1744961702.648 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0043.45,+00341.37
2025-04-18T07:35:29.395Z,1744961729.395 [ElevatorServo](FAULT): Overload Error
2025-04-18T07:35:29.396Z,1744961729.396 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T07:35:29.396Z,1744961729.396 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T07:35:29.584Z,1744961729.584 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T07:35:29.769Z,1744961729.769 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T07:35:29.769Z,1744961729.769 [ElevatorServo](INFO): Powering down
2025-04-18T07:35:30.555Z,1744961730.555 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T07:35:30.676Z,1744961730.676 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T07:35:30.684Z,1744961730.684 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T07:35:30.684Z,1744961730.684 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:37:12.136Z,1744961832.136 [ElevatorServo](FAULT): Overload Error
2025-04-18T07:37:12.136Z,1744961832.136 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T07:37:12.136Z,1744961832.136 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T07:37:12.155Z,1744961832.155 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T07:37:12.355Z,1744961832.355 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T07:37:12.355Z,1744961832.355 [ElevatorServo](INFO): Powering down
2025-04-18T07:37:13.186Z,1744961833.186 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T07:37:13.300Z,1744961833.300 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T07:37:13.305Z,1744961833.305 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T07:37:13.305Z,1744961833.305 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:37:27.783Z,1744961847.783 [ElevatorServo](FAULT): Overload Error
2025-04-18T07:37:27.783Z,1744961847.783 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T07:37:27.784Z,1744961847.784 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T07:37:28.079Z,1744961848.079 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T07:37:28.157Z,1744961848.157 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T07:37:28.157Z,1744961848.157 [ElevatorServo](INFO): Powering down
2025-04-18T07:37:28.949Z,1744961848.949 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T07:37:29.081Z,1744961849.081 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T07:37:29.086Z,1744961849.086 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T07:37:29.086Z,1744961849.086 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:41:21.349Z,1744962081.349 [ElevatorServo](FAULT): Overload Error
2025-04-18T07:41:21.349Z,1744962081.349 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T07:41:21.349Z,1744962081.349 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T07:41:21.667Z,1744962081.667 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T07:41:21.750Z,1744962081.750 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T07:41:21.750Z,1744962081.750 [ElevatorServo](INFO): Powering down
2025-04-18T07:41:22.539Z,1744962082.539 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T07:41:22.660Z,1744962082.660 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T07:41:22.783Z,1744962082.783 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T07:41:22.783Z,1744962082.783 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:41:35.482Z,1744962095.482 [DVL_micro](ERROR): Failed to parse:
:WI,-00053,+00528,+00002,+00000,A
2025-04-18T07:45:09.567Z,1744962309.567 [ElevatorServo](FAULT): Overload Error
2025-04-18T07:45:09.567Z,1744962309.567 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T07:45:09.567Z,1744962309.567 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T07:45:09.651Z,1744962309.651 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T07:45:09.787Z,1744962309.787 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T07:45:09.787Z,1744962309.787 [ElevatorServo](INFO): Powering down
2025-04-18T07:45:10.515Z,1744962310.515 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T07:45:10.637Z,1744962310.637 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T07:45:10.771Z,1744962310.771 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T07:45:10.771Z,1744962310.771 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T07:47:08.458Z,1744962428.458 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0000.0,1489.0,000
2025-04-18T07:47:08.458Z,1744962428.458 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0000.0,1489.0,000
2025-04-18T07:49:28.535Z,1744962568.535 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-18T07:49:33.126Z,1744962573.126 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.766845
CHAN A1 (24V): 0.006425
CHAN A2 (12V): -0.017795
CHAN A3 (5V): -0.140887
CHAN B0 (3.3V): -0.009306
CHAN B1 (3.15aV): -0.003409
CHAN B2 (3.15bV): -0.001855
CHAN B3 (GND): -0.007740
OPEN: 0.012313
Full Scale: +/- 1 mA
2025-04-18T07:50:09.674Z,1744962609.674 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+10.1,000073,+00000,A
2025-04-18T07:50:09.674Z,1744962609.674 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+10.1,000073,+00000,A
2025-04-18T07:51:36.654Z,1744962696.654 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-18T08:18:06.802Z,1744964286.802 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T08:18:37.636Z,1744964317.636 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0031.50,000.00
2025-04-18T08:19:15.168Z,1744964355.168 [DVL_micro](ERROR): Failed to parse:
:WI,+00036,+00389,-00056,+00000,A
2025-04-18T08:20:54.608Z,1744964454.608 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T08:20:54.609Z,1744964454.609 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-18T08:20:54.612Z,1744964454.612 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T08:21:03.914Z,1744964463.914 [keepstation:SurfaceComms] Running Loop=1
2025-04-18T08:21:03.914Z,1744964463.914 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-18T08:21:03.914Z,1744964463.914 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-18T08:21:03.915Z,1744964463.915 [keepstation:SurfaceComms:A] Stopped
2025-04-18T08:21:03.915Z,1744964463.915 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-18T08:21:03.915Z,1744964463.915 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-18T08:21:04.308Z,1744964464.308 [keepstation:KeepStation:Dive] Stopped
2025-04-18T08:21:04.308Z,1744964464.308 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-18T08:21:04.308Z,1744964464.308 [keepstation:KeepStation:Dive:A] Stopped
2025-04-18T08:21:04.308Z,1744964464.308 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-18T08:21:04.309Z,1744964464.309 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-18T08:21:04.309Z,1744964464.309 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-18T08:21:04.309Z,1744964464.309 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-18T08:21:04.309Z,1744964464.309 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-18T08:21:04.309Z,1744964464.309 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-18T08:21:04.309Z,1744964464.309 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-18T08:21:04.311Z,1744964464.311 [keepstation:NeedComms] Running Loop=1
2025-04-18T08:21:04.311Z,1744964464.311 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-18T08:21:04.311Z,1744964464.311 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-18T08:21:04.311Z,1744964464.311 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T08:21:04.312Z,1744964464.312 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-18T08:21:04.312Z,1744964464.312 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-18T08:21:04.312Z,1744964464.312 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-18T08:21:04.313Z,1744964464.313 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-18T08:21:04.313Z,1744964464.313 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T08:21:04.313Z,1744964464.313 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T08:21:04.314Z,1744964464.314 [keepstation:NeedComms:A] Running Loop=1
2025-04-18T08:21:04.316Z,1744964464.316 [keepstation:NeedComms:A](INFO): last time_fix was:  1744957264.000000 second since 1970/01/01T00:00:00Z 
2025-04-18T08:21:04.316Z,1744964464.316 [keepstation:NeedComms:A] Stopped
2025-04-18T08:21:06.384Z,1744964466.384 [ElevatorServo](FAULT): Overload Error
2025-04-18T08:21:06.384Z,1744964466.384 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T08:21:06.384Z,1744964466.384 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T08:21:06.671Z,1744964466.671 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T08:21:06.771Z,1744964466.771 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T08:21:06.771Z,1744964466.771 [ElevatorServo](INFO): Powering down
2025-04-18T08:21:07.566Z,1744964467.566 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T08:21:07.680Z,1744964467.680 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T08:21:07.712Z,1744964467.712 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T08:21:07.712Z,1744964467.712 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T08:21:34.642Z,1744964494.642 [DVL_micro](ERROR): Failed to parse:

2025-04-18T08:22:22.497Z,1744964542.497 [DVL_micro](ERROR): Failed to parse:
:SA,+27.39,-02.53,228.9
2025-04-18T08:22:35.829Z,1744964555.829 [keepstation:NeedComms:C] Running Loop=1
2025-04-18T08:22:36.580Z,1744964556.580 [Radio_Surface](INFO): Powering up
2025-04-18T08:22:42.196Z,1744964562.196 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-18T08:22:49.998Z,1744964569.998 [NAL9602](INFO): SBD MO Status=0, MOMSN=19924, MT Status=0, MTMSN=0
2025-04-18T08:22:49.998Z,1744964569.998 [NAL9602](INFO): No messages in MT queue
2025-04-18T08:23:12.316Z,1744964592.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T08:23:18.287Z,1744964598.287 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,082319.00,A,3646.64417,N,12150.90657,W,0.311,24.79,180425,,,A*41
2025-04-18T08:23:18.289Z,1744964598.289 [NAL9602](INFO): GPS fix at 20250418T082319: (36.777403, -121.848443)
2025-04-18T08:23:18.302Z,1744964598.302 [UniversalFixResidualReporter](INFO): Fix residual: 3.5 %DT, over the last 2173.6 m. Residual distance 76.1 m at bearing 128.9 degrees. Fix at (36.7774, -121.8484) with 146.6 m made good.
2025-04-18T08:23:18.305Z,1744964598.305 [keepstation:NeedComms:C] Stopped
2025-04-18T08:23:18.306Z,1744964598.306 [keepstation:NeedComms:D] Running Loop=1
2025-04-18T08:23:43.442Z,1744964623.442 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T08:23:58.783Z,1744964638.783 [NAL9602](INFO): SBD MO Status=1, MOMSN=19925, MT Status=0, MTMSN=0
2025-04-18T08:23:58.840Z,1744964638.840 [NAL9602](INFO): Sent 97 bytes from file Logs/20250418T051319/Courier0015.lzma
2025-04-18T08:23:58.841Z,1744964638.841 [NAL9602](INFO): Packets left to send: 0
2025-04-18T08:24:09.572Z,1744964649.572 [NAL9602](INFO): SBD MO Status=1, MOMSN=19926, MT Status=0, MTMSN=0
2025-04-18T08:24:09.621Z,1744964649.621 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0016.lzma
2025-04-18T08:24:09.621Z,1744964649.621 [NAL9602](INFO): Packets left to send: 3
2025-04-18T08:24:14.572Z,1744964654.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T08:24:18.241Z,1744964658.241 [NAL9602](INFO): SBD MO Status=1, MOMSN=19927, MT Status=0, MTMSN=0
2025-04-18T08:24:18.292Z,1744964658.292 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0016.lzma
2025-04-18T08:24:18.292Z,1744964658.292 [NAL9602](INFO): Packets left to send: 2
2025-04-18T08:24:22.929Z,1744964662.929 [DVL_micro](ERROR): Failed to parse:
:SA,-00.15,-02.48,223.1
2025-04-18T08:24:27.788Z,1744964667.788 [NAL9602](INFO): SBD MO Status=1, MOMSN=19928, MT Status=0, MTMSN=0
2025-04-18T08:24:27.840Z,1744964667.840 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0016.lzma
2025-04-18T08:24:27.840Z,1744964667.840 [NAL9602](INFO): Packets left to send: 1
2025-04-18T08:24:38.465Z,1744964678.465 [NAL9602](INFO): SBD MO Status=1, MOMSN=19929, MT Status=0, MTMSN=0
2025-04-18T08:24:38.520Z,1744964678.520 [NAL9602](INFO): Sent 131 bytes from file Logs/20250418T051319/Express0016.lzma
2025-04-18T08:24:38.520Z,1744964678.520 [NAL9602](INFO): Packets left to send: 0
2025-04-18T08:24:45.696Z,1744964685.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T08:24:49.868Z,1744964689.868 [NAL9602](INFO): SBD MO Status=0, MOMSN=19930, MT Status=0, MTMSN=0
2025-04-18T08:24:49.953Z,1744964689.953 [keepstation:NeedComms:D] Stopped
2025-04-18T08:24:49.954Z,1744964689.954 [keepstation:NeedComms:E] Running Loop=1
2025-04-18T08:24:52.243Z,1744964692.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,082453.00,A,3646.64702,N,12150.89946,W,0.156,90.51,180425,,,A*4C
2025-04-18T08:24:52.245Z,1744964692.245 [NAL9602](INFO): GPS fix at 20250418T082453: (36.777450, -121.848324)
2025-04-18T08:24:52.306Z,1744964692.306 [keepstation:NeedComms:E] Stopped
2025-04-18T08:24:52.316Z,1744964692.316 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-18T08:24:52.316Z,1744964692.316 [keepstation:NeedComms] Stopped
2025-04-18T08:24:52.316Z,1744964692.316 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-18T08:24:52.316Z,1744964692.316 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-18T08:24:52.316Z,1744964692.316 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T08:24:52.702Z,1744964692.702 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-18T08:24:52.703Z,1744964692.703 [keepstation:SurfaceComms:B] Stopped
2025-04-18T08:24:52.703Z,1744964692.703 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-18T08:24:52.703Z,1744964692.703 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-18T08:24:52.703Z,1744964692.703 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-18T08:24:52.703Z,1744964692.703 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-18T08:24:53.075Z,1744964693.075 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-18T08:24:53.075Z,1744964693.075 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-18T08:24:53.075Z,1744964693.075 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-18T08:24:53.075Z,1744964693.075 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-18T08:24:53.075Z,1744964693.075 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-18T08:24:53.076Z,1744964693.076 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-18T08:24:53.480Z,1744964693.480 [keepstation:SurfaceComms:setStation] Stopped
2025-04-18T08:24:53.480Z,1744964693.480 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-18T08:24:53.481Z,1744964693.481 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-18T08:24:53.481Z,1744964693.481 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-18T08:24:53.481Z,1744964693.481 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-18T08:24:53.481Z,1744964693.481 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms:setSink] Stopped
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms] Stopped
2025-04-18T08:24:53.878Z,1744964693.878 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-18T08:24:54.286Z,1744964694.286 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-18T08:24:54.286Z,1744964694.286 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-18T08:24:54.286Z,1744964694.286 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-18T08:24:54.286Z,1744964694.286 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-18T08:24:54.287Z,1744964694.287 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-18T08:24:54.287Z,1744964694.287 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-18T08:24:54.287Z,1744964694.287 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-18T08:24:54.287Z,1744964694.287 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T08:24:54.288Z,1744964694.288 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-18T08:24:54.288Z,1744964694.288 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T08:24:54.288Z,1744964694.288 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-18T08:24:54.288Z,1744964694.288 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-18T08:24:54.288Z,1744964694.288 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-18T08:24:54.288Z,1744964694.288 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-18T08:24:54.289Z,1744964694.289 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-18T08:24:54.289Z,1744964694.289 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-18T08:24:54.289Z,1744964694.289 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-18T08:24:54.448Z,1744964694.448 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003308
2025-04-18T08:25:24.971Z,1744964724.971 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T08:26:48.936Z,1744964808.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T08:27:04.056Z,1744964824.056 [Radio_Surface](INFO): Powering down
2025-04-18T08:27:20.076Z,1744964840.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T08:27:21.080Z,1744964841.080 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-18T08:28:26.340Z,1744964906.340 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.854340
2025-04-18T08:28:36.482Z,1744964916.482 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-18T08:28:36.482Z,1744964916.482 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-18T08:28:36.482Z,1744964916.482 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-18T08:28:36.482Z,1744964916.482 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-18T08:28:36.881Z,1744964916.881 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-18T08:28:36.881Z,1744964916.881 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-18T08:28:36.881Z,1744964916.881 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-18T08:28:36.881Z,1744964916.881 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-18T08:28:36.881Z,1744964916.881 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T08:28:36.881Z,1744964916.881 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-18T08:28:37.309Z,1744964917.309 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-18T08:28:37.309Z,1744964917.309 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T08:28:37.309Z,1744964917.309 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-18T08:28:37.309Z,1744964917.309 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-18T08:28:37.310Z,1744964917.310 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-18T08:28:37.689Z,1744964917.689 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-18T08:28:37.689Z,1744964917.689 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-18T08:28:37.689Z,1744964917.689 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-18T08:28:37.689Z,1744964917.689 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-18T08:28:37.689Z,1744964917.689 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-18T08:28:37.690Z,1744964917.690 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-18T08:28:37.690Z,1744964917.690 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-18T08:29:50.007Z,1744964990.007 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-18T08:32:16.250Z,1744965136.250 [DVL_micro](ERROR): Failed to parse:
:SA,-00.20,-00.20,139.6
2025-04-18T08:34:24.717Z,1744965264.717 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00201,-00156,+0
2025-04-18T08:34:24.718Z,1744965264.718 [DVL_micro](ERROR): Failed to parse:
:BI,-00201,-00156,+0
2025-04-18T08:47:45.470Z,1744966065.470 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T08:48:06.239Z,1744966086.239 [DVL_micro](ERROR): Failed to parse:
:SA,+00.03,+02.75,096.8
2025-04-18T08:51:20.297Z,1744966280.297 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-18T08:51:20.298Z,1744966280.298 [ThrusterHE](INFO): Powering down
2025-04-18T08:51:21.089Z,1744966281.089 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-18T08:51:21.211Z,1744966281.211 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-18T08:54:37.777Z,1744966477.777 [DVL_micro](ERROR): Failed to parse:
:WI,-00053,+00505,+00068,+00000,A
2025-04-18T08:55:04.196Z,1744966504.196 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-18T08:55:04.197Z,1744966504.197 [ThrusterHE](INFO): Powering down
2025-04-18T08:55:05.029Z,1744966505.029 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-18T08:55:05.152Z,1744966505.152 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-18T08:56:18.911Z,1744966578.911 [ElevatorServo](FAULT): Overload Error
2025-04-18T08:56:18.912Z,1744966578.912 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T08:56:18.912Z,1744966578.912 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T08:56:19.233Z,1744966579.233 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T08:56:19.338Z,1744966579.338 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T08:56:19.338Z,1744966579.338 [ElevatorServo](INFO): Powering down
2025-04-18T08:56:20.105Z,1744966580.105 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T08:56:20.225Z,1744966580.225 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T08:56:20.467Z,1744966580.467 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T08:56:20.467Z,1744966580.467 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T08:56:39.579Z,1744966599.579 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-04-18T08:56:39.579Z,1744966599.579 [DVL_micro] Communications Fault, FailCount= 1
2025-04-18T08:56:39.580Z,1744966599.580 [DVL_micro](ERROR): Communications Fault
2025-04-18T08:56:39.580Z,1744966599.580 [DVL_micro](ERROR): Failed to parse:
2025-04-18T08:56:39.687Z,1744966599.687 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-04-18T08:56:39.000Z,1744966600.000 [DVL_micro](INFO): Powering down
2025-04-18T08:56:40.819Z,1744966600.819 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-04-18T08:56:40.819Z,1744966600.819 [DVL_micro] No Fault, FailCount= 1
2025-04-18T09:04:07.836Z,1744967047.836 [ElevatorServo](FAULT): Overload Error
2025-04-18T09:04:07.836Z,1744967047.836 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T09:04:07.836Z,1744967047.836 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T09:04:07.955Z,1744967047.955 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T09:04:08.203Z,1744967048.203 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T09:04:08.203Z,1744967048.203 [ElevatorServo](INFO): Powering down
2025-04-18T09:04:09.037Z,1744967049.037 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T09:04:09.156Z,1744967049.156 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T09:04:09.161Z,1744967049.161 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T09:04:09.161Z,1744967049.161 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T09:06:22.355Z,1744967182.355 [DVL_micro](ERROR): Failed to parse:
:WI,+00026,+00636,-00015,+00000,A
2025-04-18T09:08:05.151Z,1744967285.151 [ElevatorServo](FAULT): Overload Error
2025-04-18T09:08:05.152Z,1744967285.152 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T09:08:05.152Z,1744967285.152 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T09:08:05.227Z,1744967285.227 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T09:08:05.429Z,1744967285.429 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T09:08:05.429Z,1744967285.429 [ElevatorServo](INFO): Powering down
2025-04-18T09:08:06.208Z,1744967286.208 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T09:08:06.325Z,1744967286.325 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T09:08:06.332Z,1744967286.332 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T09:08:06.332Z,1744967286.332 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T09:08:31.011Z,1744967311.011 [ElevatorServo](FAULT): Overload Error
2025-04-18T09:08:31.011Z,1744967311.011 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T09:08:31.011Z,1744967311.011 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T09:08:31.083Z,1744967311.083 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T09:08:31.300Z,1744967311.300 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T09:08:31.300Z,1744967311.300 [ElevatorServo](INFO): Powering down
2025-04-18T09:08:32.076Z,1744967312.076 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T09:08:32.193Z,1744967312.193 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T09:08:32.199Z,1744967312.199 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T09:08:32.200Z,1744967312.200 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T09:12:18.139Z,1744967538.139 [ElevatorServo](FAULT): Overload Error
2025-04-18T09:12:18.139Z,1744967538.139 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T09:12:18.139Z,1744967538.139 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T09:12:18.327Z,1744967538.327 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T09:12:18.533Z,1744967538.533 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T09:12:18.533Z,1744967538.533 [ElevatorServo](INFO): Powering down
2025-04-18T09:12:19.319Z,1744967539.319 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T09:12:19.441Z,1744967539.441 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T09:12:19.453Z,1744967539.453 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T09:12:19.453Z,1744967539.453 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T09:13:55.508Z,1744967635.508 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-18T09:28:28.176Z,1744968508.176 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T09:28:28.177Z,1744968508.177 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-04-18T09:28:28.183Z,1744968508.183 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T09:33:55.028Z,1744968835.028 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+0039.02,+0039.30,+99998.93
2025-04-18T09:33:55.028Z,1744968835.028 [DVL_micro](ERROR): Failed to parse:
:RD,+0039.02,+0039.30,+99998.93
2025-04-18T09:38:36.589Z,1744969116.589 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0038.30,+0029.12,+0037.74
2025-04-18T09:50:00.977Z,1744969800.977 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T09:51:29.032Z,1744969889.032 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0032.10,000.00
2025-04-18T09:57:10.491Z,1744970230.491 [ElevatorServo](FAULT): Overload Error
2025-04-18T09:57:10.492Z,1744970230.492 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T09:57:10.492Z,1744970230.492 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T09:57:10.618Z,1744970230.618 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T09:57:10.861Z,1744970230.861 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T09:57:10.861Z,1744970230.861 [ElevatorServo](INFO): Powering down
2025-04-18T09:57:11.660Z,1744970231.660 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T09:57:11.780Z,1744970231.780 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T09:57:11.859Z,1744970231.859 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T09:57:11.859Z,1744970231.859 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T09:58:44.961Z,1744970324.961 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0038.12,+9999.99,+0035.64
2025-04-18T10:04:27.068Z,1744970667.068 [ElevatorServo](FAULT): Overload Error
2025-04-18T10:04:27.068Z,1744970667.068 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T10:04:27.068Z,1744970667.068 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T10:04:27.151Z,1744970667.151 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T10:04:27.338Z,1744970667.338 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T10:04:27.338Z,1744970667.338 [ElevatorServo](INFO): Powering down
2025-04-18T10:04:28.177Z,1744970668.177 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T10:04:28.292Z,1744970668.292 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T10:04:28.450Z,1744970668.450 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T10:04:28.451Z,1744970668.451 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T10:10:59.700Z,1744971059.700 [DVL_micro](ERROR): Failed to parse:
2,+00000,A
2025-04-18T10:11:28.395Z,1744971088.395 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-18T10:21:31.965Z,1744971691.965 [DVL_micro](ERROR): Failed to parse:
:WI,+00082,-00116,-00037,+00000,A
2025-04-18T10:24:52.375Z,1744971892.375 [keepstation:SurfaceComms] Running Loop=1
2025-04-18T10:24:52.375Z,1744971892.375 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-18T10:24:52.376Z,1744971892.376 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-18T10:24:52.376Z,1744971892.376 [keepstation:SurfaceComms:A] Stopped
2025-04-18T10:24:52.377Z,1744971892.377 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-18T10:24:52.383Z,1744971892.383 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-18T10:24:52.758Z,1744971892.758 [keepstation:KeepStation:Dive] Stopped
2025-04-18T10:24:52.758Z,1744971892.758 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-18T10:24:52.758Z,1744971892.758 [keepstation:KeepStation:Dive:A] Stopped
2025-04-18T10:24:52.758Z,1744971892.758 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-18T10:24:52.759Z,1744971892.759 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-18T10:24:52.759Z,1744971892.759 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-18T10:24:52.759Z,1744971892.759 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-18T10:24:52.760Z,1744971892.760 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-18T10:24:52.760Z,1744971892.760 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-18T10:24:52.760Z,1744971892.760 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-18T10:24:52.762Z,1744971892.762 [keepstation:NeedComms] Running Loop=1
2025-04-18T10:24:52.762Z,1744971892.762 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-18T10:24:52.762Z,1744971892.762 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-18T10:24:52.762Z,1744971892.762 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T10:24:52.762Z,1744971892.762 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-18T10:24:52.763Z,1744971892.763 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-18T10:24:52.763Z,1744971892.763 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-18T10:24:52.764Z,1744971892.764 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-18T10:24:52.764Z,1744971892.764 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T10:24:52.764Z,1744971892.764 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T10:24:52.765Z,1744971892.765 [keepstation:NeedComms:A] Running Loop=1
2025-04-18T10:24:52.766Z,1744971892.766 [keepstation:NeedComms:A](INFO): last time_fix was:  1744964693.000000 second since 1970/01/01T00:00:00Z 
2025-04-18T10:24:52.766Z,1744971892.766 [keepstation:NeedComms:A] Stopped
2025-04-18T10:26:53.205Z,1744972013.205 [keepstation:NeedComms:C] Running Loop=1
2025-04-18T10:26:53.684Z,1744972013.684 [Radio_Surface](INFO): Powering up
2025-04-18T10:26:59.756Z,1744972019.756 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-18T10:27:00.067Z,1744972020.067 [DVL_micro](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BI,-00268

2025-04-18T10:27:00.067Z,1744972020.067 [DVL_micro](ERROR): Failed to parse:
:BI,-00268

2025-04-18T10:27:17.023Z,1744972037.023 [NAL9602](INFO): SBD MO Status=0, MOMSN=19931, MT Status=0, MTMSN=0
2025-04-18T10:27:17.023Z,1744972037.023 [NAL9602](INFO): No messages in MT queue
2025-04-18T10:27:29.892Z,1744972049.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:27:30.768Z,1744972050.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102731.00,A,3646.39509,N,12150.97280,W,0.428,0.00,180425,,,A*7C
2025-04-18T10:27:30.770Z,1744972050.770 [NAL9602](INFO): GPS fix at 20250418T102731: (36.773252, -121.849547)
2025-04-18T10:27:30.784Z,1744972050.784 [UniversalFixResidualReporter](INFO): Fix residual: 18.8 %DT, over the last 2169.3 m. Residual distance 406.8 m at bearing 19.2 degrees. Fix at (36.7733, -121.8495) with 479.4 m made good.
2025-04-18T10:27:30.787Z,1744972050.787 [keepstation:NeedComms:C] Stopped
2025-04-18T10:27:30.788Z,1744972050.788 [keepstation:NeedComms:D] Running Loop=1
2025-04-18T10:27:44.950Z,1744972064.950 [NAL9602](INFO): SBD MO Status=1, MOMSN=19932, MT Status=0, MTMSN=0
2025-04-18T10:27:45.008Z,1744972065.008 [NAL9602](INFO): Sent 97 bytes from file Logs/20250418T051319/Courier0018.lzma
2025-04-18T10:27:45.008Z,1744972065.008 [NAL9602](INFO): Packets left to send: 0
2025-04-18T10:27:56.524Z,1744972076.524 [NAL9602](INFO): SBD MO Status=1, MOMSN=19933, MT Status=0, MTMSN=0
2025-04-18T10:27:56.569Z,1744972076.569 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0019.lzma
2025-04-18T10:27:56.570Z,1744972076.570 [NAL9602](INFO): Packets left to send: 2
2025-04-18T10:28:01.016Z,1744972081.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:28:06.421Z,1744972086.421 [NAL9602](INFO): SBD MO Status=1, MOMSN=19934, MT Status=0, MTMSN=0
2025-04-18T10:28:06.480Z,1744972086.480 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0019.lzma
2025-04-18T10:28:06.480Z,1744972086.480 [NAL9602](INFO): Packets left to send: 1
2025-04-18T10:28:14.342Z,1744972094.342 [NAL9602](INFO): SBD MO Status=1, MOMSN=19935, MT Status=0, MTMSN=0
2025-04-18T10:28:14.400Z,1744972094.400 [NAL9602](INFO): Sent 167 bytes from file Logs/20250418T051319/Express0019.lzma
2025-04-18T10:28:14.400Z,1744972094.400 [NAL9602](INFO): Packets left to send: 0
2025-04-18T10:28:23.301Z,1744972103.301 [NAL9602](INFO): SBD MO Status=0, MOMSN=19936, MT Status=0, MTMSN=0
2025-04-18T10:28:23.405Z,1744972103.405 [keepstation:NeedComms:D] Stopped
2025-04-18T10:28:23.405Z,1744972103.405 [keepstation:NeedComms:E] Running Loop=1
2025-04-18T10:28:25.715Z,1744972105.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102826.00,A,3646.39134,N,12150.97327,W,0.408,162.21,180425,,,A*77
2025-04-18T10:28:25.717Z,1744972105.717 [NAL9602](INFO): GPS fix at 20250418T102826: (36.773189, -121.849554)
2025-04-18T10:28:25.731Z,1744972105.731 [keepstation:NeedComms:E] Stopped
2025-04-18T10:28:25.733Z,1744972105.733 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-18T10:28:25.733Z,1744972105.733 [keepstation:NeedComms] Stopped
2025-04-18T10:28:25.733Z,1744972105.733 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-18T10:28:25.733Z,1744972105.733 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-18T10:28:25.733Z,1744972105.733 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T10:28:26.176Z,1744972106.176 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-18T10:28:26.176Z,1744972106.176 [keepstation:SurfaceComms:B] Stopped
2025-04-18T10:28:26.176Z,1744972106.176 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-18T10:28:26.176Z,1744972106.176 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-18T10:28:26.176Z,1744972106.176 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-18T10:28:26.176Z,1744972106.176 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-18T10:28:26.542Z,1744972106.542 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-18T10:28:26.542Z,1744972106.542 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-18T10:28:26.542Z,1744972106.542 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-18T10:28:26.542Z,1744972106.542 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-18T10:28:26.542Z,1744972106.542 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-18T10:28:26.542Z,1744972106.542 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-18T10:28:26.949Z,1744972106.949 [keepstation:SurfaceComms:setStation] Stopped
2025-04-18T10:28:26.950Z,1744972106.950 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-18T10:28:26.950Z,1744972106.950 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-18T10:28:26.950Z,1744972106.950 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-18T10:28:26.950Z,1744972106.950 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-18T10:28:26.950Z,1744972106.950 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-18T10:28:27.378Z,1744972107.378 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-18T10:28:27.379Z,1744972107.379 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-18T10:28:27.379Z,1744972107.379 [keepstation:SurfaceComms:setSink] Stopped
2025-04-18T10:28:27.379Z,1744972107.379 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-18T10:28:27.379Z,1744972107.379 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-18T10:28:27.379Z,1744972107.379 [keepstation:SurfaceComms] Stopped
2025-04-18T10:28:27.379Z,1744972107.379 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-18T10:28:27.747Z,1744972107.747 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-18T10:28:27.747Z,1744972107.747 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-18T10:28:27.748Z,1744972107.748 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-18T10:28:27.748Z,1744972107.748 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-18T10:28:27.748Z,1744972107.748 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-18T10:28:27.748Z,1744972107.748 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-18T10:28:27.749Z,1744972107.749 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-18T10:28:27.750Z,1744972107.750 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-18T10:28:27.750Z,1744972107.750 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-18T10:28:27.750Z,1744972107.750 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-18T10:28:27.750Z,1744972107.750 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-18T10:28:32.140Z,1744972112.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:28:58.042Z,1744972138.042 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T10:29:03.264Z,1744972143.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:29:12.246Z,1744972152.246 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003330
2025-04-18T10:30:04.468Z,1744972204.468 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:30:35.592Z,1744972235.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:30:40.763Z,1744972240.763 [Radio_Surface](INFO): Powering down
2025-04-18T10:31:06.728Z,1744972266.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T10:31:07.732Z,1744972267.732 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-18T10:31:49.340Z,1744972309.340 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-18T10:31:49.340Z,1744972309.340 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-18T10:31:49.340Z,1744972309.340 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-18T10:31:49.340Z,1744972309.340 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-18T10:31:49.752Z,1744972309.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-18T10:31:49.752Z,1744972309.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-18T10:31:49.752Z,1744972309.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-18T10:31:49.752Z,1744972309.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-18T10:31:49.752Z,1744972309.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T10:31:49.752Z,1744972309.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-18T10:31:50.220Z,1744972310.220 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-18T10:31:50.220Z,1744972310.220 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T10:31:50.220Z,1744972310.220 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T10:31:50.221Z,1744972310.221 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-18T10:31:50.222Z,1744972310.222 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-18T10:31:50.222Z,1744972310.222 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-18T10:31:50.556Z,1744972310.556 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-18T10:31:50.556Z,1744972310.556 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-18T10:31:50.556Z,1744972310.556 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-18T10:31:50.556Z,1744972310.556 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-18T10:31:50.556Z,1744972310.556 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-18T10:31:50.557Z,1744972310.557 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-18T10:31:50.557Z,1744972310.557 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-18T10:32:12.992Z,1744972332.992 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.748657
2025-04-18T10:36:06.277Z,1744972566.277 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T10:36:06.278Z,1744972566.278 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-18T10:36:06.297Z,1744972566.297 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T10:52:01.344Z,1744973521.344 [DVL_micro](ERROR): Failed to parse:
:SA,-00.83,+00.59,103.5
2025-04-18T10:52:19.510Z,1744973539.510 [DVL_micro](ERROR): Failed to parse:
:BI,-00169,-00014,-00042,+00000,A
2025-04-18T10:55:47.993Z,1744973747.993 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T10:58:42.019Z,1744973922.019 [ElevatorServo](FAULT): Overload Error
2025-04-18T10:58:42.019Z,1744973922.019 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T10:58:42.020Z,1744973922.020 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T10:58:42.144Z,1744973922.144 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T10:58:42.386Z,1744973922.386 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T10:58:42.386Z,1744973922.386 [ElevatorServo](INFO): Powering down
2025-04-18T10:58:42.787Z,1744973922.787 [ElevatorServo](FAULT): LCB fault:  Current Limiter Activated.
2025-04-18T10:58:43.225Z,1744973923.225 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T10:58:43.343Z,1744973923.343 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T10:58:43.423Z,1744973923.423 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T10:58:43.423Z,1744973923.423 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T11:02:45.035Z,1744974165.035 [ElevatorServo](FAULT): Overload Error
2025-04-18T11:02:45.035Z,1744974165.035 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T11:02:45.035Z,1744974165.035 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T11:02:45.109Z,1744974165.109 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T11:02:45.314Z,1744974165.314 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T11:02:45.314Z,1744974165.314 [ElevatorServo](INFO): Powering down
2025-04-18T11:02:46.147Z,1744974166.147 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T11:02:46.269Z,1744974166.269 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T11:02:46.273Z,1744974166.273 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T11:02:46.273Z,1744974166.273 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T11:04:50.918Z,1744974290.918 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,+00545,+00064,+00000,A
2025-04-18T11:04:50.918Z,1744974290.918 [DVL_micro](ERROR): Failed to parse:
:TS,+00545,+00064,+00000,A
2025-04-18T11:06:31.931Z,1744974391.931 [DVL_micro](ERROR): Failed to parse:
:SA,-16.60,-01.81,169.7
2025-04-18T11:10:56.592Z,1744974656.592 [DVL_micro](ERROR): Failed to parse:
:WI,+00102,+00397,-00025,+00000,A
2025-04-18T11:14:15.544Z,1744974855.544 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-18T11:14:15.955Z,1744974855.955 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 3.45 mm.
2025-04-18T11:17:17.735Z,1744975037.735 [DVL_micro](ERROR): Failed to parse:
:SA,+00.47,+00.49,172.0
2025-04-18T11:26:54.246Z,1744975614.246 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+090,1489.0,000
2025-04-18T11:26:54.246Z,1744975614.246 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+090,1489.0,000
2025-04-18T11:29:31.405Z,1744975771.405 [DVL_micro](ERROR): Failed to parse:
:WI,-00152,-00054,+00031,+00000,A
2025-04-18T11:37:02.675Z,1744976222.675 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00000000.00,+00000000.00,0023.10,000.00
2025-04-18T11:37:02.675Z,1744976222.675 [DVL_micro](ERROR): Failed to parse:
:BI,-00000000.00,+00000000.00,0023.10,000.00
2025-04-18T11:37:14.003Z,1744976234.003 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-04-18T11:43:41.823Z,1744976621.823 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T11:43:41.824Z,1744976621.824 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-04-18T11:43:41.827Z,1744976621.827 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T11:48:06.038Z,1744976886.038 [DVL_micro](ERROR): Failed to parse:
:RD,+0027.87,+0027.80,+9999.99,+0028.55
2025-04-18T11:54:01.594Z,1744977241.594 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T11:54:01.976Z,1744977241.976 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 3.45 mm (1 active estimators).
2025-04-18T11:54:35.898Z,1744977275.898 [DVL_micro](ERROR): Failed to parse:
:RD,+0030.70,+0030.77,+9999.99,+0033.62
2025-04-18T11:57:44.451Z,1744977464.451 [ElevatorServo](FAULT): Overload Error
2025-04-18T11:57:44.451Z,1744977464.451 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T11:57:44.452Z,1744977464.452 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T11:57:44.627Z,1744977464.627 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T11:57:44.873Z,1744977464.873 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T11:57:44.873Z,1744977464.873 [ElevatorServo](INFO): Powering down
2025-04-18T11:57:45.633Z,1744977465.633 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T11:57:45.749Z,1744977465.749 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T11:57:45.755Z,1744977465.755 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T11:57:45.755Z,1744977465.755 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T12:00:57.594Z,1744977657.594 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,+00101,+000,A
2025-04-18T12:00:57.594Z,1744977657.594 [DVL_micro](ERROR): Failed to parse:
:BI,+00101,+000,A
2025-04-18T12:02:17.343Z,1744977737.343 [ElevatorServo](FAULT): Overload Error
2025-04-18T12:02:17.343Z,1744977737.343 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T12:02:17.344Z,1744977737.344 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T12:02:17.427Z,1744977737.427 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T12:02:17.603Z,1744977737.603 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T12:02:17.603Z,1744977737.603 [ElevatorServo](INFO): Powering down
2025-04-18T12:02:18.404Z,1744977738.404 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T12:02:18.524Z,1744977738.524 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T12:02:18.529Z,1744977738.529 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T12:02:18.529Z,1744977738.529 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T12:06:33.767Z,1744977993.767 [ElevatorServo](FAULT): Overload Error
2025-04-18T12:06:33.768Z,1744977993.768 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T12:06:33.768Z,1744977993.768 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T12:06:33.892Z,1744977993.892 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T12:06:34.150Z,1744977994.150 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T12:06:34.150Z,1744977994.150 [ElevatorServo](INFO): Powering down
2025-04-18T12:06:34.985Z,1744977994.985 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T12:06:35.100Z,1744977995.100 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T12:06:35.105Z,1744977995.105 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T12:06:35.105Z,1744977995.105 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T12:08:35.804Z,1744978115.804 [DVL_micro](ERROR): Failed to parse:
:BI,+00069,+00443,+00005,+00000,I
2025-04-18T12:11:08.975Z,1744978268.975 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-18T12:18:50.412Z,1744978730.412 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,+003

2025-04-18T12:18:50.412Z,1744978730.412 [DVL_micro](ERROR): Failed to parse:
:RD,+003

2025-04-18T12:19:58.277Z,1744978798.277 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.085,+00000,A
2025-04-18T12:19:58.277Z,1744978798.277 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.085,+00000,A
2025-04-18T12:20:54.030Z,1744978854.030 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -54
Device respsone is::TS,00000000000000,35.0,+09.8,00004,-00124,-00054,+00000,A
2025-04-18T12:20:54.032Z,1744978854.032 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0023.70,000.00
2025-04-18T12:27:27.534Z,1744979247.534 [DVL_micro](ERROR): Failed to parse:
:SA,-01.40,+00.45,178.4
2025-04-18T12:28:26.131Z,1744979306.131 [keepstation:SurfaceComms] Running Loop=1
2025-04-18T12:28:26.131Z,1744979306.131 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-18T12:28:26.131Z,1744979306.131 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-18T12:28:26.132Z,1744979306.132 [keepstation:SurfaceComms:A] Stopped
2025-04-18T12:28:26.132Z,1744979306.132 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-18T12:28:26.132Z,1744979306.132 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-18T12:28:26.518Z,1744979306.518 [keepstation:KeepStation:Dive] Stopped
2025-04-18T12:28:26.518Z,1744979306.518 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-18T12:28:26.518Z,1744979306.518 [keepstation:KeepStation:Dive:A] Stopped
2025-04-18T12:28:26.518Z,1744979306.518 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-18T12:28:26.519Z,1744979306.519 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-18T12:28:26.519Z,1744979306.519 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-18T12:28:26.519Z,1744979306.519 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-18T12:28:26.519Z,1744979306.519 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-18T12:28:26.519Z,1744979306.519 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-18T12:28:26.519Z,1744979306.519 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-18T12:28:26.537Z,1744979306.537 [keepstation:NeedComms] Running Loop=1
2025-04-18T12:28:26.537Z,1744979306.537 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-18T12:28:26.537Z,1744979306.537 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-18T12:28:26.537Z,1744979306.537 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-18T12:28:26.538Z,1744979306.538 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-18T12:28:26.538Z,1744979306.538 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-18T12:28:26.538Z,1744979306.538 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-18T12:28:26.539Z,1744979306.539 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-18T12:28:26.539Z,1744979306.539 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-18T12:28:26.544Z,1744979306.544 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-18T12:28:26.544Z,1744979306.544 [keepstation:NeedComms:A] Running Loop=1
2025-04-18T12:28:26.545Z,1744979306.545 [keepstation:NeedComms:A](INFO): last time_fix was:  1744972106.000000 second since 1970/01/01T00:00:00Z 
2025-04-18T12:28:26.546Z,1744979306.546 [keepstation:NeedComms:A] Stopped
2025-04-18T12:30:03.269Z,1744979403.269 [keepstation:NeedComms:C] Running Loop=1
2025-04-18T12:30:03.696Z,1744979403.696 [Radio_Surface](INFO): Powering up
2025-04-18T12:30:09.220Z,1744979409.220 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-18T12:30:10.186Z,1744979410.186 [DVL_micro](ERROR): Failed to parse:
:SA,+01.94,-03.49,187.2
2025-04-18T12:30:39.340Z,1744979439.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:30:53.406Z,1744979453.406 [NAL9602](INFO): SBD MO Status=0, MOMSN=19937, MT Status=0, MTMSN=0
2025-04-18T12:30:53.406Z,1744979453.406 [NAL9602](INFO): No messages in MT queue
2025-04-18T12:30:54.631Z,1744979454.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123055.00,A,3646.32914,N,12151.03469,W,0.330,262.66,180425,,,A*74
2025-04-18T12:30:54.635Z,1744979454.635 [NAL9602](INFO): GPS fix at 20250418T123055: (36.772152, -121.850578)
2025-04-18T12:30:54.676Z,1744979454.676 [UniversalFixResidualReporter](INFO): Fix residual: 2.1 %DT, over the last 1823.3 m. Residual distance 37.6 m at bearing 44.8 degrees. Fix at (36.7722, -121.8506) with 147.0 m made good.
2025-04-18T12:30:54.689Z,1744979454.689 [keepstation:NeedComms:C] Stopped
2025-04-18T12:30:54.689Z,1744979454.689 [keepstation:NeedComms:D] Running Loop=1
2025-04-18T12:31:10.464Z,1744979470.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:31:12.918Z,1744979472.918 [NAL9602](INFO): SBD MO Status=1, MOMSN=19938, MT Status=0, MTMSN=0
2025-04-18T12:31:12.980Z,1744979472.980 [NAL9602](INFO): Sent 97 bytes from file Logs/20250418T051319/Courier0021.lzma
2025-04-18T12:31:12.980Z,1744979472.980 [NAL9602](INFO): Packets left to send: 0
2025-04-18T12:31:22.448Z,1744979482.448 [NAL9602](INFO): SBD MO Status=1, MOMSN=19939, MT Status=0, MTMSN=0
2025-04-18T12:31:22.504Z,1744979482.504 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0022.lzma
2025-04-18T12:31:22.504Z,1744979482.504 [NAL9602](INFO): Packets left to send: 2
2025-04-18T12:31:33.560Z,1744979493.560 [NAL9602](INFO): SBD MO Status=1, MOMSN=19940, MT Status=0, MTMSN=0
2025-04-18T12:31:33.616Z,1744979493.616 [NAL9602](INFO): Sent 332 bytes from file Logs/20250418T051319/Express0022.lzma
2025-04-18T12:31:33.616Z,1744979493.616 [NAL9602](INFO): Packets left to send: 1
2025-04-18T12:31:41.588Z,1744979501.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:31:46.287Z,1744979506.287 [NAL9602](INFO): SBD MO Status=1, MOMSN=19941, MT Status=0, MTMSN=0
2025-04-18T12:31:46.336Z,1744979506.336 [NAL9602](INFO): Sent 162 bytes from file Logs/20250418T051319/Express0022.lzma
2025-04-18T12:31:46.336Z,1744979506.336 [NAL9602](INFO): Packets left to send: 0
2025-04-18T12:31:55.624Z,1744979515.624 [NAL9602](INFO): SBD MO Status=0, MOMSN=19942, MT Status=0, MTMSN=0
2025-04-18T12:31:55.703Z,1744979515.703 [keepstation:NeedComms:D] Stopped
2025-04-18T12:31:55.703Z,1744979515.703 [keepstation:NeedComms:E] Running Loop=1
2025-04-18T12:31:58.053Z,1744979518.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,123158.00,A,3646.32885,N,12151.03877,W,0.700,339.03,180425,,,A*79
2025-04-18T12:31:58.057Z,1744979518.057 [NAL9602](INFO): GPS fix at 20250418T123158: (36.772148, -121.850646)
2025-04-18T12:31:58.078Z,1744979518.078 [keepstation:NeedComms:E] Stopped
2025-04-18T12:31:58.096Z,1744979518.096 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-18T12:31:58.096Z,1744979518.096 [keepstation:NeedComms] Stopped
2025-04-18T12:31:58.097Z,1744979518.097 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-18T12:31:58.097Z,1744979518.097 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-18T12:31:58.097Z,1744979518.097 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-18T12:31:58.492Z,1744979518.492 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-18T12:31:58.492Z,1744979518.492 [keepstation:SurfaceComms:B] Stopped
2025-04-18T12:31:58.492Z,1744979518.492 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-18T12:31:58.493Z,1744979518.493 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-18T12:31:58.493Z,1744979518.493 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-18T12:31:58.493Z,1744979518.493 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-18T12:31:58.876Z,1744979518.876 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-18T12:31:58.876Z,1744979518.876 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-18T12:31:58.876Z,1744979518.876 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-18T12:31:58.876Z,1744979518.876 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-18T12:31:58.876Z,1744979518.876 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-18T12:31:58.876Z,1744979518.876 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-18T12:31:59.307Z,1744979519.307 [keepstation:SurfaceComms:setStation] Stopped
2025-04-18T12:31:59.307Z,1744979519.307 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-18T12:31:59.308Z,1744979519.308 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-18T12:31:59.308Z,1744979519.308 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-18T12:31:59.308Z,1744979519.308 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-18T12:31:59.308Z,1744979519.308 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms:setSink] Stopped
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms] Stopped
2025-04-18T12:31:59.673Z,1744979519.673 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-18T12:32:00.096Z,1744979520.096 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-18T12:32:00.096Z,1744979520.096 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-18T12:32:00.096Z,1744979520.096 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-18T12:32:00.097Z,1744979520.097 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-18T12:32:00.097Z,1744979520.097 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-18T12:32:00.098Z,1744979520.098 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-18T12:32:00.099Z,1744979520.099 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-18T12:32:00.100Z,1744979520.100 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-18T12:32:00.100Z,1744979520.100 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-18T12:32:00.100Z,1744979520.100 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-18T12:32:00.100Z,1744979520.100 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-18T12:32:11.644Z,1744979531.644 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003242
2025-04-18T12:32:29.979Z,1744979549.979 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-18T12:33:42.976Z,1744979622.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:34:06.975Z,1744979646.975 [Radio_Surface](INFO): Powering down
2025-04-18T12:34:14.108Z,1744979654.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:34:15.112Z,1744979655.112 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-18T12:35:27.327Z,1744979727.327 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-18T12:35:27.327Z,1744979727.327 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-18T12:35:27.328Z,1744979727.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-18T12:35:27.328Z,1744979727.328 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-18T12:35:27.740Z,1744979727.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-18T12:35:27.740Z,1744979727.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-18T12:35:27.740Z,1744979727.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-18T12:35:27.740Z,1744979727.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-18T12:35:27.740Z,1744979727.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T12:35:27.740Z,1744979727.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-18T12:35:28.138Z,1744979728.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-18T12:35:28.138Z,1744979728.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T12:35:28.138Z,1744979728.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-18T12:35:28.139Z,1744979728.139 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-18T12:35:28.139Z,1744979728.139 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T12:35:28.139Z,1744979728.139 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-18T12:35:28.139Z,1744979728.139 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-18T12:35:28.140Z,1744979728.140 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-18T12:35:28.140Z,1744979728.140 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-18T12:35:28.140Z,1744979728.140 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-18T12:35:28.140Z,1744979728.140 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-18T12:35:28.140Z,1744979728.140 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-18T12:35:28.550Z,1744979728.550 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-18T12:35:28.550Z,1744979728.550 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-18T12:35:28.550Z,1744979728.550 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-18T12:35:28.550Z,1744979728.550 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-18T12:35:28.550Z,1744979728.550 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-18T12:35:28.550Z,1744979728.550 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-18T12:35:28.551Z,1744979728.551 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-18T12:35:51.512Z,1744979751.512 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.746704
2025-04-18T12:38:20.739Z,1744979900.739 [Radio_Surface](INFO): Powering up
2025-04-18T12:38:26.140Z,1744979906.140 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-18T12:38:56.260Z,1744979936.260 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:39:27.392Z,1744979967.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:39:58.544Z,1744979998.544 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:40:29.705Z,1744980029.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:40:33.251Z,1744980033.251 [Radio_Surface](INFO): Powering down
2025-04-18T12:41:00.828Z,1744980060.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-18T12:41:01.832Z,1744980061.832 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-18T12:51:10.681Z,1744980670.681 [DVL_micro](ERROR): Failed to parse:
:WI,+00016,+00042,+00003,+00000,A
2025-04-18T12:51:19.564Z,1744980679.564 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-18T12:51:19.566Z,1744980679.566 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-18T12:51:19.570Z,1744980679.570 [BPC1](INFO): Received data from all battery sticks.
2025-04-18T12:55:37.729Z,1744980937.729 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-18T12:58:51.956Z,1744981131.956 [ElevatorServo](FAULT): Overload Error
2025-04-18T12:58:51.956Z,1744981131.956 [ElevatorServo] Hardware Fault, FailCount= 1
2025-04-18T12:58:51.956Z,1744981131.956 [ElevatorServo](ERROR): Hardware Fault
2025-04-18T12:58:52.119Z,1744981132.119 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-04-18T12:58:52.337Z,1744981132.337 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-18T12:58:52.337Z,1744981132.337 [ElevatorServo](INFO): Powering down
2025-04-18T12:58:53.130Z,1744981133.130 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-18T12:58:53.246Z,1744981133.246 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-18T12:58:53.251Z,1744981133.251 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-18T12:58:53.251Z,1744981133.251 [ElevatorServo] No Fault, FailCount= 1
2025-04-18T12:59:56.977Z,1744981196.977 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2213,HPR45,EXT,TILT